Server Version#: 1.18.2.2058
Player Version#: Samsung (orca version) before tizen
I have downloaded lots of .mkv files, they all run fine with direct play and/or transcode
but one particular show (Titans) , thats one buffers like every 2 minutes or something
if i turn on verbose, i see actual speed = 0.0
all other same kind of mkv files all play fine, speed above 1.5 / 2.0
my hardware => virtualized synology on a HP Gen 8 Xeon server, i reserved 4 cpus for this synogy, also installed 64 bit package
what could be the issue here?
this is also the only season of .mkv files, that if i start the show with direct play, it gives me a warning that the type is not supported
IMHO, as the Syno dev, Running Plex in a XPE VM buys you absolutely nothing over the native windows server installation. In fact, you lose due to VM overhead if Type 2
Don’t know think it’s the vm config or power , otherwise I would see issues with other files too… It’s only this particular season… So maybe codec related… Since I also have that media not supported message when using direct play…
When you turn on Verbose, and see speed = 0.0 this means it’s transcoding at 0 -> 9% of the real time requirement. A value of 0.1 would be 10% through 19%.
Put the logging level on DEBUG, with Verbose OFF.
Restart PMS so I see the beginning of fresh logs
Recreate the playback of one video.
Let it attempt for 30 seconds
Stop playback and wait 20 more seconds
Settings - Server - Troubleshooting - Download Logs
Get the XML for the piece you just played (Hover over it in the browser -> ellipsis -> Get Info -> View XML)
Attach the ZIP
9 Paste the XML <media> -> </media> portion. This is only the top part with all the stream info.
Paste the XML using:
``` (these are back ticks)
Paste XML here
```
Synology’s Xeons don’t have the CPU power to decode HEVC in software and burn the subtitles.
They also are not QSV (hardware transcoding) capable.
Nov 29, 2019 20:53:45.982 [0x7fb2acf2f700] INFO - Plex Media Server v1.18.2.2058-e67a4e892 - Synology DS3615xs x86_64 - build: linux-x86_64 synology - GMT 01:00
Nov 29, 2019 20:53:45.982 [0x7fb2acf2f700] INFO - Linux version: DSM 6.1.7.15284-3, language: en-US
Nov 29, 2019 20:53:45.982 [0x7fb2acf2f700] INFO - Processor Intel(R) Xeon(R) CPU E3-1220L V2 @ 2.30GHz
Nov 29, 2019 20:53:45.982 [0x7fb2acf2f700] INFO - /var/packages/Plex Media Server/target/Plex Media Server
Nov 29, 2019 20:53:45.979 [0x7fb2b75db740] DEBUG - BPQ: [Idle] -> [Starting]
Nov 29, 2019 20:55:31.258 [0x7fb253fff700] DEBUG - MDE: analyzing media item 9756
Nov 29, 2019 20:55:31.258 [0x7fb253fff700] DEBUG - MDE: E8 - Jericho: Direct Play is disabled
Nov 29, 2019 20:55:31.258 [0x7fb253fff700] DEBUG - MDE: E8 - Jericho: media must be transcoded in order to use the hls protocol
Nov 29, 2019 20:55:31.258 [0x7fb253fff700] DEBUG - MDE: E8 - Jericho: selected subtitle cannot be converted to a compatible format, burning into video stream
Nov 29, 2019 20:55:31.258 [0x7fb253fff700] DEBUG - MDE: E8 - Jericho: Direct Streaming is disabled, so video stream will be transcoded
Nov 29, 2019 20:55:31.258 [0x7fb253fff700] DEBUG - MDE: E8 - Jericho: no remuxable profile found, so video stream will be transcoded
Nov 29, 2019 20:55:31.258 [0x7fb253fff700] DEBUG - MDE: Cannot direct stream video stream due to profile or setting limitations
Nov 29, 2019 20:55:31.258 [0x7fb253fff700] DEBUG - Scaled up video bitrate to 45481Kbps based on 4.500000x fudge factor.
Nov 29, 2019 20:55:31.258 [0x7fb253fff700] DEBUG - Scaled maximum bitrate for resolution reduction to 11370Kbps.
Nov 29, 2019 20:55:31.258 [0x7fb253fff700] DEBUG - MDE: E8 - Jericho: Audio Direct Streaming is disabled, so video's audio stream will be transcoded
Nov 29, 2019 20:55:31.258 [0x7fb253fff700] DEBUG - MDE: Cannot direct stream audio stream due to profile or setting limitations
Nov 29, 2019 20:55:31.258 [0x7fb253fff700] DEBUG - MDE: Titans (2018) - S2 E8 - Jericho: selected media 0 / 9756
Nov 29, 2019 20:55:31.258 [0x7fb253fff700] DEBUG - Streaming Resource: Adding session 0x7fb268067110:mtcjs3w774g7i which is using transcoder slot. Used slots is now 1
Nov 29, 2019 20:55:31.258 [0x7fb253fff700] DEBUG - Streaming Resource: Added session 0x7fb268067110:mtcjs3w774g7i
Nov 29, 2019 20:55:31.258 [0x7fb253fff700] DEBUG - Streaming Resource: Reached Decision id=7347 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=9756 part=(id=9861 decision=transcode container=mpegts protocol=hls streams=(Video=(id=23725 decision=transcode bitrate=8566 encoder=libx264 width=1920 height=1080) Audio=(id=23726 decision=transcode bitrate=366 encoder=ac3 channels=6 rate=48000) Subtitle=(id=23728 decision=burn languageCode= location=embedded))))
Nov 29, 2019 20:55:31.258 [0x7fb253fff700] DEBUG - Found session GUID of mtcjs3w774g7i in session start.
Nov 29, 2019 20:55:31.258 [0x7fb253fff700] DEBUG - Using session GUID mtcjs3w774g7i for new transcode session.
Nov 29, 2019 20:55:31.258 [0x7fb253fff700] DEBUG - Cleaning directory for session mtcjs3w774g7i ()
Nov 29, 2019 20:55:31.259 [0x7fb2a4939700] DEBUG - Completed: [192.168.0.119:38881] 200 GET /video/:/transcode/universal/start.m3u8 (8 live) GZIP 11ms 467 bytes (pipelined: 1)
Nov 29, 2019 20:55:31.268 [0x7fb29c806700] DEBUG - Request: [192.168.0.119:38881 (Subnet)] GET /video/:/transcode/universal/session/mtcjs3w774g7i/base/index.m3u8 (8 live) GZIP Signed-in
Nov 29, 2019 20:55:31.268 [0x7fb29c806700] DEBUG - Found session GUID of mtcjs3w774g7i in session start.
Nov 29, 2019 20:55:31.268 [0x7fb29c806700] DEBUG - Starting a transcode session mtcjs3w774g7i at offset -1.0 (state=3)
Nov 29, 2019 20:55:31.269 [0x7fb29c806700] DEBUG - [Universal] Using local file path instead of URL: /volume3/Media/Series/Titans/Season 2/e08.mkv
Nov 29, 2019 20:55:31.269 [0x7fb29c806700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/streams/23728?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Nov 29, 2019 20:55:31.269 [0x7fb2a4939700] DEBUG - Auth: authenticated user 1 as Fabio.Pergola
Nov 29, 2019 20:55:31.269 [0x7fb29df76700] DEBUG - Request: [127.0.0.1:50600 (Loopback)] GET /library/streams/23728 (9 live) GZIP Signed-in Token (Fabio.Pergola)
Nov 29, 2019 20:55:31.271 [0x7fb29df76700] DEBUG - Content-Length of /volume3/Media/Series/Titans/Season 2/e08.srt is 36329 (of total: 36329).
Nov 29, 2019 20:55:31.289 [0x7fb2a4939700] DEBUG - Completed: [127.0.0.1:50600] 200 GET /library/streams/23728 (9 live) GZIP 19ms 36329 bytes
Nov 29, 2019 20:55:31.289 [0x7fb29c806700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/streams/23728?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Nov 29, 2019 20:55:31.289 [0x7fb29c806700] DEBUG - Detected character set of UTF-8.
Nov 29, 2019 20:55:31.289 [0x7fb29c806700] DEBUG - Downloaded stream from [http://127.0.0.1:32400/library/streams/23728?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (codec: srt) to temporary file [/volume1/Plex/Library/Application Support/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-mtcjs3w774g7i-5b98e94e-ada4-4812-a1c7-ceb7788e5fa7/temp-0.srt]
Nov 29, 2019 20:55:31.289 [0x7fb29c806700] DEBUG - TPU: hardware transcoding: zero-copy support not present
Nov 29, 2019 20:55:31.290 [0x7fb29c806700] DEBUG - Job running: EAE_ROOT='/volume1/Plex/tmp_transcoding/pms-d3c3e8c0-ae1c-4257-ae1e-f7e74b502856/EasyAudioEncoder' FFMPEG_EXTERNAL_LIBS='/volume1/Plex/Library/Application\ Support/Plex\ Media\ Server/Codecs/395e79c-2735-linux-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:0' 'hevc' '-codec:1' 'ac3' '-analyzeduration' '20000000' '-probesize' '20000000' '-i' '/volume3/Media/Series/Titans/Season 2/e08.mkv' '-analyzeduration' '20000000' '-probesize' '20000000' '-i' '/volume1/Plex/Library/Application Support/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-mtcjs3w774g7i-5b98e94e-ada4-4812-a1c7-ceb7788e5fa7/temp-0.srt' '-map_inlineass' '1:s:0' '-filter_complex' '[0:0]scale=w=1920:h=1080[0];[0]format=pix_fmts=yuv420p|nv12[1];[1]inlineass=font_scale=1.000000:font_path=/volume1/@appstore/Plex\\\ Media\\\ Server/Resources/Fonts/DejaVuSans-Regular.ttf:fontconfig_file=/volume1/@appstore/Plex\\\ Media\\\ Server/Resources/fonts.conf[2]' '-filter_complex' '[0:1] aresample=async=1:ocl='\''5.1'\'':osr=48000[3]' '-map' '[2]' '-codec:0' 'libx264' '-crf:0' '23' '-maxrate:0' '8566k' '-bufsize:0' '17132k' '-r:0' '23.975999999999999' '-preset:0' 'veryfast' '-x264opts' 'subme=0:me_range=4:rc_lookahead=10:me=dia:no_chroma_me:8x8dct=0:partitions=none:ref=4:bframes=2:cabac=1' '-force_key_frames:0' 'expr:gte(t,0+n_forced*1)' '-map' '[3]' '-metadata:s:1' 'language=eng' '-codec:1' 'ac3' '-b:1' '366k' '-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/mtcjs3w774g7i/5b98e94e-ada4-4812-a1c7-ceb7788e5fa7/seglist' '-segment_list_type' 'csv' '-segment_list_size' '5' '-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' '-map' '1:s:0' '-f' 'null' '-codec' 'ass' 'nullfile' '-start_at_zero' '-copyts' '-vsync' 'cfr' '-y' '-nostats' '-loglevel' 'quiet' '-loglevel_plex' 'error' '-progressurl' 'http://127.0.0.1:32400/video/:/transcode/session/mtcjs3w774g7i/5b98e94e-ada4-4812-a1c7-ceb7788e5fa7/progress'
so are you saying that the subtitle is causing the issue? cause as you can see in logs, i have lots of mkv tv show, but only this Titans season is issueing me, all other mkv just play fine on transcoding
its a combination of transcoding and an external subtitle then? i can test that easy, i just remove as a test the .srt file and play again
but now i tested that file on another samsung TV, a tizen model , on that samsung it doesnt buffer/stutter at all
all samsungs are on LAN cable, so no network issue
The other television looks like it can play the file directly.
The one you last attempted to play it on can’t play HEVC directly.
When that happens, Plex has to translate the video format so it will play (Transcode)
Plex (and others) do this in real time as it’s needed in a completely automatic manner.
what’s happening here is that one TV, because of its limitations, needs the CPU to do more than it is capable of in Real Time.
We do have a bit of a problem. You turned off DEBUG logging, thinking you’ll save space. The logs are fixed size regardless of the setting.
What needs to be done now is:
Debug logging needs to be manually enabled in the Preferences.xml file
PMS restarted and allowed to run a few minutes before you attempt again to open it.
This is bothering me. It implies no internet connectivity from within that container.
Nov 29, 2019 11:26:39.180 [0x1466bbd44700] WARN - JobManager: Could not find job for handle 9565
Nov 29, 2019 11:26:43.844 [0x1466bbd44700] WARN - JobManager: Could not find job for handle 9603
Nov 29, 2019 11:30:10.764 [0x146651dee700] INFO - It's been 2077 seconds, so we're starting scheduled library update for section 2 (Movies)
Nov 29, 2019 11:30:10.765 [0x146651dee700] INFO - It's been 2048 seconds, so we're starting scheduled library update for section 1 (Television)
Nov 29, 2019 11:38:21.471 [0x1466bb741700] ERROR - EventSource: Retrying in 15 seconds.
Nov 29, 2019 11:38:56.471 [0x1466bb741700] ERROR - EventSource: Retrying in 30 seconds.
Nov 29, 2019 11:39:28.483 [0x1466bb741700] ERROR - EventSource: Retrying in 60 seconds.
Nov 29, 2019 11:40:31.683 [0x1466bb942700] ERROR - EventSource: Retrying in 120 seconds.
Nov 29, 2019 11:42:34.975 [0x1466bb942700] ERROR - EventSource: Retrying in 240 seconds.
Nov 29, 2019 11:46:54.975 [0x1466bb942700] ERROR - EventSource: Retrying in 480 seconds.
Nov 29, 2019 11:55:14.975 [0x1466bb942700] ERROR - EventSource: Retrying in 600 seconds.
Nov 29, 2019 12:05:10.769 [0x1466517eb700] INFO - It's been 2078 seconds, so we're starting scheduled library update for section 2 (Movies)
Nov 29, 2019 12:05:10.769 [0x1466517eb700] INFO - It's been 2050 seconds, so we're starting scheduled library update for section 1 (Television)
Nov 29, 2019 12:40:10.773 [0x1466525f2700] INFO - It's been 2078 seconds, so we're starting scheduled lib
To add debug logging manually, add
LogDebug="1" logDebug="1"
to the preferences file, anywhere before the closing />
