I have a Synology DS918+ with latest 64 bit PMS and running HW transcoding and 4Gb RAM
Playing a 1080P 7Mbps HD file the stream is buffering. Logs say speed is 0.8
Device is using HW transcoder.
I bought the 918+ as it was supposed to be able to TX a HD file with HW transcoder.
Has anyone got any ideas on what might be happening.
Hi, thanks for the response
Logs attached
Time 00:27, August 8th
XML details below
Plex Media Server Logs_2018-08-08_00-28-58.zip (1.8 MB)XML infoXML.txt (2.4 KB)
Hi,
logs without verbose logging on.
I also tried another file with a 4Mbps bit rate and this is fine with a speed showing of about 3.2.
Looking at the PMS compatibility list it says it should run fine up to 10Mbps HD filePlex Media Server Logs_2018-08-08_10-01-06.zip (1.9 MB)
Time of playing 10:00 8-8-18
Andrew,
Thanks for the logs and XML.
The CPU can do the video. It can decode up to HEVC 4K inclusive.
What it will always struggle with are image-based subtitles.
Intel did not provide a means for us to render and merge. The CPU has to do the overlay creation and merge in software. Even an i7-class machine is hard pressed to do that
Text-based subtitles are sent as a 3rd stream unless the player settings mandate “Always burn subtitles”
Is the problem you’re experiencing only happening when image-based subtitles are involved?
<Stream id="42361" streamType="1" codec="h264" index="0" bitrate="6891" bitDepth="8" chromaLocation="left" chromaSubsampling="4:2:0" colorPrimaries="bt709" colorRange="tv" colorSpace="bt709" colorTrc="bt709" frameRate="25.000" hasScalingMatrix="0" height="1080" level="40" profile="main" refFrames="4" scanType="interlaced" streamIdentifier="2315" width="1920" displayTitle="Unknown (H.264 Main)"/>
<Stream id="42365" streamType="2" codec="mp2" index="4" channels="2" bitrate="160" language="nar" languageCode="nar" audioChannelLayout="stereo" samplingRate="48000" streamIdentifier="2316" displayTitle="nar (MP2 Stereo)"/>
<Stream id="42362" streamType="2" selected="1" codec="ac3" index="1" channels="2" bitrate="192" language="English" languageCode="eng" audioChannelLayout="stereo" samplingRate="48000" streamIdentifier="2317" displayTitle="English (AC3 Stereo)"/>
<Stream id="42364" streamType="3" codec="dvb_teletext" index="3" language="English" languageCode="eng" streamIdentifier="2318" displayTitle="English (DVB_TELETEXT)"/>
<Stream id="42363" streamType="3" codec="dvb_subtitle" index="2" language="English" languageCode="eng" streamIdentifier="2319" displayTitle="English (DVB_SUBTITLE)"/>
</Part>
Hi thanks for the response.
Yes this file has subtitles, but I have not actually selected them.
If I select them the transcoder speed drops to 0.5, rather than 0.8.
Andrew,
If I may request:
It works this way because PMS’s log buffer is 26 MB fixed size.
After changing the logging, please restart PMS and recreate it one more time. It will give me a fresh log file to start with.
Hi,
Thanks for your help again
New logs attached.
This stream is 8.1 Mbps and transcoder showing speed of 0.9 with buffering
File played around 00:46
Subtitles offPlex Media Server Logs_2018-08-09_00-46-41.zip (1.7 MB)
Thank you for them. I immediately can see what’s happening.
From the logs, the reason it’s not using hardware is because of the video itself.
Aug 09, 2018 00:41:34.915 [0x7fd831bff700] DEBUG - TPU: hardware transcoding: not using zero-copy because video is interlaced
Full transcoder decision and startup block follow:
Aug 09, 2018 00:41:34.915 [0x7fd831bff700] DEBUG - TPU: hardware transcoding: using hardware decode accelerator vaapi
Aug 09, 2018 00:41:34.915 [0x7fd831bff700] DEBUG - [Universal] Using local file path instead of URL: /volume1/Plex/FOXSAT/Coronation Street/Coronation Street_20180716_2125.ts
Aug 09, 2018 00:41:34.915 [0x7fd831bff700] DEBUG - TPU: hardware transcoding: zero-copy support present
Aug 09, 2018 00:41:34.915 [0x7fd831bff700] DEBUG - TPU: hardware transcoding: not using zero-copy because video is interlaced
Aug 09, 2018 00:41:34.916 [0x7fd831bff700] DEBUG - TPU: hardware transcoding: final decoder: vaapi, final encoder: vaapi
Aug 09, 2018 00:41:34.920 [0x7fd831bff700] DEBUG - Job running: EAE_ROOT='/volume1/Plex/tmp_transcoding/pms-0c1a5c5f-7ed7-4101-973e-75c9f46699c3/EasyAudioEncoder' FFMPEG_EXTERNAL_LIBS='/volume1/Plex/Library/Application\ Support/Plex\ Media\ Server/Codecs/e944d3a-1309-linux-ubuntu-x86_64/' XDG_CACHE_HOME='/volume1/Plex/Library/Application Support/Plex Media Server/Cache' XDG_DATA_HOME='/volume1/@appstore/Plex Media Server/Resources' X_PLEX_TOKEN='xxxxxxxxxxxxxxxxxxxx' '/volume1/@appstore/Plex Media Server/Plex Transcoder' '-codec:#0x90b' 'h264' '-hwaccel:#0x90b' 'vaapi' '-hwaccel_fallback_threshold:#0x90b' '10' '-i' '/volume1/Plex/FOXSAT/Coronation Street/Coronation Street_20180716_2125.ts' '-filter_complex' '[0:#0x90b]yadif[0];[0]scale=w=1920:h=1080[1];[1]format=pix_fmts=nv12[2];[2]hwupload[3]' '-map' '[3]' '-codec:0' 'h264_vaapi' '-b:0' '8829k' '-maxrate:0' '11773k' '-bufsize:0' '23546k' '-r:0' '25' '-force_key_frames:0' 'expr:gte(t,0+n_forced*1)' '-map' '0:#0x90d' '-metadata:s:1' 'language=eng' '-codec:1' 'copy' '-copypriorss:1' '0' '-segment_format' 'mpegts' '-f' 'ssegment' '-individual_header_trailer' '0' '-segment_time' '1' '-segment_start_number' '0' '-segment_copyts' '1' '-segment_time_delta' '0.0625' '-segment_list' 'http://127.0.0.1:32400/video/:/transcode/session/b19ef2b4086e513461f333aded461a57/2387eb5c-67f4-487a-91c2-b5993d8d1f52/seglist' '-segment_list_type' 'csv' '-segment_list_size' '2147483647' '-segment_list_separate_stream_times' '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/b19ef2b4086e513461f333aded461a57/2387eb5c-67f4-487a-91c2-b5993d8d1f52/progress'
Aug 09, 2018 00:41:34.922 [0x7fd831bff700] DEBUG - Jobs: Starting child process with pid 11996
Aug 09, 2018 00:41:34.931 [0x7fd8313ff700] DEBUG - Auth: authenticated user 1 as andrewberesford
I need to go find out about that. Your data shows: H.264 and Interlaced. It should be doing that in hardware. I know it dotes it for MPEG2
Andrew,
How much memory do you have in that unit? 2GB, 4GB, or 8GB?
Hi its a 4Gb unit.
Interesting the logs says it is not using HW as when I go to the status, now playing page via browser it does report hw
I also see this in the log
Aug 09, 2018 08:55:00.161 [0x7fd822fff700] DEBUG - TPU: hardware transcoding: enabled, but no hardware decode accelerator found
Andrew,
I need the information which precedes it.
You will always see “MDE” information (Media Decision Engine) where PMS decides what can be done and what it will do. It also prints out why it has made the decision.
Can you find and provide this also?
Hi Chuck,
It goes through its steps and then comes to a transcode decision.
But then lots of transcoder errors which ends with fallback to SW decoding
Aug 09, 2018 09:05:20.362 [0x7f5453bff700] ERROR - [Transcoder] [h264 @ 0x2026040] reference picture missing during reorder
Aug 09, 2018 09:05:20.363 [0x7f544cc47700] ERROR - [Transcoder] [h264 @ 0x2026040] Missing reference picture, default is 6
Aug 09, 2018 09:05:20.365 [0x7f544d7ff700] ERROR - [Transcoder] [h264 @ 0x1d5ba00] mmco: unref short failure
Aug 09, 2018 09:05:20.393 [0x7f544ad11700] ERROR - [Transcoder] [h264 @ 0x2020680] reference picture missing during reorder
Aug 09, 2018 09:05:20.394 [0x7f5453bff700] ERROR - [Transcoder] [h264 @ 0x2020680] Missing reference picture, default is 65398
Aug 09, 2018 09:05:20.398 [0x7f544d511700] ERROR - [Transcoder] [h264 @ 0x2026040] mmco: unref short failure
Aug 09, 2018 09:05:20.867 [0x7f544d7ff700] DEBUG - Transcoder: session b19ef2b4086e513461f333aded461a57 indicated fallback to software decoding
Aug 09, 2018 09:05:21.319 [0x7f544ad11700] DEBUG - Transcoder segment range: 407 - 407
Aug 09, 2018 09:05:21.345 [0x7f544cf35700] DEBUG - Finished waiting for 1 segment(s): 1
Aug 09, 2018 09:05:21.345 [0x7f544cf35700] DEBUG - HLS: Building an M3U8 for 2391 total seconds with 1 seconds/segment, target duration of 1.
Aug 09, 2018 09:05:21.357 [0x7f5454d11700] DEBUG - Completed: [192.168.0.208:48016] 200 GET /video/:/transcode/universal/session/b19ef2b4086e513461f333aded461a57/base/index.m3u8 (24 live) GZIP 2549ms 6366 bytes (pipelined: 2)
Ok… I wished to see that decision.
This tells me it is starting out and then the errors in the video file caused fallback.
b19ef2b4086e513461f333aded461a57 indicated fallback to software decoding
that is entirely a video source material problem
If you use handbrake, it will correct those encoding errors
This topic was automatically closed 90 days after the last reply. New replies are no longer allowed.
