Add TBS-453DX to the NAS compatibility list

mesqueeb · August 8, 2020, 12:27pm

Server Version#: 1.19.1

I have this QNAP NAS: TBS-453DX

And I bought it because of some recommendations that it was quite good for Plex.

However, that NAS is not in the official compatibility list:

But it does seem to work…?

My request: Can someone of PLEX add the TBS-453DX to the list?

ChuckPa · August 8, 2020, 5:42pm

@mesqueeb

I am the maintainer of the NAS Compatibility Guide.

I have not added any NAS models (QNAP, Synology, or any other vendors) which utilize the Intel J4xxx CPUs because, out of the box, they cannot perform hardware transcoding.

There was a released update to their driver (a few days ago). As soon as that is integrated into the NAS, I will add all J4xxx CPUs to the list.

If you wish to use it now, I can provide the workaround.

mesqueeb · August 8, 2020, 8:16pm

@ChuckPa
I’d be highly interested in this workaround you mention. Thank you very much.

(I’ve had many issues with transcoding, this might have been the reason)

ChuckPa · August 8, 2020, 9:17pm

If you install the current PlexPass version, you will have a “PlexData” share created.
(if it doesn’t, ignore the error and create one in Shared Folders).
Make sure you’ve assigned all the appropriate permissions to access it (it’s invisible otherwise)

Once setup, Install thee QNAP “Text Editor” app if you haven’t already done so.

Stop Plex
Open File Station
PlexData -> Plex Media Server
Right-Click Preferences.xml and “Open in Text Editor”
Go down to the last line and over to the right.
You will see preferences (Name=“Value” format).
Add another, separated by a space, before the closing /> XML marker.
Specifically add VaapiDriver="i965"
Save the file
Start PMS.

mesqueeb · August 9, 2020, 2:13am

@ChuckPa

I have 1 question about this feature and 2 about this workaround:

about the feature
is this feature something that will be built into Plex Media Server in the future?
or is it a feature that is already built into Plex Media Server but will only become usable with a future firmware update of QNAP? (if so, what should I look out for in QNAP’s release notes?)
about the workaround

I cannot see the PlexData folder. I also cannot create it. (how can I “ignore” the error?)
What is PlexPass? I’ve got a lifetime subscription and the Plex Media Server. But PlexPass refers to the lifetime subscription, and not a NAS application right?

Edit

I was able to find the folder via SSH, but “PlexData/Plex Media Server” does not seem to be a folder I can cd into. It’s also not a file I could open with vim. Your kind advice is much appreciated!

ChuckPa · August 10, 2020, 12:18am

PlexData feature
a. The feature is built into PMS now for QNAP.
b. I plan to keep it there unless the community wants me to remove it.
c. In this next update, you will be responsible for creating the PlexData share. I had to change to this method because of the unpredictability in QTS version. Once created however, PMS will use it.
d. When Plex starts (the plex.sh script is called by QPKG) is when I go make (or update) the symlink. I do that so, no matter if Plex starts or not, you have access to the PMS data which is normally not accessible in the GUI. This makes getting logs, making backups, adding / editing Preferences.xml manually, all possible.
The workaround, which will be the new default way of “enabling” the PlexData share is to create the share anywhere you want it. It will only hold one symlink unless you add more to it so place it anywhere you want.
Yes, and I apologize, I refer to PlexPass as a feature. It is a “feature flag” attached to your account. That flag is what gives you access to HW trancoding, or the Beta releases, and several other features in PMS.
The screenshot you show above of the PlexData share is showing me that PMS has at least attempted to start (the scripting is working). If you typed ls -la you will see where it points to. That full path is where Plex is installed on your system. It is probably of the form /share/CACHEDEV?_DATA/.qpkg/PlexMediaServer/Library/Plex Media Server , correct ?

mesqueeb · August 10, 2020, 2:20am

@ChuckPa Thank you so much for all your support. I’m looking forward to getting the HW transcoding to work. Because I’ve had many issues with it on my TBS so far.

I believe editing through SSH would be easiest, I don’t have experience with vim, but I think I wanna try editing the file with vim.

Edit

I overlooked your message, I was able to get in. I’m gonna try editing the file now.

Edit 2

I stopped PMS in app centre first.
then I edited the file in question with VIM via ssh.
now I’m re-starting PMS. I’ll keep you up to date.

Any hints to check if HW transcoding works properly?

mesqueeb · August 10, 2020, 3:21am

@ChuckPa

The workaround seems to not have worked.
If I am with iPhone on my local wifi, and I play a 4K video directly, it plays back fine. Once I choose Playback settings > Quality > Convert automatically (I assume it should try to transcode to something lower than 4K)

it just starts buffering for about 5 min and then continues to play, but then after 1 min, the screen freezes and only the audio continues to play.

When I look at the CPU usage, it’s minimal:

How can I make sure HW transcoding is working as expected?

Edit

I also double checked the “transcoder” settings:

make my CPU hurt
use hardware acceleration when available
use hardware-accelerated video encoding

ChuckPa · August 10, 2020, 5:34am

@mesqueeb

The account you use here does not indicate a PlexPass.
Is the account you use on the server Plex-Pass enabled?
If so, the workaround I’ve given you does work for Hardware Transcoding. (PlexPass required).

If there’s something else with the J4xxx CPUs then we will need to investigate it.

May I have the log files ? I will evaluate what’s happening.

I will say this:

Others report software transcoding success with the J4xxx CPU.
The workaround has been verified as effective for hardware transcoding in J4xxx cpus from Synology.
I do not know of any using the QNAP J4xxx systems.
This might be a player problem or something else. This is why I need the logs.

This is why I’ve not added any J4xxx CPUs to the NAS compatibility.
There are outstanding issues.

Everything has to be vetted. There have been other vendors products which didn’t pass.

After this unit is vetted and certified, i’ll add it but not until.
Let’s see if we can get to the bottom of this?

I will try to get some time on the lab system and see what I can resolve.

mesqueeb · August 10, 2020, 6:17am

@ChuckPa
It does say Plex Pass behind my username:

And on PMS I see the lifetime pass icon:

I can work with you on this issue to get to the bottom!

I tried looking at the logs, but it’s so long (even on non-verbose).

Is there any way I can clear the logs and then retry playing a 4K file with conversion on so I can just give you the minimum amount of data?

Do you have any advice how to best give you the logs?

ChuckPa · August 10, 2020, 7:12am

When I last replied, it didn’t for me. I apologize.
I did check your account and see your LifeTime is active as of earlier this year.
Again, I apologize.

To verify what was done for the bypass / workaround,

if your Preferences.xml file, which you edited in the QNAP text editor app via File Station, looks like this:

some_other_preference="value" VaapiDriver="i965" />

and

PMS has been restarted.

As one last test, please go to http://jell.yfish.us/

download any of the HEVC files

Create a section of type for these (Other/Home Videos).
Next, playback the file on a H.264-only or 1080p only device to force transcoding.

Your browser is a good test (Don’t use the iPhone app).
Using the browser app will produce a very specific result with an HEVC source.

When complete, please download the logfiles and attach the ZIP.

I need to see what is happening.

I know the logs seem long but there are very specific things to search for.
These files generate specific results which, because I use them as a control base, are easy to find (know what I’m looking for).

mesqueeb · August 10, 2020, 9:48am

@ChuckPa
I did everything you ask.
I added the VaapiDriver previously via ssh & vim, not via QNAP text editor, but it’s the same result.
I added it while PMS was stopped and restarted after. Here is the proof:

Next I uploaded & tried playing the biggest HEVC file I could find. It will try and convert and just buffer for 20 sec then stop playback without actually ever starting playback. I assume it’s crashing.

My CPU during these 20sec:

My logs after this event:

logs.txt (67.7 KB)

The logs are JSON, so feel free to change .txt to .json for better syntax highlighting.

Please let me know how I can help next.

newqie · August 10, 2020, 11:06am

I have same issue on TS-453D. I have tried adding code to preferences file, but still doesn’t seem to initiate hardware transcoding.

ChuckPa · August 10, 2020, 1:51pm

The TS-453DX, like any other QNAP , Synology, ASUSTOR, or any others with the J4xxx CPU all suffer the same status.

As for the above logs,

Please provide Server logs in the standard ZIP format.

This is not a client issue. This is a HARDWARE Compatibility issue

newqie · August 10, 2020, 3:51pm

Thanks Chuck PA,

Is this the log you need to see? Any assistance for a temporary work around will be much appreciated. I only have basic understanding of SSH.

Plex Media Server Logs_2020-08-10_16-40-01.zip (5.8 MB)

ChuckPa · August 10, 2020, 4:20pm

Thank you for the logs. Those are exactly what I was hoping for and need.

This is interesting but disturbing.

In the same Plex session:

The player disconnected. I can’t tell if that was deliberate or otherwise.
At that same instant, I see where 1 instance was playing, a second one started , and the termination of the 1st session overlapping.
At that same instant, another session was started. Notice the "TPU: statement about no hardware found.
The new session is then started without HW assist.

Aug 10, 2020 16:34:39.156 [0x7f25ddfc5700] DEBUG - Request: [127.0.0.1:51914 (Loopback)] GET /channels/all (19 live) GZIP Signed-in Token (newqie)
Aug 10, 2020 16:34:39.157 [0x7f2605bbd700] DEBUG - Completed: [127.0.0.1:51914] 200 GET /channels/all (19 live) GZIP 0ms 387 bytes
Aug 10, 2020 16:34:39.448 [0x7f25de2b3700] DEBUG - Transcoder segment range: 0 - 1 (1)
Aug 10, 2020 16:34:39.449 [0x7f25dd9e9700] DEBUG - Transcoder segment range: 0 - 2 (1)
Aug 10, 2020 16:34:39.536 [0x7f25ddcd7700] DEBUG - Returning segment 1 from session
Aug 10, 2020 16:34:39.536 [0x7f25ddcd7700] DEBUG - Content-Length of /share/CACHEDEV1_DATA/.qpkg/PlexMediaServer/Library/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-a934d7457e09ed7d-com-plexapp-android-db1d414e-63ab-4aa0-878a-f2eded69e033/media-00001.ts is 6603124 (of total: 6603124).
Aug 10, 2020 16:34:40.244 [0x7f25ddfc5700] DEBUG - Transcoder segment range: 0 - 2 (2)
Aug 10, 2020 16:34:40.245 [0x7f25ddcd7700] DEBUG - Transcoder segment range: 0 - 3 (2)
Aug 10, 2020 16:34:40.476 [0x7f2605bbd700] DEBUG - Failed to stream media, client probably disconnected after 1585560 bytes: 104 - Connection reset by peer
Aug 10, 2020 16:34:40.476 [0x7f26058cf700] DEBUG - Failed to stream media, client probably disconnected after 1589940 bytes: 104 - Connection reset by peer
Aug 10, 2020 16:34:40.476 [0x7f26058cf700] DEBUG - Completed: [192.168.2.142:38697] 200 GET /video/:/transcode/universal/session/a934d7457e09ed7d-com-plexapp-android/base/00001.ts (18 live) 1340ms 1589940 bytes (pipelined: 1)
Aug 10, 2020 16:34:40.476 [0x7f26058cf700] DEBUG - Removed transcode data consumer, active count 2 => 1
Aug 10, 2020 16:34:40.476 [0x7f2605bbd700] DEBUG - Completed: [192.168.2.142:38696] 200 GET /video/:/transcode/universal/session/a934d7457e09ed7d-com-plexapp-android/base/00000.ts (17 live) 1362ms 1585560 bytes (pipelined: 1)
Aug 10, 2020 16:34:40.477 [0x7f2605bbd700] DEBUG - Removed transcode data consumer, active count 1 => 0
Aug 10, 2020 16:34:40.483 [0x7f25dd9e9700] DEBUG - Request: [192.168.2.142:38699 (Subnet)] GET /video/:/transcode/universal/session/a934d7457e09ed7d-com-plexapp-android/base/00018.ts (17 live) Signed-in
Aug 10, 2020 16:34:40.483 [0x7f25dd9e9700] DEBUG - Asked for segment 18 from session.
Aug 10, 2020 16:34:40.483 [0x7f25dd9e9700] DEBUG - We're in a seek: asked for 18, min/max available was 0/2
Aug 10, 2020 16:34:40.483 [0x7f25dd9e9700] DEBUG - Segment #18 WON.
Aug 10, 2020 16:34:40.483 [0x7f25dd9e9700] DEBUG - Killing job.
Aug 10, 2020 16:34:40.483 [0x7f25dd9e9700] DEBUG - Signalling job ID 21166 with 9
Aug 10, 2020 16:34:40.483 [0x7f25dd9e9700] DEBUG - Job was already killed, not killing again.
Aug 10, 2020 16:34:40.483 [0x7f25dd9e9700] DEBUG - Stopping transcode session a934d7457e09ed7d-com-plexapp-android
Aug 10, 2020 16:34:40.484 [0x7f25dd9e9700] DEBUG - Starting a transcode session a934d7457e09ed7d-com-plexapp-android at offset 180.0 (state=3)
Aug 10, 2020 16:34:40.485 [0x7f25dd9e9700] DEBUG - TPU: hardware transcoding: enabled, but no hardware decode accelerator found
Aug 10, 2020 16:34:40.488 [0x7f2606487700] DEBUG - Jobs: '/share/CACHEDEV1_DATA/.qpkg/PlexMediaServer/Plex Transcoder' exit code for process 21166 is -9 (signal: Killed)
Aug 10, 2020 16:34:40.497 [0x7f25dd9e9700] DEBUG - [Universal] Using local file path instead of URL: /share/external/.nd/0/0591c38b2-49c3-45ce-a5ab-d3beb9a3e019/Torrents/TV/The Grand Tour/The.Grand.Tour.S02.720p.WEB.H.264-Mixed/the.grand.tour.s02e01.720p.web.h264-strife.mkv
Aug 10, 2020 16:34:40.498 [0x7f25dd9e9700] DEBUG - TPU: hardware transcoding: final decoder: , final encoder: 
Aug 10, 2020 16:34:40.498 [0x7f25dd9e9700] DEBUG - Job running: EAE_ROOT='/share/CACHEDEV1_DATA/.qpkg/PlexMediaServer/Library/tmp/pms-142545d7-6424-464e-ab45-4c37b382a966/EasyAudioEncoder' FFMPEG_EXTERNAL_LIBS='/share/CACHEDEV1_DATA/.qpkg/PlexMediaServer/Library/Plex\ Media\ Server/Codecs/dd95667-2450-linux-x86_64/' XDG_CACHE_HOME='/share/CACHEDEV1_DATA/.qpkg/PlexMediaServer/Library/Plex Media Server/Cache' XDG_DATA_HOME='/share/CACHEDEV1_DATA/.qpkg/PlexMediaServer/Resources' X_PLEX_TOKEN='xxxxxxxxxxxxxxxxxxxx' '/share/CACHEDEV1_DATA/.qpkg/PlexMediaServer/Plex Transcoder' '-codec:0' 'h264' '-codec:1' 'eac3_eae' '-eae_prefix:1' 'a934d7457e09ed7d-com-plexapp-android_' '-ss' '180' '-noaccurate_seek' '-analyzeduration' '20000000' '-probesize' '20000000' '-i' '/share/external/.nd/0/0591c38b2-49c3-45ce-a5ab-d3beb9a3e019/Torrents/TV/The Grand Tour/The.Grand.Tour.S02.720p.WEB.H.264-Mixed/the.grand.tour.s02e01.720p.web.h264-strife.mkv' '-filter_complex' '[0:1] aresample=async=1:ocl='\''5.1'\'':osr=48000[0]' '-map' '0:0' '-metadata:s:0' 'language=eng' '-codec:0' 'copy' '-map' '[0]' '-metadata:s:1' 'language=eng' '-codec:1' 'aac' '-strict:1' 'experimental' '-aac_coder:1' 'fast' '-q:1' '0' '-segment_format' 'mpegts' '-f' 'ssegment' '-individual_header_trailer' '0' '-segment_time' '10' '-segment_start_number' '18' '-segment_copyts' '1' '-segment_time_delta' '0.0625' '-segment_list' 'http://127.0.0.1:32400/video/:/transcode/session/a934d7457e09ed7d-com-plexapp-android/ff79189f-af4e-45a8-a60b-24245aef7d9c/seglist' '-segment_list_type' 'csv' '-segment_list_size' '2147483647' '-segment_list_separate_stream_times' '1' '-segment_list_unfinished' '1' '-max_delay' '5000000' '-avoid_negative_ts' 'disabled' '-map_metadata' '-1' '-map_chapters' '-1' 'media-%05d.ts' '-start_at_zero' '-copyts' '-y' '-nostats' '-loglevel' 'quiet' '-loglevel_plex' 'error' '-progressurl' 'http://127.0.0.1:32400/video/:/transcode/session/a934d7457e09ed7d-com-plexapp-android/ff79189f-af4e-45a8-a60b-24245aef7d9c/progress'

Now this is where it gets weird. This second file

Detects the hardware
Uses it.

Aug 10, 2020 16:35:11.274 [0x7f26058cf700] DEBUG - Auth: authenticated user 1 as newqie
Aug 10, 2020 16:35:11.274 [0x7f25de2b3700] DEBUG - Request: [192.168.2.142:38617 (Subnet)] GET /video/:/transcode/universal/decision?audioBoost=100&autoAdjustQuality=1&directPlay=0&directStream=1&directStreamAudio=1&fastSeek=1&hasMDE=1&location=lan&maxVideoBitrate=200000&mediaBufferSize=209664&mediaIndex=0&partIndex=0&path=%2Flibrary%2Fmetadata%2F2578&protocol=*&session=a934d7457e09ed7d-com-plexapp-android&subtitleSize=100&videoBitrate=200000&videoQuality=100&videoResolution=3840x2160 (18 live) GZIP Signed-in Token (newqie)
Aug 10, 2020 16:35:11.275 [0x7f25de2b3700] DEBUG - Found session GUID of a934d7457e09ed7d-com-plexapp-android in session start.
Aug 10, 2020 16:35:11.275 [0x7f25de2b3700] DEBUG - TranscodeUniversalRequest: using augmented profile Android
Aug 10, 2020 16:35:11.276 [0x7f25de2b3700] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/2578?includeBandwidths=1&offset=-1&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=a934d7457e09ed7d-com-plexapp-android
Aug 10, 2020 16:35:11.276 [0x7f25de2b3700] DEBUG - Auth: authenticated user 1 as newqie
Aug 10, 2020 16:35:11.280 [0x7f25de2b3700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 10, 2020 16:35:11.280 [0x7f25de2b3700] DEBUG - Selecting best audio stream for part ID 2131 (autoselect: 1 language: en)
Aug 10, 2020 16:35:11.280 [0x7f25de2b3700] DEBUG - We're going to try to auto-select a subtitle.
Aug 10, 2020 16:35:11.280 [0x7f25de2b3700] DEBUG - Audio Stream: 42, Subtitle Stream: -1
Aug 10, 2020 16:35:11.280 [0x7f25de2b3700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 10, 2020 16:35:11.280 [0x7f25de2b3700] DEBUG - Selecting best audio stream for part ID 6849 (autoselect: 1 language: en)
Aug 10, 2020 16:35:11.280 [0x7f25de2b3700] DEBUG - We're going to try to auto-select a subtitle.
Aug 10, 2020 16:35:11.280 [0x7f25de2b3700] DEBUG - Audio Stream: 11679, Subtitle Stream: -1
Aug 10, 2020 16:35:11.281 [0x7f25de2b3700] DEBUG - Codecs: testing h264_qsv (encoder)
Aug 10, 2020 16:35:11.281 [0x7f25de2b3700] WARN - Failed to find encoder 'h264_qsv'
Aug 10, 2020 16:35:11.281 [0x7f25de2b3700] DEBUG - Codecs: testing h264_vaapi (encoder)
Aug 10, 2020 16:35:11.281 [0x7f25de2b3700] DEBUG - Codecs: hardware transcoding: testing API vaapi
Aug 10, 2020 16:35:11.402 [0x7f25de2b3700] DEBUG - [FFMPEG] - Format 0x32315659 -> yuv420p.
Aug 10, 2020 16:35:11.402 [0x7f25de2b3700] DEBUG - [FFMPEG] - Format 0x30323449 -> yuv420p.
Aug 10, 2020 16:35:11.402 [0x7f25de2b3700] DEBUG - [FFMPEG] - Format 0x3231564e -> nv12.
Aug 10, 2020 16:35:11.402 [0x7f25de2b3700] DEBUG - [FFMPEG] - Format 0x32595559 -> yuyv422.
Aug 10, 2020 16:35:11.402 [0x7f25de2b3700] DEBUG - [FFMPEG] - Format 0x59565955 -> uyvy422.
Aug 10, 2020 16:35:11.402 [0x7f25de2b3700] DEBUG - [FFMPEG] - Format 0x48323234 -> yuv422p.
Aug 10, 2020 16:35:11.402 [0x7f25de2b3700] DEBUG - [FFMPEG] - Format 0x58424752 -> rgb0.
Aug 10, 2020 16:35:11.402 [0x7f25de2b3700] DEBUG - [FFMPEG] - Format 0x58524742 -> bgr0.
Aug 10, 2020 16:35:11.402 [0x7f25de2b3700] DEBUG - [FFMPEG] - Format 0x30313050 -> p010le.
Aug 10, 2020 16:35:11.402 [0x7f25de2b3700] DEBUG - [FFMPEG] - Created surface 0x4000000.
Aug 10, 2020 16:35:11.402 [0x7f25de2b3700] DEBUG - [FFMPEG] - Direct mapping possible.
Aug 10, 2020 16:35:11.402 [0x7f25de2b3700] DEBUG - [FFMPEG] - Using nv12 as format of reconstructed frames.
Aug 10, 2020 16:35:11.402 [0x7f25de2b3700] DEBUG - [FFMPEG] - Created surface 0x4000001.
Aug 10, 2020 16:35:11.403 [0x7f25de2b3700] DEBUG - [FFMPEG] - Direct mapping possible.
Aug 10, 2020 16:35:11.403 [0x7f25de2b3700] DEBUG - [FFMPEG] - Created surface 0x4000002.
Aug 10, 2020 16:35:11.403 [0x7f25de2b3700] DEBUG - [FFMPEG] - Created surface 0x4000003.
Aug 10, 2020 16:35:11.403 [0x7f25de2b3700] DEBUG - [FFMPEG] - Created surface 0x4000004.
Aug 10, 2020 16:35:11.403 [0x7f25de2b3700] DEBUG - [FFMPEG] - Created surface 0x4000005.
Aug 10, 2020 16:35:11.403 [0x7f25de2b3700] DEBUG - [FFMPEG] - Created surface 0x4000006.
Aug 10, 2020 16:35:11.403 [0x7f25de2b3700] DEBUG - [FFMPEG] - Created surface 0x4000007.
Aug 10, 2020 16:35:11.421 [0x7f25de2b3700] DEBUG - [FFMPEG] - Using fixed QP = 20 / 20 / 24 for IDR- / P- / B-frames.
Aug 10, 2020 16:35:11.422 [0x7f25de2b3700] DEBUG - Codecs: hardware transcoding: testing API vaapi
Aug 10, 2020 16:35:11.423 [0x7f25de2b3700] DEBUG - [FFMPEG] - Format 0x32315659 -> yuv420p.
Aug 10, 2020 16:35:11.423 [0x7f25de2b3700] DEBUG - [FFMPEG] - Format 0x30323449 -> yuv420p.
Aug 10, 2020 16:35:11.423 [0x7f25de2b3700] DEBUG - [FFMPEG] - Format 0x3231564e -> nv12.
Aug 10, 2020 16:35:11.423 [0x7f25de2b3700] DEBUG - [FFMPEG] - Format 0x32595559 -> yuyv422.
Aug 10, 2020 16:35:11.423 [0x7f25de2b3700] DEBUG - [FFMPEG] - Format 0x59565955 -> uyvy422.
Aug 10, 2020 16:35:11.423 [0x7f25de2b3700] DEBUG - [FFMPEG] - Format 0x48323234 -> yuv422p.
Aug 10, 2020 16:35:11.423 [0x7f25de2b3700] DEBUG - [FFMPEG] - Format 0x58424752 -> rgb0.
Aug 10, 2020 16:35:11.423 [0x7f25de2b3700] DEBUG - [FFMPEG] - Format 0x58524742 -> bgr0.
Aug 10, 2020 16:35:11.423 [0x7f25de2b3700] DEBUG - [FFMPEG] - Format 0x30313050 -> p010le.
Aug 10, 2020 16:35:11.423 [0x7f25de2b3700] DEBUG - [FFMPEG] - Created surface 0x4000000.
Aug 10, 2020 16:35:11.423 [0x7f25de2b3700] DEBUG - [FFMPEG] - Direct mapping possible.
Aug 10, 2020 16:35:11.423 [0x7f25de2b3700] DEBUG - Scaled up video bitrate to 117229Kbps based on 4.500000x fudge factor.
Aug 10, 2020 16:35:11.424 [0x7f25de2b3700] DEBUG - MDE: Selected protocol hls; container: mpegts
Aug 10, 2020 16:35:11.424 [0x7f25de2b3700] DEBUG - MDE: analyzing media item 2130
Aug 10, 2020 16:35:11.424 [0x7f25de2b3700] DEBUG - MDE: E1 - Past, Present or Future: Direct Play is disabled
Aug 10, 2020 16:35:11.424 [0x7f25de2b3700] DEBUG - MDE: E1 - Past, Present or Future: media must be transcoded in order to use the hls protocol
Aug 10, 2020 16:35:11.424 [0x7f25de2b3700] DEBUG - MDE: E1 - Past, Present or Future: no direct play video profile exists for http/mkv/hevc
Aug 10, 2020 16:35:11.424 [0x7f25de2b3700] DEBUG - MDE: E1 - Past, Present or Future: no direct play video profile exists for http/mkv/hevc/eac3
Aug 10, 2020 16:35:11.424 [0x7f25de2b3700] DEBUG - MDE: E1 - Past, Present or Future: no direct play video profile exists for http/mkv/hevc/eac3
Aug 10, 2020 16:35:11.424 [0x7f25de2b3700] DEBUG - Past, Present or Future - video.profile limitation applies: main 10 != main [list]
Aug 10, 2020 16:35:11.424 [0x7f25de2b3700] DEBUG - Past, Present or Future - video.profile limitation applies: main 10 != main [list]
Aug 10, 2020 16:35:11.424 [0x7f25de2b3700] DEBUG - MDE: E1 - Past, Present or Future: no remuxable profile found, so video stream will be transcoded
Aug 10, 2020 16:35:11.424 [0x7f25de2b3700] DEBUG - MDE: Cannot direct stream video stream due to profile or setting limitations
Aug 10, 2020 16:35:11.424 [0x7f25de2b3700] DEBUG - Codecs: hardware transcoding: testing API vaapi
Aug 10, 2020 16:35:11.425 [0x7f25de2b3700] DEBUG - [FFMPEG] - Format 0x32315659 -> yuv420p.
Aug 10, 2020 16:35:11.425 [0x7f25de2b3700] DEBUG - [FFMPEG] - Format 0x30323449 -> yuv420p.
Aug 10, 2020 16:35:11.425 [0x7f25de2b3700] DEBUG - [FFMPEG] - Format 0x3231564e -> nv12.
Aug 10, 2020 16:35:11.425 [0x7f25de2b3700] DEBUG - [FFMPEG] - Format 0x32595559 -> yuyv422.
Aug 10, 2020 16:35:11.425 [0x7f25de2b3700] DEBUG - [FFMPEG] - Format 0x59565955 -> uyvy422.
Aug 10, 2020 16:35:11.425 [0x7f25de2b3700] DEBUG - [FFMPEG] - Format 0x48323234 -> yuv422p.
Aug 10, 2020 16:35:11.425 [0x7f25de2b3700] DEBUG - [FFMPEG] - Format 0x58424752 -> rgb0.
Aug 10, 2020 16:35:11.425 [0x7f25de2b3700] DEBUG - [FFMPEG] - Format 0x58524742 -> bgr0.
Aug 10, 2020 16:35:11.425 [0x7f25de2b3700] DEBUG - [FFMPEG] - Format 0x30313050 -> p010le.
Aug 10, 2020 16:35:11.425 [0x7f25de2b3700] DEBUG - [FFMPEG] - Created surface 0x4000000.
Aug 10, 2020 16:35:11.425 [0x7f25de2b3700] DEBUG - [FFMPEG] - Direct mapping possible.
Aug 10, 2020 16:35:11.426 [0x7f25de2b3700] DEBUG - Scaled up video bitrate to 117229Kbps based on 4.500000x fudge factor.
Aug 10, 2020 16:35:11.426 [0x7f25de2b3700] DEBUG - MDE: Cannot direct stream audio stream due to codec eac3 when profile only allows aac
Aug 10, 2020 16:35:11.427 [0x7f25de2b3700] DEBUG - MDE: The Grand Tour (2016) - S2 E1 - Past, Present or Future: selected media 0 / 2130
Aug 10, 2020 16:35:11.428 [0x7f25de2b3700] DEBUG - Streaming Resource: Adding session 0x7f25b42421d0:a934d7457e09ed7d-com-plexapp-android which is using transcoder slot.  Used slots is now 1
Aug 10, 2020 16:35:11.428 [0x7f25de2b3700] DEBUG - Streaming Resource: Added session 0x7f25b42421d0:a934d7457e09ed7d-com-plexapp-android
Aug 10, 2020 16:35:11.428 [0x7f25de2b3700] DEBUG - Streaming Resource: Reached Decision id=2578 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=2130 part=(id=2131 decision=transcode container=mpegts protocol=hls streams=(Video=(id=41 decision=transcode bitrate=117229 encoder=h264_vaapi width=3840 height=2160) Audio=(id=42 decision=transcode bitrate=774 encoder=aac channels=6 rate=48000))))
Aug 10, 2020 16:35:11.429 [0x7f26058cf700] DEBUG - Completed: [192.168.2.142:38617] 200 GET /video/:/transcode/universal/decision?audioBoost=100&autoAdjustQuality=1&directPlay=0&directStream=1&directStreamAudio=1&fastSeek=1&hasMDE=1&location=lan&maxVideoBitrate=200000&mediaBufferSize=209664&mediaIndex=0&partIndex=0&path=%2Flibrary%2Fmetadata%2F2578&protocol=*&session=a934d7457e09ed7d-com-plexapp-android&subtitleSize=100&videoBitrate=200000&videoQuality=100&videoResolution=3840x2160 (18 live) GZIP 155ms 1605 bytes (pipelined: 21)
Aug 10, 2020 16:35:11.564 [0x7f2605bbd700] DEBUG - Auth: authenticated user 1 as newqie
Aug 10, 2020 16:35:11.564 [0x7f25be203700] DEBUG - Request: [192.168.2.142:38712 (Subnet)] GET /video/:/transcode/universal/start.m3u8?audioBoost=100&autoAdjustQuality=1&directPlay=0&directStream=1&directStreamAudio=1&fastSeek=1&hasMDE=1&location=lan&maxVideoBitrate=200000&mediaBufferSize=209664&mediaIndex=0&partIndex=0&path=%2Flibrary%2Fmetadata%2F2578&protocol=hls&session=a934d7457e09ed7d-com-plexapp-android&subtitleSize=100&videoBitrate=200000&videoQuality=100&videoResolution=3840x2160 (19 live) Signed-in Token (newqie)
Aug 10, 2020 16:35:11.565 [0x7f25be203700] DEBUG - Found session GUID of a934d7457e09ed7d-com-plexapp-android in session start.
Aug 10, 2020 16:35:11.565 [0x7f25be203700] DEBUG - TranscodeUniversalRequest: using augmented profile Android
Aug 10, 2020 16:35:11.565 [0x7f25be203700] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/2578?checkFiles=1&includeBandwidths=1&offset=-1&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=a934d7457e09ed7d-com-plexapp-android
Aug 10, 2020 16:35:11.566 [0x7f25be203700] DEBUG - Auth: authenticated user 1 as newqie
Aug 10, 2020 16:35:11.580 [0x7f25be203700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 10, 2020 16:35:11.580 [0x7f25be203700] DEBUG - Selecting best audio stream for part ID 2131 (autoselect: 1 language: en)
Aug 10, 2020 16:35:11.580 [0x7f25be203700] DEBUG - We're going to try to auto-select a subtitle.
Aug 10, 2020 16:35:11.580 [0x7f25be203700] DEBUG - Audio Stream: 42, Subtitle Stream: -1
Aug 10, 2020 16:35:11.580 [0x7f25be203700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 10, 2020 16:35:11.580 [0x7f25be203700] DEBUG - Selecting best audio stream for part ID 6849 (autoselect: 1 language: en)
Aug 10, 2020 16:35:11.580 [0x7f25be203700] DEBUG - We're going to try to auto-select a subtitle.
Aug 10, 2020 16:35:11.580 [0x7f25be203700] DEBUG - Audio Stream: 11679, Subtitle Stream: -1
Aug 10, 2020 16:35:11.581 [0x7f25be203700] DEBUG - Found session GUID of a934d7457e09ed7d-com-plexapp-android in session start.
Aug 10, 2020 16:35:11.581 [0x7f25be203700] DEBUG - Using session GUID a934d7457e09ed7d-com-plexapp-android for new transcode session.
Aug 10, 2020 16:35:11.581 [0x7f25be203700] DEBUG - Cleaning directory for session a934d7457e09ed7d-com-plexapp-android ()
Aug 10, 2020 16:35:11.582 [0x7f26058cf700] DEBUG - Completed: [192.168.2.142:38712] 206 GET /video/:/transcode/universal/start.m3u8?audioBoost=100&autoAdjustQuality=1&directPlay=0&directStream=1&directStreamAudio=1&fastSeek=1&hasMDE=1&location=lan&maxVideoBitrate=200000&mediaBufferSize=209664&mediaIndex=0&partIndex=0&path=%2Flibrary%2Fmetadata%2F2578&protocol=hls&session=a934d7457e09ed7d-com-plexapp-android&subtitleSize=100&videoBitrate=200000&videoQuality=100&videoResolution=3840x2160 (19 live) 17ms 394 bytes (range: bytes=0-) 
Aug 10, 2020 16:35:11.594 [0x7f25ddfc5700] DEBUG - Request: [192.168.2.142:38713 (Subnet)] GET /video/:/transcode/universal/session/a934d7457e09ed7d-com-plexapp-android/base/index.m3u8 (20 live) Signed-in
Aug 10, 2020 16:35:11.594 [0x7f25ddfc5700] DEBUG - Found session GUID of a934d7457e09ed7d-com-plexapp-android in session start.
Aug 10, 2020 16:35:11.594 [0x7f25ddfc5700] DEBUG - Starting a transcode session a934d7457e09ed7d-com-plexapp-android at offset -1.0 (state=3)
Aug 10, 2020 16:35:11.595 [0x7f25ddfc5700] DEBUG - TPU: hardware transcoding: using hardware decode accelerator vaapi
Aug 10, 2020 16:35:11.595 [0x7f25ddfc5700] DEBUG - [Universal] Using local file path instead of URL: /share/external/.nd/0/04b736e45-68a1-4741-92ca-7f8f5799b228/4k/The.Grand.Tour.2016.S02.2160p.HDR.AMZN.WEBRip.DD5.1.EN.FR.x265-GASMASK/The.Grand.Tour.2016.S02E01.2160p.HDR.AMZN.WEBRip.DD5.1.EN.FR.x265-GASMASK.mkv
Aug 10, 2020 16:35:11.595 [0x7f25ddfc5700] DEBUG - TPU: hardware transcoding: zero-copy support present
Aug 10, 2020 16:35:11.595 [0x7f25ddfc5700] DEBUG - TPU: hardware transcoding: using zero-copy transcoding
Aug 10, 2020 16:35:11.595 [0x7f25ddfc5700] DEBUG - TPU: hardware transcoding: final decoder: vaapi, final encoder: vaapi
Aug 10, 2020 16:35:11.596 [0x7f25ddfc5700] DEBUG - Job running: EAE_ROOT='/share/CACHEDEV1_DATA/.qpkg/PlexMediaServer/Library/tmp/pms-142545d7-6424-464e-ab45-4c37b382a966/EasyAudioEncoder' FFMPEG_EXTERNAL_LIBS='/share/CACHEDEV1_DATA/.qpkg/PlexMediaServer/Library/Plex\ Media\ Server/Codecs/dd95667-2450-linux-x86_64/' XDG_CACHE_HOME='/share/CACHEDEV1_DATA/.qpkg/PlexMediaServer/Library/Plex Media Server/Cache' XDG_DATA_HOME='/share/CACHEDEV1_DATA/.qpkg/PlexMediaServer/Resources' X_PLEX_TOKEN='xxxxxxxxxxxxxxxxxxxx' '/share/CACHEDEV1_DATA/.qpkg/PlexMediaServer/Plex Transcoder' '-codec:0' 'hevc' '-hwaccel:0' 'vaapi' '-hwaccel_fallback_threshold:0' '10' '-hwaccel_output_format:0' 'vaapi' '-codec:1' 'eac3_eae' '-eae_prefix:1' 'a934d7457e09ed7d-com-plexapp-android_' '-analyzeduration' '20000000' '-probesize' '20000000' '-i' '/share/external/.nd/0/04b736e45-68a1-4741-92ca-7f8f5799b228/4k/The.Grand.Tour.2016.S02.2160p.HDR.AMZN.WEBRip.DD5.1.EN.FR.x265-GASMASK/The.Grand.Tour.2016.S02E01.2160p.HDR.AMZN.WEBRip.DD5.1.EN.FR.x265-GASMASK.mkv' '-filter_complex' '[0:0]hwupload[0];[0]scale_vaapi=w=3840:h=2160:format=nv12[1];[1]hwupload[2]' '-filter_complex' '[0:1] aresample=async=1:ocl='\''5.1'\'':osr=48000[3]' '-map' '[2]' '-codec:0' 'h264_vaapi' '-b:0' '87921k' '-maxrate:0' '117229k' '-bufsize:0' '234458k' '-r:0' '25' '-force_key_frames:0' 'expr:gte(t,0+n_forced*3)' '-map' '[3]' '-metadata:s:1' 'language=eng' '-codec:1' 'aac' '-strict:1' 'experimental' '-aac_coder:1' 'fast' '-q:1' '0' '-segment_format' 'mpegts' '-f' 'ssegment' '-individual_header_trailer' '0' '-segment_time' '3' '-segment_start_number' '0' '-segment_copyts' '1' '-segment_time_delta' '0.0625' '-segment_list' 'http://127.0.0.1:32400/video/:/transcode/session/a934d7457e09ed7d-com-plexapp-android/c2e65892-97ec-49f8-bbd6-a2b61c78e864/seglist' '-segment_list_type' 'csv' '-segment_list_size' '2147483647' '-segment_list_separate_stream_times' '1' '-segment_list_unfinished' '1' '-max_delay' '5000000' '-avoid_negative_ts' 'disabled' '-map_metadata' '-1' '-map_chapters' '-1' 'media-%05d.ts' '-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/a934d7457e09ed7d-com-plexapp-android/c2e65892-97ec-49f8-bbd6-a2b61c78e864/progress'
Aug 10, 2020 16:35:11.596 [0x7f25ddfc5700] DEBUG - Jobs: Starting child process with pid 27920
Aug 10, 2020 16:35:11.611 [0x7f25dd9e9700] DEBUG - Request: [127.0.0.1:52038 (Loopback)] PUT /video/:/transcode/session/a934d7457e09ed7d-com-plexapp-android/c2e65892-97ec-49f8-bbd6-a2b61c78e864/progress?status=startup (21 live) Signed-in Token (newqie)
Aug 10, 2020 16:35:11.611 [0x7f26058cf700] DEBUG - Completed: [127.0.0.1:52038] 204 PUT /video/:/transcode/session/a934d7457e09ed7d-com-plexapp-android/c2e65892-97ec-49f8-bbd6-a2b61c78e864/progress?status=startup (21 live) 0ms 203 bytes (pipelined: 1) (range: bytes=0-)

This seems to indicate flip/flopping in and out of HW detection.
You screenshot above does confirm the bypass is intact (thank you).

I see where you were using MPEG2 (The Grand Tour) as the source.

Does the same occur with the different Jellyfish videos? (the status indicator will tell you if (hw) is active.

newqie · August 10, 2020, 7:17pm

Apologies, that was me flip flopping to test on different devices.

Jellyfish does appear to work, so perhaps your fix is working for me. It did buffer a little, and the CPU use only went to around 50%. When I transcoded to 10mbp it still buffered. Can you tell from the log what was causing the buffering?

Plex Media Server Logs_2020-08-10_20-14-16.zip (5.9 MB)

ChuckPa · August 10, 2020, 8:09pm

I think I can explain why you’re seeing the CPU load.

In a word: Scanner

Aug 10, 2020 20:06:30.093 [0x7f259f159700] DEBUG - Activity: updated activity c23cc7c0-2279-4d4b-a4c4-7b6e0de35ba4 - completed 54.285042% - Scanning TV
Aug 10, 2020 20:06:30.093 [0x7f26058cf700] DEBUG - Completed: [127.0.0.1:51110] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20Lego%2EMasters%2ES02E03%2E720p%2EHEVC%2Ex265-MeGusta (15 live) GZIP 0ms 166 bytes
Aug 10, 2020 20:06:30.094 [0x7f2605bbd700] DEBUG - Completed: [127.0.0.1:51108] 200 PUT /activities/c23cc7c0-2279-4d4b-a4c4-7b6e0de35ba4?percentComplete=%2B0%2E022914758&subtitle=Lego%2EMasters%2ES02E03%2E720p%2EHEVC%2Ex265-MeGusta (15 live) GZIP 2ms 166 bytes
Aug 10, 2020 20:06:30.141 [0x7f25df447700] DEBUG - Request: [127.0.0.1:51114 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20Lego%2EMasters%2ES02E02%2E720p%2EHEVC%2Ex265-MeGusta (15 live) GZIP Signed-in Token (newqie)
Aug 10, 2020 20:06:30.142 [0x7f26058cf700] DEBUG - Completed: [127.0.0.1:51114] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20Lego%2EMasters%2ES02E02%2E720p%2EHEVC%2Ex265-MeGusta (15 live) GZIP 0ms 166 bytes
Aug 10, 2020 20:06:30.233 [0x7f25bf0a9700] DEBUG - Request: [127.0.0.1:51118 (Loopback)] PUT /activities/c23cc7c0-2279-4d4b-a4c4-7b6e0de35ba4?percentComplete=%2B0%2E022914758&subtitle=Screens (15 live) GZIP Signed-in Token (newqie)
Aug 10, 2020 20:06:30.233 [0x7f25dd9e9700] DEBUG - Request: [127.0.0.1:51116 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20Lego%2EMasters%2ES02E02%2E720p%2EHEVC%2Ex265-MeGusta%2FScreens (15 live) GZIP Signed-in Token (newqie)
Aug 10, 2020 20:06:30.234 [0x7f25bf0a9700] DEBUG - Activity: updated activity c23cc7c0-2279-4d4b-a4c4-7b6e0de35ba4 - completed 54.307957% - Scanning TV
Aug 10, 2020 20:06:30.234 [0x7f26058cf700] DEBUG - Completed: [127.0.0.1:51116] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20Le

The scanner was running and getting metadata while you were playing.
On a quad core CPU, that’s two threads (50%) plus the little bit of extra CPU for file system overhead and remuxing for output from the transcoder.

I do not yet know what the root cause of the buffering is but it’s not the NAS CPU.
Getting metadata won’t clog up the network either.

I really do suspect network issues. Maybe they are the server and maybe they are the player. The logs show me that requests are backing up in the server meaning things are not being turned over fast enough.

Notice, in the log segment below, the (piplined: N)
See how quickly they are increasing?

Aug 10, 2020 20:09:16.862 [0x7f259f159700] DEBUG - Request: [127.0.0.1:59898 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20Glow (16 live) GZIP Signed-in Token (newqie)
Aug 10, 2020 20:09:16.863 [0x7f25bcda4700] DEBUG - Activity: updated activity c23cc7c0-2279-4d4b-a4c4-7b6e0de35ba4 - completed 87.737411% - Scanning TV
Aug 10, 2020 20:09:16.863 [0x7f2605bbd700] DEBUG - Completed: [127.0.0.1:59896] 200 PUT /activities/c23cc7c0-2279-4d4b-a4c4-7b6e0de35ba4?percentComplete=%2B0%2E095057033&subtitle=Glow (16 live) GZIP 1ms 166 bytes
Aug 10, 2020 20:09:16.865 [0x7f2605bbd700] DEBUG - Completed: [127.0.0.1:59898] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20Glow (16 live) GZIP 2ms 166 bytes
Aug 10, 2020 20:09:16.911 [0x7f25de2b3700] DEBUG - Request: [127.0.0.1:59902 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20Ghosts (16 live) GZIP Signed-in Token (newqie)
Aug 10, 2020 20:09:16.912 [0x7f26058cf700] DEBUG - Completed: [127.0.0.1:59902] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20Ghosts (16 live) GZIP 0ms 166 bytes
Aug 10, 2020 20:09:16.916 [0x7f26058cf700] DEBUG - Auth: authenticated user 1 as newqie
Aug 10, 2020 20:09:16.916 [0x7f25bf0a9700] DEBUG - Request: [192.168.2.10:44878 (Subnet)] GET /:/timeline?ratingKey=25938&key=%2Flibrary%2Fmetadata%2F25938&playbackTime=0&playQueueItemID=121&state=buffering&hasMDE=1&time=0&duration=30000 (15 live) GZIP Signed-in Token (newqie)
Aug 10, 2020 20:09:16.918 [0x7f25bf0a9700] DEBUG - Client [fsz510rjxvjqncdif6vyuntz] reporting timeline state buffering, progress of 0/30000ms for guid=, ratingKey=25938 url=, key=/library/metadata/25938, containerKey=, metadataId=25938, source=
Aug 10, 2020 20:09:16.929 [0x7f25dc855700] DEBUG - Request: [192.168.2.10:44879 (Subnet)] GET /video/:/transcode/universal/dash/ojbn8hx6kojkbj4ivk1cv8gf/0/1.m4s (14 live) GZIP Signed-in
Aug 10, 2020 20:09:16.929 [0x7f25dc855700] DEBUG - Asked for segment 1 from session.
Aug 10, 2020 20:09:16.947 [0x7f259e88f700] DEBUG - Request: [127.0.0.1:59906 (Loopback)] PUT /activities/c23cc7c0-2279-4d4b-a4c4-7b6e0de35ba4?percentComplete=%2B0%2E0475285165&subtitle=Ghosts%2ES01E04%2EHDTV%2Ex264-RiVER[ettv] (14 live) GZIP Signed-in Token (newqie)
Aug 10, 2020 20:09:16.947 [0x7f25dd9e9700] DEBUG - Request: [127.0.0.1:59904 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20Ghosts%2FGhosts%2ES01E04%2EHDTV%2Ex264-RiVER[ettv] (14 live) GZIP Signed-in Token (newqie)
Aug 10, 2020 20:09:16.947 [0x7f259e88f700] DEBUG - Activity: updated activity c23cc7c0-2279-4d4b-a4c4-7b6e0de35ba4 - completed 87.784943% - Scanning TV
Aug 10, 2020 20:09:16.947 [0x7f26058cf700] DEBUG - Completed: [127.0.0.1:59904] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20Ghosts%2FGhosts%2ES01E04%2EHDTV%2Ex264-RiVER[ettv] (14 live) GZIP 1ms 166 bytes
Aug 10, 2020 20:09:16.949 [0x7f26058cf700] DEBUG - Completed: [127.0.0.1:59906] 200 PUT /activities/c23cc7c0-2279-4d4b-a4c4-7b6e0de35ba4?percentComplete=%2B0%2E0475285165&subtitle=Ghosts%2ES01E04%2EHDTV%2Ex264-RiVER[ettv] (14 live) GZIP 2ms 166 bytes
Aug 10, 2020 20:09:16.981 [0x7f259ee6b700] DEBUG - Request: [127.0.0.1:59912 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20Ghosts%2FGhosts%2ES01E05%2EHDTV%2Ex264-RiVER[ettv] (15 live) GZIP Signed-in Token (newqie)
Aug 10, 2020 20:09:16.981 [0x7f25df447700] DEBUG - Request: [127.0.0.1:59910 (Loopback)] PUT /activities/c23cc7c0-2279-4d4b-a4c4-7b6e0de35ba4?percentComplete=%2B0%2E0475285165&subtitle=Ghosts%2ES01E05%2EHDTV%2Ex264-RiVER[ettv] (15 live) GZIP Signed-in Token (newqie)
Aug 10, 2020 20:09:16.982 [0x7f26058cf700] DEBUG - Completed: [127.0.0.1:59912] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20Ghosts%2FGhosts%2ES01E05%2EHDTV%2Ex264-RiVER[ettv] (15 live) GZIP 1ms 166 bytes
Aug 10, 2020 20:09:16.984 [0x7f25df447700] DEBUG - Activity: updated activity c23cc7c0-2279-4d4b-a4c4-7b6e0de35ba4 - completed 87.832474% - Scanning TV
Aug 10, 2020 20:09:16.984 [0x7f25bf0a9700] DEBUG - [Now] User is newqie (ID: 1)
Aug 10, 2020 20:09:16.984 [0x7f26058cf700] DEBUG - Completed: [127.0.0.1:59910] 200 PUT /activities/c23cc7c0-2279-4d4b-a4c4-7b6e0de35ba4?percentComplete=%2B0%2E0475285165&subtitle=Ghosts%2ES01E05%2EHDTV%2Ex264-RiVER[ettv] (15 live) GZIP 3ms 166 bytes
Aug 10, 2020 20:09:16.985 [0x7f25bf0a9700] DEBUG - [Now] Device is Tizen (TV UE65MU6400).
Aug 10, 2020 20:09:16.985 [0x7f25bf0a9700] DEBUG - [Now] Profile is Samsung Tizen
Aug 10, 2020 20:09:16.985 [0x7f25bf0a9700] DEBUG - [Now] Updated play state for /library/metadata/25938.
Aug 10, 2020 20:09:16.986 [0x7f25bf0a9700] DEBUG - Statistics: (c49s1lbweglul3didtbfqhbj) Reporting active playback in state 2 of type 1 (scrobble: 0) for account 1
Aug 10, 2020 20:09:17.014 [0x7f26058cf700] DEBUG - Completed: [192.168.2.10:44878] 200 GET /:/timeline?ratingKey=25938&key=%2Flibrary%2Fmetadata%2F25938&playbackTime=0&playQueueItemID=121&state=buffering&hasMDE=1&time=0&duration=30000 (15 live) GZIP 97ms 785 bytes (pipelined: 3)
Aug 10, 2020 20:09:17.033 [0x7f259f735700] DEBUG - Request: [127.0.0.1:59918 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20Gavin%20%26%20Stacey (16 live) GZIP Signed-in Token (newqie)
Aug 10, 2020 20:09:17.033 [0x7f25bcda4700] DEBUG - Request: [127.0.0.1:59920 (Loopback)] PUT /activities/c23cc7c0-2279-4d4b-a4c4-7b6e0de35ba4?percentComplete=%2B0%2E095057033&subtitle=Gavin%20%26%20Stacey (15 live) GZIP Signed-in Token (newqie)
Aug 10, 2020 20:09:17.034 [0x7f25bcda4700] DEBUG - Activity: updated activity c23cc7c0-2279-4d4b-a4c4-7b6e0de35ba4 - completed 87.927528% - Scanning TV
Aug 10, 2020 20:09:17.034 [0x7f26058cf700] DEBUG - Completed: [127.0.0.1:59918] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20Gavin%20%26%20Stacey (15 live) GZIP 1ms 166 bytes
Aug 10, 2020 20:09:17.034 [0x7f26058cf700] DEBUG - Completed: [127.0.0.1:59920] 200 PUT /activities/c23cc7c0-2279-4d4b-a4c4-7b6e0de35ba4?percentComplete=%2B0%2E095057033&subtitle=Gavin%20%26%20Stacey (15 live) GZIP 1ms 166 bytes
Aug 10, 2020 20:09:17.048 [0x7f2605bbd700] DEBUG - Auth: authenticated user 1 as newqie
Aug 10, 2020 20:09:17.049 [0x7f259f159700] DEBUG - Request: [192.168.2.103:50346 (Subnet)] GET /status/sessions (14 live) TLS GZIP Signed-in Token (newqie)
Aug 10, 2020 20:09:17.049 [0x7f259f159700] DEBUG - [Now] Adding 1 sessions.
Aug 10, 2020 20:09:17.050 [0x7f2605bbd700] DEBUG - Completed: [192.168.2.103:50346] 200 GET /status/sessions (14 live) TLS GZIP 1ms 1581 bytes (pipelined: 17)
Aug 10, 2020 20:09:17.075 [0x7f26058cf700] DEBUG - Auth: authenticated user 1 as newqie
Aug 10, 2020 20:09:17.076 [0x7f25de2b3700] DEBUG - Request: [192.168.2.103:50346 (Subnet)] GET /status/sessions (14 live) TLS GZIP Signed-in Token (newqie)
Aug 10, 2020 20:09:17.076 [0x7f25de2b3700] DEBUG - [Now] Adding 1 sessions.
Aug 10, 2020 20:09:17.077 [0x7f26058cf700] DEBUG - Completed: [192.168.2.103:50346] 200 GET /status/sessions (13 live) TLS GZIP 2ms 1581 bytes (pipelined: 18)

This should not be happening.

When’s the last speedtest run on both LAN (from your computer) AND from player simultaneously ?

How much is wired and how much is WiFi only?

mesqueeb · August 10, 2020, 10:25pm

@ChuckPa
Sorry, yesterday I was looking at the wrong logs I guess?

I found one more page in Plex settings that said “download logs”, I hope these are the ones you’re looking for:

Plex Media Server Logs_2020-08-11_07-20-08.zip (1.8 MB)

I would much appreciate your assessment of my issue.

ChuckPa · August 11, 2020, 12:51am

Again Great logs. Thanks.

Using the i965 bypass, HEVC HDR decode confirmed.

Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - Codecs: Testing with profile 'Main 10'
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - nal_unit_type: 32(VPS), nuh_layer_id: 0, temporal_id: 0
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - nal_unit_type: 33(SPS), nuh_layer_id: 0, temporal_id: 0
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - nal_unit_type: 34(PPS), nuh_layer_id: 0, temporal_id: 0
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - nal_unit_type: 20(IDR_N_LP), nuh_layer_id: 0, temporal_id: 0
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Decoding VPS
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Main 10 profile bitstream
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Decoding SPS
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Main 10 profile bitstream
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Decoding VUI
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Decoding PPS
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Format vaapi_vld chosen by get_format().
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Format vaapi_vld requires hwaccel initialisation.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Considering format 0x3231564e -> nv12.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Considering format 0x30313050 -> p010le.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Picked p010le (0x30313050) as best match for yuv420p10le.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Created surface 0x4000000.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Direct mapping disabled: derived image format 30313050 does not match expected format 32315659.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Created surface 0x4000001.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Created surface 0x4000002.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Created surface 0x4000003.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Created surface 0x4000004.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Created surface 0x4000005.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Created surface 0x4000006.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Created surface 0x4000007.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Created surface 0x4000008.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Created surface 0x4000009.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Created surface 0x400000a.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Created surface 0x400000b.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Created surface 0x400000c.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Created surface 0x400000d.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Created surface 0x400000e.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Created surface 0x400000f.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Created surface 0x4000010.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Created surface 0x4000011.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Created surface 0x4000012.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Created surface 0x4000013.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Considering format 0x3231564e -> nv12.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Considering format 0x30313050 -> p010le.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Picked p010le (0x30313050) as best match for yuv420p10le.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Decode context initialised: 0x1000000/0x2000000.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Param buffer (type 0, 604 bytes) is 0x8000000.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Slice 0 param buffer (264 bytes) is 0x8000001.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Slice 0 data buffer (47 bytes) is 0x8000002.
Aug 10, 2020 12:13:01.976 [0x7fe584e31700] DEBUG - [FFMPEG] - Decode to surface 0x4000013.
Aug 10, 2020 12:13:01.977 [0x7fe584e31700] DEBUG - Codecs: 10-bit HEVC test succeeded
Aug 10, 2020 12:13:01.977 [0x7fe584e31700] DEBUG - Scaled up video bitrate to 16560Kbps based on 4.500000x fudge factor.
Aug 10, 2020 12:13:01.977 [0x7fe584e31700] DEBUG - Scaled maximum bitrate for resolution reduction to 4140Kbps.
Aug 10, 2020 12:13:01.978 [0x7fe584e31700] DEBUG - Codecs: testing hevc_vaapi (encoder)
Aug 10, 2020 12:13:01.982 [0x7fe5a1beb700] DEBUG - Auth: authenticated user 1 as mesqueeb@gmail.com

Topic		Replies	Views
QNAP Hardware transcoding fro PLEX NAS & Devices server-qnap	241	7463	January 8, 2020
Problem with new Qnap / Synology NAS? NAS & Devices server-synology	36	3032	March 31, 2021
QNAP TVS-h1688x won't hw transcode with nvidia driver installed without gpu NAS & Devices server-qnap	20	203	April 5, 2022
Hardware transcoding on Qnap TVS-672XT NAS & Devices server-qnap	85	3105	January 5, 2021
Plex HW Transcoding 1.81 Beta4 (QNAP TVS-673) NAS & Devices server-qnap	50	2483	February 11, 2020

Add TBS-453DX to the NAS compatibility list

Edit

Edit

Edit 2

Edit

Related topics