Can't get Plex HW transcoding to work with Docker (Unraid 6.7.0)

switch_cz · June 19, 2019, 2:38pm

Server Version#: 1.16.0.1226
Player Version#: 3.100.1

Hi,

this is my first post here so here’s hoping I’m in the correct forum. I’ve been scratching my head for two days now trying to get HW transcoding to work with Plex running as a Docker container. I have an ASRock J4105 miniITX board (integrated J4105 quadcore Celeron with UHD600 iGPU), so QuickSync is supported.

I have of course modified my go & syslinux.cfg files to include support for the needed i915 drivers:

#!/bin/bash
#Setup drivers for hardware transcoding in Plex
modprobe i915
chown -R nobody:users /dev/dri
chmod -R 777 /dev/dri
#Start the Management Utility
/usr/local/sbin/emhttp &

label Unraid OS
  menu default
  kernel /bzimage
  append pci=realloc=off initrd=/bzroot i915.alpha_support=1

I am passing /dev/dri to the container as a device and the devices are visible inside the container:

root@532cd6092721:/# ls -la /dev/dri
total 0
drwxr-xr-x 2 root root        80 Jun 19 11:05 .
drwxr-xr-x 6 root root       360 Jun 19 11:05 ..
crw-rw---- 1 root video 226,   0 Jun 19 11:05 card0
crwxrwxrwx 1   99 users 226, 128 Jun 19 11:05 renderD128

So far all looks good but when I force trasncoding by lowering the stream quality I get no video. It just seems to be buffering endlessly, yet in the dashboard, I can see HW transcoding is ongoing:

plex

I have also checked the Plex logs quite extensively and it appears that the transcoder is working fine and is actually using HW acceleration as well.

Jun 19, 2019 15:38:32.060 [0x15102adf6700] DEBUG - [FFMPEG] - Created surface 0x4000000.
Jun 19, 2019 15:38:32.060 [0x15102adf6700] DEBUG - [FFMPEG] - Direct mapping possible.
Jun 19, 2019 15:38:32.075 [0x15102adf6700] DEBUG - Scaled up video bitrate to 12877Kbps based on 1.500000x fudge factor.
Jun 19, 2019 15:38:32.075 [0x15102adf6700] DEBUG - MDE: Selected protocol dash; container: mp4
Jun 19, 2019 15:38:32.075 [0x15102adf6700] DEBUG - MDE: analyzing media item 26
Jun 19, 2019 15:38:32.075 [0x15102adf6700] DEBUG - MDE: Captain Marvel (2019): Direct Play is disabled
Jun 19, 2019 15:38:32.075 [0x15102adf6700] DEBUG - MDE: Captain Marvel (2019): media must be transcoded in order to use the dash protocol
Jun 19, 2019 15:38:32.075 [0x15102adf6700] DEBUG - MDE: Captain Marvel (2019): no direct play video profile exists for http/mkv/h264
Jun 19, 2019 15:38:32.075 [0x15102adf6700] DEBUG - MDE: Captain Marvel (2019): no direct play video profile exists for http/mkv/h264/dca
Jun 19, 2019 15:38:32.076 [0x15102adf6700] DEBUG - Captain Marvel - video.bitrate limitation applies: 8585 > 4000
Jun 19, 2019 15:38:32.076 [0x15102adf6700] DEBUG - Captain Marvel - video.bitrate limitation applies: 8585 > 4000
Jun 19, 2019 15:38:32.076 [0x15102adf6700] DEBUG - MDE: Captain Marvel (2019): no remuxable profile found, so video stream will be transcoded
Jun 19, 2019 15:38:32.076 [0x15102adf6700] DEBUG - Captain Marvel - video.bitrate limitation applies: 8585 > 4000
Jun 19, 2019 15:38:32.076 [0x15102adf6700] DEBUG - MDE: Cannot direct stream video stream due to profile or setting limitations
Jun 19, 2019 15:38:32.076 [0x15102adf6700] DEBUG - Codecs: hardware transcoding: testing API vaapi
Jun 19, 2019 15:38:32.078 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x32315659 -> yuv420p.
Jun 19, 2019 15:38:32.078 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x30323449 -> yuv420p.
Jun 19, 2019 15:38:32.078 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x3231564e -> nv12.
Jun 19, 2019 15:38:32.078 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x32595559 -> yuyv422.
Jun 19, 2019 15:38:32.078 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x59565955 -> uyvy422.
Jun 19, 2019 15:38:32.078 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x48323234 -> yuv422p.
Jun 19, 2019 15:38:32.078 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x58424752 -> rgb0.
Jun 19, 2019 15:38:32.078 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x58524742 -> bgr0.
Jun 19, 2019 15:38:32.078 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x30313050 -> p010le.
Jun 19, 2019 15:38:32.078 [0x15102adf6700] DEBUG - [FFMPEG] - Created surface 0x4000000.
Jun 19, 2019 15:38:32.078 [0x15102adf6700] DEBUG - [FFMPEG] - Direct mapping possible.
Jun 19, 2019 15:38:32.090 [0x15102aff7700] DEBUG - Transcoder segment range: 0 - 36 (36)
Jun 19, 2019 15:38:32.091 [0x15102adf6700] DEBUG - Scaled up video bitrate to 12877Kbps based on 1.500000x fudge factor.
Jun 19, 2019 15:38:32.092 [0x15102adf6700] DEBUG - MDE: Cannot direct stream audio stream due to codec dca when profile only allows aac
Jun 19, 2019 15:38:32.092 [0x15102adf6700] DEBUG - MDE: Captain Marvel (2019): selected media 0 / 26
Jun 19, 2019 15:38:32.092 [0x15102adf6700] DEBUG - Streaming Resource: Calculated bandwidth of 4470kbps exceeds bandwidth limit. Changing decision parameters provided by client to fit bandwidth limit of 4000kbps
Jun 19, 2019 15:38:32.092 [0x15102adf6700] DEBUG - Streaming Resource: Determining preferred transcode encoders through transcode only decision.
Jun 19, 2019 15:38:32.093 [0x15102adf6700] DEBUG - Codecs: hardware transcoding: testing API vaapi
Jun 19, 2019 15:38:32.094 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x32315659 -> yuv420p.
Jun 19, 2019 15:38:32.094 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x30323449 -> yuv420p.
Jun 19, 2019 15:38:32.094 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x3231564e -> nv12.
Jun 19, 2019 15:38:32.094 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x32595559 -> yuyv422.
Jun 19, 2019 15:38:32.094 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x59565955 -> uyvy422.
Jun 19, 2019 15:38:32.094 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x48323234 -> yuv422p.
Jun 19, 2019 15:38:32.094 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x58424752 -> rgb0.
Jun 19, 2019 15:38:32.094 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x58524742 -> bgr0.
Jun 19, 2019 15:38:32.094 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x30313050 -> p010le.
Jun 19, 2019 15:38:32.094 [0x15102adf6700] DEBUG - [FFMPEG] - Created surface 0x4000000.
Jun 19, 2019 15:38:32.094 [0x15102adf6700] DEBUG - [FFMPEG] - Direct mapping possible.
Jun 19, 2019 15:38:32.108 [0x15102adf6700] DEBUG - Scaled up video bitrate to 12877Kbps based on 1.500000x fudge factor.
Jun 19, 2019 15:38:32.108 [0x15102adf6700] DEBUG - Codecs: hardware transcoding: testing API vaapi
Jun 19, 2019 15:38:32.110 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x32315659 -> yuv420p.
Jun 19, 2019 15:38:32.110 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x30323449 -> yuv420p.
Jun 19, 2019 15:38:32.110 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x3231564e -> nv12.
Jun 19, 2019 15:38:32.110 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x32595559 -> yuyv422.
Jun 19, 2019 15:38:32.110 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x59565955 -> uyvy422.
Jun 19, 2019 15:38:32.110 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x48323234 -> yuv422p.
Jun 19, 2019 15:38:32.110 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x58424752 -> rgb0.
Jun 19, 2019 15:38:32.110 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x58524742 -> bgr0.
Jun 19, 2019 15:38:32.110 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x30313050 -> p010le.
Jun 19, 2019 15:38:32.110 [0x15102adf6700] DEBUG - [FFMPEG] - Created surface 0x4000000.
Jun 19, 2019 15:38:32.110 [0x15102adf6700] DEBUG - [FFMPEG] - Direct mapping possible.
Jun 19, 2019 15:38:32.125 [0x15102adf6700] DEBUG - Scaled up video bitrate to 12877Kbps based on 1.500000x fudge factor.
Jun 19, 2019 15:38:32.125 [0x15102adf6700] DEBUG - Streaming Resource: Reducing playback quality for 3809kbps stream bitrate: video resolution to 1280x538, audio channels to 2, quality to 32
Jun 19, 2019 15:38:32.125 [0x15102adf6700] DEBUG - Codecs: hardware transcoding: testing API vaapi
Jun 19, 2019 15:38:32.127 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x32315659 -> yuv420p.
Jun 19, 2019 15:38:32.127 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x30323449 -> yuv420p.
Jun 19, 2019 15:38:32.127 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x3231564e -> nv12.
Jun 19, 2019 15:38:32.127 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x32595559 -> yuyv422.
Jun 19, 2019 15:38:32.127 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x59565955 -> uyvy422.
Jun 19, 2019 15:38:32.127 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x48323234 -> yuv422p.
Jun 19, 2019 15:38:32.127 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x58424752 -> rgb0.
Jun 19, 2019 15:38:32.127 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x58524742 -> bgr0.
Jun 19, 2019 15:38:32.127 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x30313050 -> p010le.
Jun 19, 2019 15:38:32.128 [0x15102adf6700] DEBUG - [FFMPEG] - Created surface 0x4000000.
Jun 19, 2019 15:38:32.128 [0x15102adf6700] DEBUG - [FFMPEG] - Direct mapping possible.
Jun 19, 2019 15:38:32.141 [0x15102adf6700] DEBUG - Scaled up video bitrate to 12877Kbps based on 1.500000x fudge factor.
Jun 19, 2019 15:38:32.141 [0x15102adf6700] DEBUG - Scaled maximum bitrate for resolution reduction to 5707Kbps.
Jun 19, 2019 15:38:32.142 [0x15102adf6700] DEBUG - MDE: Selected protocol dash; container: mp4
Jun 19, 2019 15:38:32.142 [0x15102adf6700] DEBUG - MDE: analyzing media item 26
Jun 19, 2019 15:38:32.142 [0x15102adf6700] DEBUG - MDE: Captain Marvel (2019): Direct Play is disabled
Jun 19, 2019 15:38:32.142 [0x15102adf6700] DEBUG - MDE: Captain Marvel (2019): media must be transcoded in order to use the dash protocol
Jun 19, 2019 15:38:32.142 [0x15102adf6700] DEBUG - MDE: Captain Marvel (2019): no direct play video profile exists for http/mkv/h264
Jun 19, 2019 15:38:32.142 [0x15102adf6700] DEBUG - MDE: Captain Marvel (2019): no direct play video profile exists for http/mkv/h264/dca
Jun 19, 2019 15:38:32.142 [0x15102adf6700] DEBUG - Captain Marvel - video.bitrate limitation applies: 8585 > 4000
Jun 19, 2019 15:38:32.142 [0x15102adf6700] DEBUG - Captain Marvel - audio.channels limitation applies: 6 > 2
Jun 19, 2019 15:38:32.142 [0x15102adf6700] DEBUG - Captain Marvel - video.bitrate limitation applies: 8585 > 4000
Jun 19, 2019 15:38:32.143 [0x15102adf6700] DEBUG - MDE: Captain Marvel (2019): no remuxable profile found, so video stream will be transcoded
Jun 19, 2019 15:38:32.143 [0x15102adf6700] DEBUG - Captain Marvel - video.bitrate limitation applies: 8585 > 4000
Jun 19, 2019 15:38:32.143 [0x15102adf6700] DEBUG - MDE: Cannot direct stream video stream due to profile or setting limitations
Jun 19, 2019 15:38:32.143 [0x15102adf6700] DEBUG - Codecs: hardware transcoding: testing API vaapi
Jun 19, 2019 15:38:32.144 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x32315659 -> yuv420p.
Jun 19, 2019 15:38:32.145 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x30323449 -> yuv420p.
Jun 19, 2019 15:38:32.145 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x3231564e -> nv12.
Jun 19, 2019 15:38:32.145 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x32595559 -> yuyv422.
Jun 19, 2019 15:38:32.145 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x59565955 -> uyvy422.
Jun 19, 2019 15:38:32.145 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x48323234 -> yuv422p.
Jun 19, 2019 15:38:32.145 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x58424752 -> rgb0.
Jun 19, 2019 15:38:32.145 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x58524742 -> bgr0.
Jun 19, 2019 15:38:32.145 [0x15102adf6700] DEBUG - [FFMPEG] - Format 0x30313050 -> p010le.
Jun 19, 2019 15:38:32.145 [0x15102adf6700] DEBUG - [FFMPEG] - Created surface 0x4000000.
Jun 19, 2019 15:38:32.145 [0x15102adf6700] DEBUG - [FFMPEG] - Direct mapping possible.
Jun 19, 2019 15:38:32.158 [0x15102adf6700] DEBUG - Scaled up video bitrate to 12877Kbps based on 1.500000x fudge factor.
Jun 19, 2019 15:38:32.158 [0x15102adf6700] DEBUG - Scaled maximum bitrate for resolution reduction to 5707Kbps.
Jun 19, 2019 15:38:32.158 [0x15102adf6700] DEBUG - Captain Marvel - audio.channels limitation applies: 6 > 2
Jun 19, 2019 15:38:32.158 [0x15102adf6700] DEBUG - MDE: Cannot direct stream audio stream due to profile or setting limitations
Jun 19, 2019 15:38:32.159 [0x15102adf6700] DEBUG - MDE: Captain Marvel (2019): selected media 0 / 26
Jun 19, 2019 15:38:32.159 [0x151098248700] DEBUG - Killing job.
Jun 19, 2019 15:38:32.159 [0x15102adf6700] DEBUG - Streaming Resource: Session 0x15104836b300:60q02hreuh9kpw5z26ejj5g0 changed from using 18299kbps to 3961kbps of WAN bandwidth.  Used bandwidth is now 3961kbps
Jun 19, 2019 15:38:32.159 [0x151098248700] DEBUG - Signalling job ID 576 with 9
Jun 19, 2019 15:38:32.159 [0x15102adf6700] DEBUG - Streaming Resource: Session 0x15104836b300:60q02hreuh9kpw5z26ejj5g0 changed transcode slot usage to used.  Used slots is now 1
Jun 19, 2019 15:38:32.159 [0x151098248700] DEBUG - Job was already killed, not killing again.
Jun 19, 2019 15:38:32.159 [0x151098248700] DEBUG - Stopping transcode session yj9y28e6er586ohximaprzfr
Jun 19, 2019 15:38:32.159 [0x15102adf6700] DEBUG - Streaming Resource: Reached Decision id=27 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=26 part=(id=26 decision=transcode container=mp4 protocol=dash streams=(Video=(id=939 decision=transcode bitrate=3623 encoder=h264_vaapi width=1278 height=538) Audio=(id=940 decision=transcode bitrate=148 encoder=aac channels=2 rate=48000) Subtitle=(id=181 decision=transcode bitrate=2147483647 encoder=ass languageCode=eng location=sidecar))))
Jun 19, 2019 15:38:32.163 [0x151098449700] DEBUG - Jobs: '/usr/lib/plexmediaserver/Plex Transcoder' exit code for process 576 is -9 (signal: Killed)
Jun 19, 2019 15:38:32.163 [0x1510906b0700] DEBUG - Notifying consumer that data is complete.
Jun 19, 2019 15:38:32.164 [0x151093fff700] DEBUG - Completed: [192.168.1.10:7804] 200 GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F27&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&maxVideoBitrate=4000&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=jk19rtdgc5dtot7fu88d8gsy&subtitles=auto&Accept-Language=en (18 live) TLS GZIP 168ms 4687 bytes (pipelined: 25)
Jun 19, 2019 15:38:32.165 [0x151098248700] DEBUG - Transcoder: Cleaning old transcode directories.
Jun 19, 2019 15:38:32.166 [0x151098248700] DEBUG - Transcoder: Cleaning old transcode directory: "/transcode/Transcode/Sessions/plex-transcode-yj9y28e6er586ohximaprzfr-853cc14c-c4f8-4a18-ae8b-77361d71ad98"
Jun 19, 2019 15:38:32.166 [0x151093fff700] DEBUG - Auth: authenticated user 1 as obfuscated
Jun 19, 2019 15:38:32.166 [0x151093dfe700] DEBUG - Auth: authenticated user 1 as obfuscated
Jun 19, 2019 15:38:32.167 [0x1510916b8700] DEBUG - Request: [192.168.1.10:7797 (WAN)] GET /status/sessions (16 live) TLS GZIP Signed-in Token (obfuscated)
Jun 19, 2019 15:38:32.167 [0x15102b9fc700] DEBUG - Request: [192.168.1.10:7802 (WAN)] GET /status/sessions (16 live) TLS GZIP Signed-in Token (obfuscated)
Jun 19, 2019 15:38:32.167 [0x1510916b8700] DEBUG - [Now] Adding 1 sessions.
Jun 19, 2019 15:38:32.168 [0x15102b9fc700] DEBUG - [Now] Adding 1 sessions.
Jun 19, 2019 15:38:32.169 [0x151093dfe700] DEBUG - Completed: [192.168.1.10:7797] 200 GET /status/sessions (16 live) TLS GZIP 2ms 5109 bytes (pipelined: 39)
Jun 19, 2019 15:38:32.171 [0x151093dfe700] DEBUG - Completed: [192.168.1.10:7802] 200 GET /status/sessions (16 live) TLS GZIP 4ms 5030 bytes (pipelined: 7)
Jun 19, 2019 15:38:32.213 [0x151093fff700] DEBUG - [TranscodeOutputStream] Timed out waiting for data
Jun 19, 2019 15:38:32.213 [0x151093fff700] DEBUG - Removed transcode data consumer, active count 1 => 0
Jun 19, 2019 15:38:32.213 [0x15102abf5700] DEBUG - [TranscodeOutputStream] Input processing thread exited after writing 754 bytes, m_closed=1, m_endOfFileReached=1, session->isStopped()=1
Jun 19, 2019 15:38:32.214 [0x15102bfff700] DEBUG - Cleaning directory for session yj9y28e6er586ohximaprzfr (/transcode/Transcode/Sessions/plex-transcode-yj9y28e6er586ohximaprzfr-853cc14c-c4f8-4a18-ae8b-77361d71ad98)
Jun 19, 2019 15:38:32.214 [0x151098248700] DEBUG - Whacked session yj9y28e6er586ohximaprzfr, 0 remaining.
Jun 19, 2019 15:38:32.217 [0x151093dfe700] DEBUG - Auth: authenticated user 1 as obfuscated
Jun 19, 2019 15:38:32.218 [0x15102bbfd700] DEBUG - Request: [192.168.1.10:7797 (WAN)] GET /video/:/transcode/universal/start.mpd?hasMDE=1&path=%2Flibrary%2Fmetadata%2F27&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&maxVideoBitrate=4000&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=jk19rtdgc5dtot7fu88d8gsy&subtitles=auto&Accept-Language=en (14 live) TLS GZIP Signed-in Token (obfuscated)
Jun 19, 2019 15:38:32.218 [0x15102bbfd700] DEBUG - Found session GUID of jk19rtdgc5dtot7fu88d8gsy in session start.
Jun 19, 2019 15:38:32.219 [0x15102bbfd700] DEBUG - TranscodeUniversalRequest: using augmented profile Web
Jun 19, 2019 15:38:32.219 [0x15102bbfd700] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/27?checkFiles=1&includeBandwidths=1&offset=-1&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=60q02hreuh9kpw5z26ejj5g0
Jun 19, 2019 15:38:32.219 [0x15102bbfd700] DEBUG - Auth: authenticated user 1 as obfuscated
Jun 19, 2019 15:38:32.224 [0x15102bbfd700] DEBUG - It took 0.000000 ms to retrieve 114 items.
Jun 19, 2019 15:38:32.245 [0x15102bbfd700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jun 19, 2019 15:38:32.245 [0x15102bbfd700] DEBUG - Selecting best audio stream for part ID 26 (autoselect: 1 language: en)
Jun 19, 2019 15:38:32.245 [0x15102bbfd700] DEBUG - We're going to try to auto-select a subtitle.
Jun 19, 2019 15:38:32.245 [0x15102bbfd700] DEBUG - Subtitles: Found a candidate subtitle language [eng] for a foreign film
Jun 19, 2019 15:38:32.245 [0x15102bbfd700] DEBUG - Audio Stream: 940, Subtitle Stream: 181
Jun 19, 2019 15:38:32.247 [0x15102bbfd700] DEBUG - Found session GUID of jk19rtdgc5dtot7fu88d8gsy in session start.
Jun 19, 2019 15:38:32.247 [0x15102bbfd700] DEBUG - Cleaning directory for session jk19rtdgc5dtot7fu88d8gsy ()
Jun 19, 2019 15:38:32.247 [0x15102bbfd700] DEBUG - Starting a transcode session jk19rtdgc5dtot7fu88d8gsy at offset -1.0 (state=3)
Jun 19, 2019 15:38:32.248 [0x15102bbfd700] DEBUG - TPU: hardware transcoding: using hardware decode accelerator vaapi
Jun 19, 2019 15:38:32.248 [0x15102bbfd700] DEBUG - [Universal] Using local file path instead of URL: /movies/Captain Marvel (2019)/Captain Marvel (2019) - Bluray-1080p.mkv
Jun 19, 2019 15:38:32.249 [0x15102bbfd700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/streams/181?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jun 19, 2019 15:38:32.249 [0x151093dfe700] DEBUG - Auth: authenticated user 1 as obfuscated
Jun 19, 2019 15:38:32.249 [0x15102b1f8700] DEBUG - Request: [127.0.0.1:34844 (Loopback)] GET /library/streams/181 (14 live) GZIP Signed-in Token (obfuscated)
Jun 19, 2019 15:38:32.252 [0x15102b1f8700] DEBUG - Content-Length of /movies/Captain Marvel (2019)/Captain Marvel (2019) - Bluray-1080p.en.srt is 91753.
Jun 19, 2019 15:38:32.253 [0x151093dfe700] DEBUG - Completed: [127.0.0.1:34844] 200 GET /library/streams/181 (14 live) GZIP 3ms 91753 bytes
Jun 19, 2019 15:38:32.253 [0x15102bbfd700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/streams/181?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jun 19, 2019 15:38:32.253 [0x15102bbfd700] DEBUG - Detected character set of UTF-8.
Jun 19, 2019 15:38:32.254 [0x15102bbfd700] DEBUG - Downloaded stream from [http://127.0.0.1:32400/library/streams/181?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (codec: srt) to temporary file [/transcode/Transcode/Sessions/plex-transcode-jk19rtdgc5dtot7fu88d8gsy-ef1b128f-4370-4808-a01d-07825b462435/temp-0.srt]
Jun 19, 2019 15:38:32.254 [0x15102bbfd700] DEBUG - TPU: hardware transcoding: zero-copy support present
Jun 19, 2019 15:38:32.254 [0x15102bbfd700] DEBUG - TPU: hardware transcoding: using zero-copy transcoding
Jun 19, 2019 15:38:32.254 [0x15102bbfd700] DEBUG - TPU: hardware transcoding: final decoder: vaapi, final encoder: vaapi
Jun 19, 2019 15:38:32.255 [0x15102bbfd700] DEBUG - Job running: EAE_ROOT='/tmp/pms-85736070-8352-49fb-956e-d7be9ba4a099/EasyAudioEncoder' FFMPEG_EXTERNAL_LIBS='/config/Library/Application\ Support/Plex\ Media\ Server/Codecs/21b5515-2321-linux-x86_64/' XDG_CACHE_HOME='/config/Library/Application Support/Plex Media Server/Cache' XDG_DATA_HOME='/usr/lib/plexmediaserver/Resources' X_PLEX_TOKEN='xxxxxxxxxxxxxxxxxxxx' '/usr/lib/plexmediaserver/Plex Transcoder' '-codec:0' 'h264' '-hwaccel:0' 'vaapi' '-hwaccel_fallback_threshold:0' '10' '-hwaccel_output_format:0' 'vaapi' '-codec:1' 'dca' '-analyzeduration' '20000000' '-probesize' '20000000' '-i' '/movies/Captain Marvel (2019)/Captain Marvel (2019) - Bluray-1080p.mkv' '-analyzeduration' '20000000' '-probesize' '20000000' '-i' '/transcode/Transcode/Sessions/plex-transcode-jk19rtdgc5dtot7fu88d8gsy-ef1b128f-4370-4808-a01d-07825b462435/temp-0.srt' '-filter_complex' '[0:0]hwupload[0];[0]scale_vaapi=w=1278:h=538:format=nv12[1];[1]hwupload[2]' '-filter_complex' '[0:1] aresample=async=1:ocl='\''stereo'\'':osr=48000[3]' '-map' '[2]' '-metadata:s:0' 'language=eng' '-codec:0' 'h264_vaapi' '-b:0' '2717k' '-maxrate:0' '3623k' '-bufsize:0' '7246k' '-r:0' '23.975999999999999' '-force_key_frames:0' 'expr:gte(t,0+n_forced*5)' '-map' '[3]' '-metadata:s:1' 'language=eng' '-codec:1' 'aac' '-b:1' '148k' '-f' 'dash' '-min_seg_duration' '5000000' '-skip_to_segment' '1' '-time_delta' '0.0625' '-manifest_name' 'http://127.0.0.1:32400/video/:/transcode/session/jk19rtdgc5dtot7fu88d8gsy/ef1b128f-4370-4808-a01d-07825b462435/manifest' '-avoid_negative_ts' 'disabled' '-map_metadata' '-1' '-map_chapters' '-1' 'dash' '-map' '1:s:0' '-metadata:s:0' 'language=eng' '-codec:0' 'ass' '-f' 'segment' '-segment_format' 'ass' '-segment_time' '1' '-segment_header_filename' 'sub-header' '-segment_start_number' '0' '-segment_list' 'http://127.0.0.1:32400/video/:/transcode/session/jk19rtdgc5dtot7fu88d8gsy/ef1b128f-4370-4808-a01d-07825b462435/seglist?stream=subtitles' '-segment_list_type' 'csv' '-segment_list_size' '2147483647' '-segment_list_separate_stream_times' '1' '-segment_format_options' 'ignore_readorder=1' 'sub-chunk-%05d' '-start_at_zero' '-copyts' '-vsync' 'cfr' '-y' '-vaapi_device' '/dev/dri/renderD128' '-nostats' '-loglevel' 'quiet' '-loglevel_plex' 'error' '-progressurl' 'http://127.0.0.1:32400/video/:/transcode/session/jk19rtdgc5dtot7fu88d8gsy/ef1b128f-4370-4808-a01d-07825b462435/progress'
Jun 19, 2019 15:38:32.256 [0x15102bbfd700] DEBUG - Jobs: Starting child process with pid 586
Jun 19, 2019 15:38:32.281 [0x15102adf6700] DEBUG - Request: [127.0.0.1:34846 (Loopback)] PUT /video/:/transcode/session/jk19rtdgc5dtot7fu88d8gsy/ef1b128f-4370-4808-a01d-07825b462435/progress?status=startup (15 live) Signed-in Token (obfuscated)
Jun 19, 2019 15:38:32.282 [0x151093dfe700] DEBUG - Completed: [127.0.0.1:34846] 204 PUT /video/:/transcode/session/jk19rtdgc5dtot7fu88d8gsy/ef1b128f-4370-4808-a01d-07825b462435/progress?status=startup (15 live) 0ms 203 bytes (pipelined: 1) (range: bytes=0-) 
Jun 19, 2019 15:38:32.282 [0x1510916b8700] DEBUG - Request: [127.0.0.1:34846 (Loopback)] PUT /video/:/transcode/session/jk19rtdgc5dtot7fu88d8gsy/ef1b128f-4370-4808-a01d-07825b462435/progress?status=opening (15 live) Signed-in Token (obfuscated)
Jun 19, 2019 15:38:32.282 [0x151093dfe700] DEBUG - Completed: [127.0.0.1:34846] 204 PUT /video/:/transcode/session/jk19rtdgc5dtot7fu88d8gsy/ef1b128f-4370-4808-a01d-07825b462435/progress?status=opening (15 live) 0ms 203 bytes (pipelined: 2) (range: bytes=0-) 
Jun 19, 2019 15:38:32.284 [0x15102aff7700] DEBUG - Request: [127.0.0.1:34846 (Loopback)] PUT /video/:/transcode/session/jk19rtdgc5dtot7fu88d8gsy/ef1b128f-4370-4808-a01d-07825b462435/progress?status=opened (15 live) Signed-in Token (obfuscated)
Jun 19, 2019 15:38:32.284 [0x151093fff700] DEBUG - Completed: [127.0.0.1:34846] 204 PUT /video/:/transcode/session/jk19rtdgc5dtot7fu88d8gsy/ef1b128f-4370-4808-a01d-07825b462435/progress?status=opened (15 live) 0ms 203 bytes (pipelined: 3) (range: bytes=0-) 
Jun 19, 2019 15:38:32.284 [0x15102b9fc700] DEBUG - Request: [127.0.0.1:34846 (Loopback)] PUT /video/:/transcode/session/jk19rtdgc5dtot7fu88d8gsy/ef1b128f-4370-4808-a01d-07825b462435/progress/stream?index=0&id=0&codec=h264&type=video (14 live) Signed-in Token (obfuscated)

Just an excerpt, full logs attached if anyone is interested in taking a look at them.

Any ideas as to why I am not getting any video even though the transcoder appears to be working fine will be much appreciated!
Plex Media Server Logs_2019-06-19_16-27-36.zip (2.1 MB)

switch_cz · June 20, 2019, 11:40am

Just did some more troubleshooting, this is the input of vainfo run inside the container:

root@7011518d444b:/# vainfo
error: XDG_RUNTIME_DIR not set in the environment.
error: can't connect to X server!
libva info: VA-API version 1.1.0
libva info: va_getDriverName() returns 0
libva info: Trying to open /usr/lib/x86_64-linux-gnu/dri/i965_drv_video.so
libva info: Found init function __vaDriverInit_1_1
libva info: va_openDriver() returns 0
vainfo: VA-API version: 1.1 (libva 2.1.0)
vainfo: Driver version: Intel i965 driver for Intel(R) Gemini Lake - 2.1.0
vainfo: Supported profile and entrypoints
      VAProfileMPEG2Simple            : VAEntrypointVLD
      VAProfileMPEG2Main              : VAEntrypointVLD
      VAProfileH264ConstrainedBaseline: VAEntrypointVLD
      VAProfileH264ConstrainedBaseline: VAEntrypointEncSlice
      VAProfileH264ConstrainedBaseline: VAEntrypointEncSliceLP
      VAProfileH264Main               : VAEntrypointVLD
      VAProfileH264Main               : VAEntrypointEncSlice
      VAProfileH264Main               : VAEntrypointEncSliceLP
      VAProfileH264High               : VAEntrypointVLD
      VAProfileH264High               : VAEntrypointEncSlice
      VAProfileH264High               : VAEntrypointEncSliceLP
      VAProfileH264MultiviewHigh      : VAEntrypointVLD
      VAProfileH264MultiviewHigh      : VAEntrypointEncSlice
      VAProfileH264StereoHigh         : VAEntrypointVLD
      VAProfileH264StereoHigh         : VAEntrypointEncSlice
      VAProfileVC1Simple              : VAEntrypointVLD
      VAProfileVC1Main                : VAEntrypointVLD
      VAProfileVC1Advanced            : VAEntrypointVLD
      VAProfileNone                   : VAEntrypointVideoProc
      VAProfileJPEGBaseline           : VAEntrypointVLD
      VAProfileJPEGBaseline           : VAEntrypointEncPicture
      VAProfileVP8Version0_3          : VAEntrypointVLD
      VAProfileVP8Version0_3          : VAEntrypointEncSlice
      VAProfileHEVCMain               : VAEntrypointVLD
      VAProfileHEVCMain               : VAEntrypointEncSlice
      VAProfileHEVCMain10             : VAEntrypointVLD
      VAProfileHEVCMain10             : VAEntrypointEncSlice
      VAProfileVP9Profile0            : VAEntrypointVLD
      VAProfileVP9Profile0            : VAEntrypointEncSlice
      VAProfileVP9Profile2            : VAEntrypointVLD

However, as mentioned above, even though all seems to indicate that the transcoder sees the iGPU and is trying to use it, looking at the iGPU utilization through intel-igpu-tools, the device is idle

EDIT: Just tried the official plexinc docker image instead of linuxserver’s and it behaves the same.

mcrommert · June 20, 2019, 1:54pm

switch_cz:

AEntrypointEncSlice
      VAProfileH264StereoHigh         : VAEntrypointVLD
      VAProfileH264StereoHigh         : VAEntrypointEncSlice
      VAProfileVC1Simple              : VAEntrypointVLD
      VAProfileVC1Main                : VAEntrypointVLD
      VAProfileVC1Advanced            : VAEntrypointVLD
      VAProfileNone                   : VAEntrypointVideoProc
      VAProfileJPEGBaseline           : VAEntrypointVLD
      VAProfileJPEGBaseline           : VAEntrypointEncPicture
      VAProfileVP8Version0_3          : VAEntrypointVLD
      VAProfileVP8Version0_3          : VAEntrypointEncSlice
      VAProfileHEVCMain               : VAEntrypointVLD
      VAProfileHEVCMain               : VAEntrypointEncSlice
      VAProfileHEVCMain10             : VAEntrypointVLD
      VAProfileHEVCMain10             : VAEntrypointEncSlice
      VAProfileVP9Profile0            : VAEntrypointVLD
      VAProfileVP9Profile0            : VAEntrypointEncSlice
      VAProfileVP9Profile2            : VAEntrypointVLD

However, as mentioned above, even though all seems to indicate that the transcoder sees the iGPU and is trying to use it, looking at the iGPU utilization

you will find more help here -

switch_cz · June 21, 2019, 6:42am

Uhm, unfortunately not. I’m positive that my config is OK (definitely does not differ from the guide in the thread you’ve linked). As stated above, it appears the transcoder is aware of the vaapi device and is trying to use it. I can see that in the logs and I can also see files/chunks being created in the /transcode directory. It’s just the playback won’t start and keeps buffering instead.

switch_cz · June 21, 2019, 12:30pm

Just downgraded to UnRaid 6.6.7 and HW transcoding works as expected.

mcrommert · June 21, 2019, 7:29pm

ah yes i did just see some talk about that in the linuxserver thread today

system · September 19, 2019, 7:29pm

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

Topic		Replies	Views
Plex hw transcoding is not working with iGPU (10710U) under Debian 11 Plex Media Server server-linux , server-docker	4	131	August 11, 2022
How to setup Nvidia HW acceleration in Ubuntu Docker? Desktops & Laptops server-docker	33	22557	September 22, 2019
Hardware transcoding not working in docker Plex Media Server server-linux , server-docker	37	749	April 6, 2023
Hardware Transcoding issue (quicksync) in docker NAS & Devices server-linux , server-synology	25	415	September 27, 2023
[BUG] Transcoding broken on Plex 1.29.1 (Unraid / docker)- I had to roll back to 1.29.0.6244 NAS & Devices server-linux , server-docker , server-unraid	10	686	April 3, 2023

Can't get Plex HW transcoding to work with Docker (Unraid 6.7.0)

Related topics