Hardware Transcoding w/ Intel Quicksync not working

Plex Media Server
1

Transcoding was working previously… I don’t know which version it stopped at… just noticed the CPU being pegged while watching a movie and started to investigate. Any help would be appreciated. I recently just upgraded to newest release to see if that would fix it. (it did not)

Server Version#: Version 1.20.0.3133
Player Version#: Web Version 4.39.1

CPU Info
processor : 0
vendor_id : GenuineIntel
cpu family : 6
model : 158
model name : Intel® Celeron® G4900 CPU @ 3.10GHz
stepping : 11
microcode : 0xd6
cpu MHz : 900.120
cache size : 2048 KB
physical id : 0
siblings : 2
core id : 0
cpu cores : 2
apicid : 0
initial apicid : 0
fpu : yes
fpu_exception : yes
cpuid level : 22
wp : yes
flags : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush dts acpi mmx fxsr sse sse2 ss ht tm pbe syscall nx pdpe1gb rdtscp lm constant_tsc art arch_perfmon pebs bts rep_good nopl xtopology nonstop_tsc cpuid aperfmperf pni pclmulqdq dtes64 monitor ds_cpl vmx est tm2 ssse3 sdbg cx16 xtpr pdcm pcid sse4_1 sse4_2 x2apic movbe popcnt tsc_deadline_timer aes xsave rdrand lahf_lm abm 3dnowprefetch cpuid_fault epb invpcid_single pti ssbd ibrs ibpb stibp tpr_shadow vnmi flexpriority ept vpid ept_ad fsgsbase tsc_adjust smep erms invpcid mpx rdseed smap clflushopt intel_pt xsaveopt xsavec xgetbv1 xsaves dtherm arat pln pts hwp hwp_notify hwp_act_window hwp_epp md_clear flush_l1d
vmx flags : vnmi preemption_timer invvpid ept_x_only ept_ad ept_1gb flexpriority tsc_offset vtpr mtf vapic ept vpid unrestricted_guest ple pml ept_mode_based_exec
bugs : cpu_meltdown spectre_v1 spectre_v2 spec_store_bypass l1tf mds swapgs itlb_multihit srbds
bogomips : 6199.99
clflush size : 64
cache_alignment : 64
address sizes : 39 bits physical, 48 bits virtual
power management:

Linux Version Info:
Fedora release 31 (Thirty One)

Logs: Attached
plexlogs.tar.gz (40.1 KB)

2

No Hardware GPU was detected.

Jul 31, 2020 13:20:35.245 [0x7fb504ff9700] DEBUG - Starting a transcode session m2edghghsc8itu145udfqjab at offset -1.0 (state=3)
Jul 31, 2020 13:20:35.247 [0x7fb504ff9700] DEBUG - [Universal] Using local file path instead of URL: /mnt/nfs/plex/Movies/My Big Fat Greek Wedding/My Big Fat Greek Wedding (2002).mkv
Jul 31, 2020 13:20:35.247 [0x7fb504ff9700] DEBUG - TPU: hardware transcoding: zero-copy support not present
Jul 31, 2020 13:20:35.248 [0x7fb504ff9700] DEBUG - Job running: EAE_ROOT='/tmp/pms-d24484e5-8611-4b69-8de9-
.
.
.

Is this native, container, or VM?

3

Other questions:

  1. udev installed?
  2. what does ls -la /dev/dri return?
  3. Any customizations (override.conf) ?
4

native (no vm or container)

5
  1. Package systemd-udev-243.8-1.fc31.x86_64 is already installed.

  2. ls -la /dev/dri

ls: cannot access ‘/dev/dri’: No such file or directory
3) no override.conf file (none applied)

6

There you are. Item #2.

The kernel doesn’t see the QSV ASIC.

Firmware update / BIOS setting change to disable it?

7

I will inspect…

I haven’t done anything in the BIOS / Firmware

8

it’s going to be trivial – unless the latest Fedora kernel botched the i915 driver

9

5.7.9-100.fc31.x86_64 Kernel Version

Also thank you for the prompt reply.

10

unfortunately, that doesn’t mean much. (5.4.0-42 here)
I am somewhat further back and now using Ubuntu (more stable development environment for me). I switched when Redhat was bought by IBM.

Do you have a previous kernel version to boot (you should have 2-3 ?)

11

[root@pms1 ~]# grubby --info=ALL
index=0
kernel="/boot/vmlinuz-5.7.9-100.fc31.x86_64"
args=“ro resume=/dev/mapper/fedora_pms1-swap rd.lvm.lv=fedora_pms1/root rd.lvm.lv=fedora_pms1/swap nomodeset rhgb quiet”
root="/dev/mapper/fedora_pms1-root"
initrd="/boot/initramfs-5.7.9-100.fc31.x86_64.img"
title=“Fedora (5.7.9-100.fc31.x86_64) 31 (Thirty One)”
id=“b5284d7304714674a939331c8051f07d-5.7.9-100.fc31.x86_64”
index=1
kernel="/boot/vmlinuz-5.7.8-100.fc31.x86_64"
args=“ro resume=/dev/mapper/fedora_pms1-swap rd.lvm.lv=fedora_pms1/root rd.lvm.lv=fedora_pms1/swap nomodeset rhgb quiet”
root="/dev/mapper/fedora_pms1-root"
initrd="/boot/initramfs-5.7.8-100.fc31.x86_64.img"
title=“Fedora (5.7.8-100.fc31.x86_64) 31 (Thirty One)”
id=“b5284d7304714674a939331c8051f07d-5.7.8-100.fc31.x86_64”
index=2
kernel="/boot/vmlinuz-5.6.13-200.fc31.x86_64"
args=“ro resume=/dev/mapper/fedora_pms1-swap rd.lvm.lv=fedora_pms1/root rd.lvm.lv=fedora_pms1/swap nomodeset rhgb quiet”
root="/dev/mapper/fedora_pms1-root"
initrd="/boot/initramfs-5.6.13-200.fc31.x86_64.img"
title=“Fedora (5.6.13-200.fc31.x86_64) 31 (Thirty One)”
id=“b5284d7304714674a939331c8051f07d-5.6.13-200.fc31.x86_64”
index=3
kernel="/boot/vmlinuz-0-rescue-b5284d7304714674a939331c8051f07d"
args=“ro resume=/dev/mapper/fedora_pms1-swap rd.lvm.lv=fedora_pms1/root rd.lvm.lv=fedora_pms1/swap nomodeset rhgb quiet”
root="/dev/mapper/fedora_pms1-root"
initrd="/boot/initramfs-0-rescue-b5284d7304714674a939331c8051f07d.img"
title=“Fedora (0-rescue-b5284d7304714674a939331c8051f07d) 31 (Thirty One)”
id=“b5284d7304714674a939331c8051f07d-0-rescue”

12

I was implying to look at your previous kernels, restart using one of them, then reinspect /dev/dri, because the kernel is what creates /dev/dri at boot time.

13

ahh… ok… just booted into the 5.7.8 kernel… i have a monitor plugged in with a graphic… but I still don’t see

[root@pms1 ~]# ls -la /dev/dri
ls: cannot access ‘/dev/dri’: No such file or directory

14

yep… all 3 kernels do the same thing…

maybe I need to reload the machine or switch to ubuntu…

thanks for your help…

15

Actually update…

so…

5.6.13-200.fc31.x86_64

This directory is still missing…
[root@pms1 Logs]# ls -la /dev/dri
ls: cannot access ‘/dev/dri’: No such file or directory
[root@pms1 Logs]#

but plex is using the hardware transcoding… same file 1% CPU usage
l

16

Log files please (ZIP) before they disappear

17

plexlogs-updated.tar.gz (156.6 KB)

18

In the log set attached, I find:

  1. Plex Media Server.log
Jul 31, 2020 14:58:14.377 [0x7fda1bfff700] DEBUG - [Universal] Using local file path instead of URL: /mnt/nfs/plex/Movies/My Big Fat Greek Wedding/My Big Fat Greek Wedding (2002).mkv
Jul 31, 2020 14:58:14.378 [0x7fda1bfff700] DEBUG - Job running: EAE_ROOT='/tmp/pms-bab915a6-9504-41f6-9f57-9f003a607e0b/EasyAudioEncoder' FFMPEG_EXTERNAL_LIBS='/var/lib/plexmediaserver/Library/Application\ Support/Plex\ Media\ Server/Codecs/5f603a2-3204-linux-x86_64/' XDG_CACHE_HOME='/var/lib/plexmediaserver/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' '-codec:1' 'dca' '-ss' '0' '-noaccurate_seek' '-analyzeduration' '20000000' '-probesize' '20000000' '-i' '/mnt/nfs/plex/Movies/My Big Fat Greek Wedding/My Big Fat Greek Wedding (2002).mkv' '-filter_complex' '[0:1] aresample=async=1:ocl='\''stereo'\'':rematrix_maxval=0.000000dB:osr=48000[0]' '-map' '0:0' '-metadata:s:0' 'language=eng' '-codec:0' 'copy' '-map' '[0]' '-metadata:s:1' 'language=eng' '-codec:1' 'aac' '-b:1' '256k' '-f' 'dash' '-seg_duration' '5' '-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/3g3u6gc99ckpp0w7vzegvq1r/d99540f7-4bb7-4a50-bc59-3025734e5225/manifest?X-Plex-Http-Pipeline=infinite' '-avoid_negative_ts' 'disabled' '-map_metadata' '-1' '-map_chapters' '-1' 'dash' '-start_at_zero' '-copyts' '-vsync' 'cfr' '-y' '-nostats' '-loglevel' 'quiet' '-loglevel_plex' 'error' '-progressurl' 'http://127.0.0.1:32400/video/:/transcode/session/3g3u6gc99ckpp0w7vzegvq1r/d99540f7-4bb7-4a50-bc59-3025734e5225/progress'
Jul 31, 2020 14:58:14.382 [0x7fda1bfff700] DEBUG - Jobs: Starting child process with pid 2739
  1. Plex Media Server.log
Jul 31, 2020 14:58:15.108 [0x7fda38d61700] DEBUG - Starting a transcode session 3g3u6gc99ckpp0w7vzegvq1r at offset 4415.0 (state=3)
Jul 31, 2020 14:58:15.115 [0x7fda3a564700] DEBUG - Auth: authenticated user 1 as dion.thanos
Jul 31, 2020 14:58:15.115 [0x7fd9f67fc700] DEBUG - Request: [192.168.111.50:62380 (Subnet)] GET /status/sessions (9 live) TLS GZIP Signed-in Token (dion.thanos)
Jul 31, 2020 14:58:15.115 [0x7fd9f67fc700] DEBUG - [Now] Adding 0 sessions.
Jul 31, 2020 14:58:15.115 [0x7fda3a564700] DEBUG - Completed: [192.168.111.50:62380] 200 GET /status/sessions (9 live) TLS GZIP 0ms 429 bytes (pipelined: 5)
Jul 31, 2020 14:58:15.116 [0x7fda38d61700] DEBUG - [Universal] Using local file path instead of URL: /mnt/nfs/plex/Movies/My Big Fat Greek Wedding/My Big Fat Greek Wedding (2002).mkv
Jul 31, 2020 14:58:15.117 [0x7fda38d61700] DEBUG - Job running: EAE_ROOT='/tmp/pms-bab915a6-9504-41f6-9f57-9f003a607e0b/EasyAudioEncoder' FFMPEG_EXTERNAL_LIBS='/var/lib/plexmediaserver/Library/Application\ Support/Plex\ Media\ Server/Codecs/5f603a2-3204-linux-x86_64/' XDG_CACHE_HOME='/var/lib/plexmediaserver/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' '-codec:1' 'dca' '-ss' '4415' '-noaccurate_seek' '-analyzeduration' '20000000' '-probesize' '20000000' '-i' '/mnt/nfs/plex/Movies/My Big Fat Greek Wedding/My Big Fat Greek Wedding (2002).mkv' '-filter_complex' '[0:1] aresample=async=1:ocl='\''stereo'\'':rematrix_maxval=0.000000dB:osr=48000[0]' '-map' '0:0' '-metadata:s:0' 'language=eng' '-codec:0' 'copy' '-map' '[0]' '-metadata:s:1' 'language=eng' '-codec:1' 'aac' '-b:1' '256k' '-f' 'dash' '-seg_duration' '5' '-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' '884' '-time_delta' '0.0625' '-manifest_name' 'http://127.0.0.1:32400/video/:/transcode/session/3g3u6gc99ckpp0w7vzegvq1r/4e967c90-4447-45b7-be04-b9bc6c36a353/manifest?X-Plex-Http-Pipeline=infinite' '-avoid_negative_ts' 'disabled' '-map_metadata' '-1' '-map_chapters' '-1' 'dash' '-start_at_zero' '-copyts' '-y' '-nostats' '-loglevel' 'quiet' '-loglevel_plex' 'error' '-progressurl' 'http://127.0.0.1:32400/video/:/transcode/session/3g3u6gc99ckpp0w7vzegvq1r/4e967c90-4447-45b7-be04-b9bc6c36a353/progress'
Jul 31, 2020 14:58:15.117 [0x7fda38d61700] DEBUG - Jobs: Starting child process with pid 2744
Jul 31, 2020 14:58:15.117 [0x7fda38d61700] DEBUG - Started session successfully: 3g3u6gc99ckpp0w7vzegvq1r

With logs ending abruptly (not flushed) at this point

deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (7 live) TLS GZIP 20000ms 5 bytes (pipelined: 101)
Jul 31, 2020 15:13:24.753 [0x7fda3ad65700] DEBUG - Auth: authenticated user 1 as dion.thanos
Jul 31, 2020 15:13:24.753 [0x7fda1affd700] DEBUG - Request: [192.168.111.50:60655 (Subnet)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (7 live) TLS GZIP Signed-in Token (dion.thanos)
Jul 31, 2020 15:13:24.753 [0x7fda1affd700] DEBUG - Content-Length is -1 (of total: -1).
Jul 31, 2020 15:13:28.620 [0x7fda3ad65700] DEBUG - Auth: authenticated user 1 as dion.thanos
Jul 31, 2020 15:13:28.620 [0x7fd9f67fc700] DEBUG - Request: [192.168.111.50:64256 (Subnet)] GET /:/timeline?ratingKey=5469&key=%2Flibrary%2Fmetadata%2F5469&playbackTime=689989&playQueueItemID=979&state=paused&hasMDE=1&context=hub.home.continue&time=5110000&duration=5696000 (7 live) TLS GZIP Signed-in Token (dion.thanos)
Jul 31, 2020 15:13:28.621 [0x7fd9f67fc700] DEBUG - Client [ztudeyuiharhrr6ukmiqe5ds] reporting timeline state paused, progress of 5110000/5696000ms for guid=, playbackTime=689989ms ratingKey=5469 url=, key=/library/metadata/5469, containerKey=, metadataId=5469, source=
Jul 31, 2020 15:13:28.623 [0x7fd9f67fc700] DEBUG - Play progress on 5469 'My Big Fat Greek Wedding' - got played 5110000 ms by account 1!
Jul 31, 2020 15:13:28.623 [0x7fd9f67fc700] DEBUG - [Now] User is dion.thanos (ID: 1)
Jul 31, 2020 15:13:28.623 [0x7fd9f67fc700] DEBUG - [Now] Device is Chrome (Chrome).
Jul 31, 2020 15:13:28.623 [0x7fd9f67fc700] DEBUG - [Now] Profile is Web
Jul 31, 2020 15:13:28.623 [0x7fd9f67fc700] DEBUG - [Now] Updated play state for /library/metadata/5469.
Jul 31, 2020 15:13:28.623 [0x7fd9f67fc700] DEBUG - Statistics: (5mjvnrtozy6bjjhispa1p6al) Reporting active playback in state 1 of type 1 (scrobble: 0) for account 1
Jul 31, 2020 15:13:28.625 [0x7fda3a564700] DEBUG - Completed: [192.168.111.50:64256] 200 GET /:/timeline?ratingKey=5469&key=%2Flibrary%2Fmetadata%2F5469&playbackTime=689989&playQueueItemID=979&state=paused&hasMDE=1&context=hub.home.continue&time=5110000&duration=5696000 (7 live) TLS GZIP 5ms 858 bytes (pipelined: 2)
Jul 31, 2020 15:13:28.658 [0x7fda3ad65700] DEBUG - Auth: authenticated user 1 as dion.thanos
Jul 31, 2020 15:13:28.658 [0x7fda2affd700] DEBUG - Request: [192.168.111.61:37446 (Subnet)] GET /status/sessions (8 live) TLS Signed-in Token (dion.thanos)
Jul 31, 2020 15:13:28.658 [0x7fda2affd700] DEBUG - [Now] Adding 1 sessions.
Jul 31, 2020 15:13:28.660 [0x7fda3a564700] DEBUG - Completed: [192.168.111.61:37446] 200 GET /status/sessions (8 live) TLS 1ms 7898 bytes (pipelined: 1)

Nowhere in this log, nor in the other logs do I find hardware transcoding (VAAPI)

19

no worries… I think I am going to rebuild the server…
I went through the BIOS… very limited…

We will see …
thanks for all your help…

20

Figured it out… it was in the Kernel… removed the nomodeset from the grub bootloader forcing basic graphic mode.

All set.

[root@pms1 Plex]# ls -alh /dev/dri
total 0
drwxr-xr-x. 3 root root 100 Jul 31 21:32 .
drwxr-xr-x. 20 root root 4.2K Jul 31 21:33 …
drwxr-xr-x. 2 root root 80 Jul 31 21:32 by-path
crw-rw----+ 1 root video 226, 0 Jul 31 21:32 card0
crw-rw-rw-. 1 root render 226, 128 Jul 31 21:32 renderD128
[root@pms1 Plex]#