Lagging when Playing Star Wars 4K video

Plex Media Server NAS & Devices
,
1

I cannot seem to stream a fan vers. of the original Star Wars (1977) original Theatrical vers. in 4K fluently:

My power lines show sufficient through put. (150 + mbs) but something is strange:

PNG
PNG665×752 133 KB

Why is my NAS only using 57 Mbs but the stats claim that my TV is using 129 Mbs???

Everytime I get the “Did you hear that? They shot down the main reactor! We’ll be destroyed, for sure! This is ma…”

…it starts to lag ad my Android TV Plex App says “server not strong enough…”

I have attached the logs from the server (synology DS918+) and the logs from the Plex Andreoid App.

Please help!

Plex Media Server Logs_2019-11-09_13-07-30.zip (3.8 MB)
PLEX Android TV App Star Wars 4K logs.txt (16.2 KB)

Server Version#: 1.18.2.2015
Player Version#: Android Sony Plex App: newest available.

2

There is something wrong for sure, altough I’m not expert in this area. I just see messages that I would not like to see myself :wink:
That is the only movie that is giving you a hard time ? Or is it a general problem ? Other 4K-media play ?

Summary

Nov 09, 2019 12:49:40.027 [0x7fd65cde2700] DEBUG - Client [3ed42a3f93f4d816-com-plexapp-android] reporting timeline state stopped, progress of 199994/7310336ms for guid=com.plexapp.agents.imdb://tt0076759?lang=en, ratingKey=560 url=, key=/library/metadata/560, containerKey=/playQueues/1036, metadataId=560, source=
Nov 09, 2019 12:49:40.028 [0x7fd65cde2700] DEBUG - Play progress on 560 ‘Star Wars’ - got played 199994 ms by account 1!
Nov 09, 2019 12:49:40.039 [0x7fd65cde2700] DEBUG - It took 0.000000 ms to retrieve 109 items.
Nov 09, 2019 12:49:40.041 [0x7fd65cde2700] DEBUG - [Now] User is nicolaidenmark (ID: 1)
Nov 09, 2019 12:49:40.041 [0x7fd65cde2700] DEBUG - [Now] Device is Android (BRAVIA 4K GB).
Nov 09, 2019 12:49:40.042 [0x7fd65cde2700] DEBUG - [Now] Profile is Android
Nov 09, 2019 12:49:40.042 [0x7fd65cde2700] DEBUG - [Now] Updated play state for /library/metadata/560.
Nov 09, 2019 12:49:40.043 [0x7fd65cde2700] DEBUG - Statistics: (3ed42a3f93f4d816-com-plexapp-android) Reporting active playback in state 3 of type 1 (scrobble: 0) for account 1
Nov 09, 2019 12:49:40.046 [0x7fd65cde2700] DEBUG - Streaming Resource: Removing session 0x7fd61c1fa0d0:3ed42a3f93f4d816-com-plexapp-android
Nov 09, 2019 12:49:40.048 [0x7fd67d79d700] DEBUG - Completed: [192.168.1.154:44751] 200 GET /:/timeline?bandwidth=44205&bufferedTime=0&containerKey=%2FplayQueues%2F1036&duration=7310336&guid=com.plexapp.agents.imdb%3A%2F%2Ftt0076759%3Flang%3Den&key=%2Flibrary%2Fmetadata%2F560&machineIdentifier=3dac10236289a918e858421b00ae6a3281e797d7&playbackTime=257162&playQueueItemID=33716&ratingKey=560&state=stopped&time=199994&token=xxxxxxxxxxxxxxxxxxxx (10 live) TLS GZIP 22ms 644 bytes (pipelined: 32)
Nov 09, 2019 12:49:40.094 [0x7fd67da8b700] DEBUG - Auth: authenticated user 1 as nicolaidenmark
Nov 09, 2019 12:49:40.095 [0x7fd6322b3700] DEBUG - Request: [192.168.1.47:58188 (Subnet)] GET /statistics/bandwidth?timespan=6 (9 live) TLS GZIP Signed-in Token (nicolaidenmark)
Nov 09, 2019 12:49:40.102 [0x7fd67d79d700] DEBUG - Completed: [192.168.1.47:58188] 200 GET /statistics/bandwidth?timespan=6 (9 live) TLS GZIP 7ms 2813 bytes (pipelined: 9)
Nov 09, 2019 12:49:40.585 [0x7fd67da8b700] DEBUG - Auth: authenticated user 1 as nicolaidenmark
Nov 09, 2019 12:49:40.585 [0x7fd65d6ac700] DEBUG - Request: [192.168.1.154:44751 (Subnet)] GET /hubs (10 live) TLS GZIP Signed-in Token (nicolaidenmark)
Nov 09, 2019 12:49:40.585 [0x7fd65d6ac700] DEBUG - HubCache: Adding ‘1/home.continue/1/hubs/da-dkexternal-media/’ to the cache (1/home.continue/1/hubs/da-dkexternal-media/).
Nov 09, 2019 12:49:40.585 [0x7fd65d6ac700] DEBUG - HubCache: Adding ‘1/home.ondeck/1/hubs/da-dkexternal-media/’ to the cache (1/home.ondeck/1/hubs/da-dkexternal-media/).
Nov 09, 2019 12:49:40.614 [0x7fd65d6ac700] DEBUG - HubCache: Computed ‘1/home.ondeck/1/hubs/da-dkexternal-media/’ in 28 ms.
Nov 09, 2019 12:49:40.614 [0x7fd65d6ac700] DEBUG - HubCache: 41 hubs cached, 58.3% hit ratio.
Nov 09, 2019 12:49:40.614 [0x7fd65d6ac700] DEBUG - HubCache: Computed ‘1/home.continue/1/hubs/da-dkexternal-media/’ in 28 ms.
Nov 09, 2019 12:49:40.614 [0x7fd65d6ac700] DEBUG - HubCache: Retrieving ‘1/home.ondeck/1/hubs/da-dkexternal-media/’ from the cache.
Nov 09, 2019 12:49:40.614 [0x7fd65d6ac700] DEBUG - HubCache: Retrieving ‘1/home.playlists/1/hubs/da-dkexternal-media/’ from the cache.
Nov 09, 2019 12:49:40.615 [0x7fd65d6ac700] DEBUG - HubCache: Retrieving ‘1/home.movies.recent/1/hubs/da-dkexternal-media/’ from the cache.
Nov 09, 2019 12:49:40.615 [0x7fd65d6ac700] DEBUG - HubCache: Retrieving ‘1/home.television.recent/1/hubs/da-dkexternal-media/’ from the cache.
Nov 09, 2019 12:49:40.615 [0x7fd65d6ac700] DEBUG - HubCache: Retrieving ‘1/home.videos.recent/1/hubs/da-dkexternal-media/’ from the cache.
Nov 09, 2019 12:49:40.615 [0x7fd65d6ac700] DEBUG - HubCache: Retrieving ‘1/home.photos.recent/1/hubs/da-dkexternal-media/’ from the cache.
Nov 09, 2019 12:49:40.615 [0x7fd65d6ac700] DEBUG - HubCache: Retrieving ‘1/home.music.recent/1/hubs/da-dkexternal-media/’ from the cache.
Nov 09, 2019 12:49:40.624 [0x7fd65d6ac700] DEBUG - There were 11 top-level paths for Teenage Mutant Ninja Turtles.
Nov 09, 2019 12:49:40.626 [0x7fd65d6ac700] DEBUG - Audio Stream: 18419, Subtitle Stream: 18421
Nov 09, 2019 12:49:40.626 [0x7fd65d6ac700] DEBUG - Audio Stream: 17233, Subtitle Stream: 17235
Nov 09, 2019 12:49:40.626 [0x7fd65d6ac700] DEBUG - Audio Stream: 17228, Subtitle Stream: 17230
Nov 09, 2019 12:49:40.629 [0x7fd67d79d700] DEBUG - Completed: [192.168.1.154:44751] 200 GET /hubs (10 live) TLS GZIP 44ms 9238 bytes (pipelined: 33)
Nov 09, 2019 12:49:40.645 [0x7fd67da8b700] DEBUG - Auth: authenticated user 1 as nicolaidenmark
Nov 09, 2019 12:49:40.645 [0x7fd65d3be700] DEBUG - Request: [192.168.1.154:44945 (Subnet)] GET /library/parts/11097/1573208834/file.mkv?autoAdjustQuality=0&hasMDE=1&location=lan&mediaBufferSize=209664 (10 live) TLS Signed-in Token (nicolaidenmark) (range: bytes=1192897050-)
Nov 09, 2019 12:49:40.653 [0x7fd65d3be700] DEBUG - Streaming Resource: Attempting to create AdHoc dp session 3ed42a3f93f4d816-com-plexapp-android for part 11097
Nov 09, 2019 12:49:40.663 [0x7fd65d3be700] DEBUG - TranscodeUniversalRequest: using profile Generic
Nov 09, 2019 12:49:40.663 [0x7fd65d3be700] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/560?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&includeBandwidths=1&offset=-1&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=3ed42a3f93f4d816-com-plexapp-android
Nov 09, 2019 12:49:40.663 [0x7fd65d3be700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/metadata/560?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&includeBandwidths=1&offset=-1&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=3ed42a3f93f4d816-com-plexapp-android
Nov 09, 2019 12:49:40.664 [0x7fd67da8b700] DEBUG - Auth: authenticated user 1 as nicolaidenmark
Nov 09, 2019 12:49:40.665 [0x7fd6325a1700] DEBUG - Request: [127.0.0.1:40862 (Loopback)] GET /library/metadata/560 (11 live) GZIP Signed-in Token (nicolaidenmark)
Nov 09, 2019 12:49:40.676 [0x7fd6325a1700] DEBUG - It took 0.000000 ms to retrieve 109 items.
Nov 09, 2019 12:49:40.678 [0x7fd6325a1700] DEBUG - Audio Stream: 21500, Subtitle Stream: 21512
Nov 09, 2019 12:49:40.680 [0x7fd67da8b700] DEBUG - Completed: [127.0.0.1:40862] 200 GET /library/metadata/560 (11 live) GZIP 15ms 3101 bytes
Nov 09, 2019 12:49:40.680 [0x7fd65d3be700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/metadata/560?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&includeBandwidths=1&offset=-1&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=3ed42a3f93f4d816-com-plexapp-android
Nov 09, 2019 12:49:40.684 [0x7fd65d3be700] DEBUG - MDE: received PLEX_PROTOCOL_ANY from client, but could not determine best protocol. Defaulting to HTTP
Nov 09, 2019 12:49:40.684 [0x7fd65d3be700] DEBUG - MDE: analyzing media item 10553
Nov 09, 2019 12:49:40.684 [0x7fd65d3be700] DEBUG - MDE: Star Wars (1977): Direct Playing due to no transcode profile
Nov 09, 2019 12:49:40.684 [0x7fd65d3be700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc
Nov 09, 2019 12:49:40.684 [0x7fd65d3be700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/dca
Nov 09, 2019 12:49:40.684 [0x7fd65d3be700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/dca
Nov 09, 2019 12:49:40.684 [0x7fd65d3be700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/dca
Nov 09, 2019 12:49:40.684 [0x7fd65d3be700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/dca
Nov 09, 2019 12:49:40.685 [0x7fd65d3be700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/dca
Nov 09, 2019 12:49:40.685 [0x7fd65d3be700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/dca
Nov 09, 2019 12:49:40.685 [0x7fd65d3be700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/ac3
Nov 09, 2019 12:49:40.685 [0x7fd65d3be700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/ac3
Nov 09, 2019 12:49:40.685 [0x7fd65d3be700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/ac3
Nov 09, 2019 12:49:40.685 [0x7fd65d3be700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/ac3
Nov 09, 2019 12:49:40.685 [0x7fd65d3be700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/ac3
Nov 09, 2019 12:49:40.685 [0x7fd65d3be700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/ac3
Nov 09, 2019 12:49:40.685 [0x7fd65d3be700] DEBUG - MDE: Star Wars (1977): selected media 0 / 10553
Nov 09, 2019 12:49:40.685 [0x7fd65d3be700] DEBUG - Streaming Resource: Added session 0x7fd614134100:3ed42a3f93f4d816-com-plexapp-android
Nov 09, 2019 12:49:40.685 [0x7fd65d3be700] DEBUG - Streaming Resource: Reached Decision id=560 codes=(MDE=1000,Direct play OK.) media=(id=10553 part=(id=11097 decision=direct play protocol= streams=(Video=(id=21499 decision= width=3840 height=2160) Audio=(id=21500 decision= channels=0 rate=0) Subtitle=(id=21512 decision=unavailable languageCode=dan location=sidecar))))*
Nov 09, 2019 12:49:40.685 [0x7fd65d3be700] DEBUG - Content-Length of /volume1/Film - NAS/Films/Star Wars Films/Original Thetrical 4K vers/Star Wars (Original Theatrical, 4K (1977)).mkv is 79380932146 (of total: 80573829196).
Nov 09, 2019 12:49:40.845 [0x7fd67da8b700] DEBUG - Failed to stream media, client probably disconnected after 1245184 bytes: 104 - Connection reset by peer
Nov 09, 2019 12:49:40.846 [0x7fd67da8b700] DEBUG - Completed after connection close: [192.168.1.154:44945] 206 GET /library/parts/11097/1573208834/file.mkv?autoAdjustQuality=0&hasMDE=1&location=lan&mediaBufferSize=209664 (11 live) TLS 200ms 1245184 bytes (range: bytes=1192897050-)

3

May I see the XML for this movie? (Hover over it, Get Info -> View XML )

Paste the XML (video and audio parts only) using:

``` (triple back-tick)
paste XML
```

will show as

paste XML

PMS is likely counting only what it knows but the forced direct play is sending everything.

4

I see from SSDP info that its a Sony KD-65XE9005
This Sony TV appears to have a 100Mbps LAN port. I think you are very close to its limits with this high-bitrate file !!
Did you try to put the movie a USB-disk/stick and plug it directly into the TV ?
There are several people complaining on the same issue, they experiences 4K stutter/lag/drops etc,etc.

1 Like
5

Not the only one. I have 7X gb MKVs of Batman (2160 HDR 10) that stall as well.

I have a 4K (prob) HDR MKV of The Last Jedi. That one is fine but direct play is something along 3x mbits, so it was probably shrinked.

Running a speed test app on my TV gets me only 6x-70 mbits if my Network player (also connected to the same 1200 mbits power line is switched off. If network player is on, I get only 40 mbits on the TV speed test. Neither 40 nor 30 - 40 mbits are sufficient for streaming 4K lossless MKVs.

6

I can’t retrieve any info that way. All I get is a blank page:

7

Hover over the movie, Expose the ellipsis (vertical)

Click it (you get Media Info)

Screenshot%20from%202019-11-11%2015-19-55
Screenshot from 2019-11-11 15-19-55.png739×528 58.5 KB

Left corner at the bottom is View XML The media part is all we need.

<MediaContainer size="1" allowSync="1" identifier="com.plexapp.plugins.library" librarySectionID="9" librarySectionTitle="UHD Movies" librarySectionUUID="50b0d73f-c586-4de4-a839-ce2609903923" mediaTagPrefix="/system/bundle/media/flags/" mediaTagVersion="1572181986">
<Video ratingKey="100563" key="/library/metadata/100563" guid="com.plexapp.agents.imdb://tt0112384?lang=en" studio="Imagine Entertainment" type="movie" title="Apollo 13" librarySectionTitle="UHD Movies" librarySectionID="9" librarySectionKey="/library/sections/9" contentRating="PG" summary="The true story of technical troubles that scuttle the Apollo 13 lunar mission in 1971, risking the lives of astronaut Jim Lovell and his crew, with the failed journey turning into a thrilling saga of heroism. Drifting more than 200,000 miles from Earth, the astronauts work furiously with the ground crew to avert tragedy." rating="9.5" audienceRating="8.7" year="1995" tagline="Houston, we have a problem." thumb="/library/metadata/100563/thumb/1570664981" art="/library/metadata/100563/art/1570664981" duration="8420953" originallyAvailableAt="1995-06-30" addedAt="1527443113" updatedAt="1570664981" audienceRatingImage="rottentomatoes://image.rating.upright" chapterSource="media" primaryExtraKey="/library/metadata/100643" ratingImage="rottentomatoes://image.rating.ripe">
<Media id="99244" duration="8420953" bitrate="44012" width="3840" height="2160" aspectRatio="1.78" audioChannels="8" audioCodec="dca-ma" videoCodec="hevc" videoResolution="4k" container="mkv" videoFrameRate="24p" audioProfile="ma" videoProfile="main 10">
<Part accessible="1" exists="1" id="99288" key="/library/parts/99288/1527443113/file.mkv" duration="8420953" file="/share/uhd/Apollo 13 (1995)/Apollo 13 (1995).mkv" size="46332351616" audioProfile="ma" container="mkv" deepAnalysisVersion="4" requiredBandwidths="95553,85744,73417,68393,63369,58347,52094,47269" videoProfile="main 10">
<Stream id="179161" streamType="1" default="1" codec="hevc" index="0" bitrate="36786" language="English" languageCode="eng" bitDepth="10" chromaSubsampling="4:2:0" colorPrimaries="bt2020" colorRange="tv" colorSpace="bt2020nc" colorTrc="smpte2084" frameRate="23.976" height="2160" level="153" profile="main 10" refFrames="1" requiredBandwidths="88101,78291,65498,60474,55450,51180,44262,39472" width="3840" displayTitle="4K (HEVC Main 10 HDR)"/>
<Stream id="179162" streamType="2" selected="1" default="1" codec="dca" index="1" channels="8" bitrate="7226" language="English" languageCode="eng" audioChannelLayout="7.1" bitDepth="24" profile="ma" requiredBandwidths="8475,8230,7843,7479,7297,7249,7249,7249" samplingRate="48000" displayTitle="English (DTS-HD MA 7.1)"/>
</Part>
</Media>
8

Hi Chuck,

I am aware of how to find the “View XML”. Problem is that when I click it, nothing (i.e. a blank browser page) is displayed.

Med venlig hilsen

Nicolai Godrim

Kandidat I International Erhvervskommunikation

Best Regards,

Nicolai Godrim

Master Degree in English and ESP

27D2CD18F67941498F997D7AC623C9E1.png
27D2CD18F67941498F997D7AC623C9E1.png708×1 82 Bytes

9

I tried hardwiring my TV to ethernet. Despite going from 60 mbit to 95 mbit throughput, the video still stalls in the same spot, however, in the log from my Pl,ex Android TV app, I found some line which don’t look too good, for example:

Summary

**11-18 12:48:59.934 i: [FFmpeg] [INFO] DURATION-eng :
11-18 12:48:59.934 i: [FFmpeg] [INFO] 01:55:07.241000000
11-18 12:48:59.934 i: [FF] Video decoder doesn’t support hevc (video/hevc).
11-18 12:48:59.934 i: [FFmpeg] [INFO] **

11-18 12:48:59.936 i: [FF] Video decoder doesn’t support dca (audio/vnd.dts.hd).
11-18 12:48:59.936 i: [FF] Audio decoder supports dca (audio/vnd.dts.hd).
11-18 12:48:59.936 i: [FF] Audio decoder supports dca (audio/vnd.dts.hd).
11-18 12:48:59.936 i: [FF] Video decoder doesn’t support dca (audio/vnd.dts).
11-18 12:48:59.936 i: [FF] Audio decoder supports dca (audio/vnd.dts).
11-18 12:48:59.936 i: [FF] Audio decoder supports dca (audio/vnd.dts).
11-18 12:48:59.937 i: [FF] Video decoder doesn’t support dca (audio/vnd.dts.hd).
11-18 12:48:59.937 i: [FF] Audio decoder supports dca (audio/vnd.dts.hd).
11-18 12:48:59.937 i: [FF] Audio decoder supports dca (audio/vnd.dts.hd).
11-18 12:48:59.937 i: [FF] Video decoder doesn’t support dca (audio/vnd.dts).
11-18 12:48:59.937 i: [FF] Audio decoder supports dca (audio/vnd.dts).
11-18 12:48:59.937 i: [FF] Audio decoder supports dca (audio/vnd.dts).
11-18 12:48:59.937 i: [FF] Video decoder doesn’t support dca (audio/vnd.dts.hd).
11-18 12:48:59.937 i: [FF] Audio decoder supports dca (audio/vnd.dts.hd).
11-18 12:48:59.937 i: [FF] Audio decoder supports dca (audio/vnd.dts.hd).
11-18 12:48:59.938 i: [FF] Video decoder doesn’t support dca (audio/vnd.dts).
11-18 12:48:59.938 i: [FF] Audio decoder supports dca (audio/vnd.dts).
11-18 12:48:59.938 i: [FF] Audio decoder supports dca (audio/vnd.dts).
11-18 12:48:59.938 i: [FF] Video decoder doesn’t support ac3 (audio/ac3).
11-18 12:48:59.938 i: [FF] Audio decoder doesn’t support ac3 (audio/ac3).
11-18 12:48:59.938 i: [FF] Video decoder doesn’t support ac3 (audio/ac3).
11-18 12:48:59.940 i: [FF] Audio decoder doesn’t support ac3 (audio/ac3).
11-18 12:48:59.940 i: [FF] Video decoder doesn’t support ac3 (audio/ac3).
11-18 12:48:59.940 i: [FF] Audio decoder doesn’t support ac3 (audio/ac3).
11-18 12:48:59.941 i: [FF] Video decoder doesn’t support ac3 (audio/ac3).
11-18 12:48:59.942 i: [FF] Audio decoder doesn’t support ac3 (audio/ac3).
11-18 12:48:59.946 i: [FF] Video decoder doesn’t support ac3 (audio/ac3).
11-18 12:48:59.947 i: [FF] Audio decoder doesn’t support ac3 (audio/ac3).
11-18 12:48:59.947 i: [FF] Video decoder doesn’t support ac3 (audio/ac3).
11-18 12:48:59.947 i: [FF] Audio decoder doesn’t support ac3 (audio/ac3).
11-18 12:48:59.947 i: [FF] Video decoder doesn’t support subrip (application/x-subrip).
11-18 12:48:59.948 i: [FF] Audio decoder doesn’t support subrip (application/x-subrip).
11-18 12:48:59.948 i: [FF] Video decoder doesn’t support subrip (application/x-subrip).
11-18 12:48:59.948 i: [FF] Audio decoder doesn’t support subrip (application/x-subrip).
11-18 12:48:59.948 i: [FF] Video decoder doesn’t support subrip (application/x-subrip).
11-18 12:48:59.948 i: [FF] Audio decoder doesn’t support subrip (application/x-subrip).
11-18 12:48:59.948 i: [FF][MediaPeriod] Selecting tracks.
11-18 12:48:59.948 i: [BufferHelper] Assumed Bitrate: 176326, Segment Count: 20662
11-18 12:48:59.948 i: [BufferHelper] Detected memory pressure, reducing segment count to fit available memory: 512
11-18 12:48:59.949 i: [BufferHelper] Segment count: 3276
11-18 12:48:59.949 i: [FFmpeg] [INFO] Stream metadata entries found: 9.
11-18 12:48:59.949 i: [FFmpeg] [INFO] Stream metadata entries found: 9.
11-18 12:48:59.949 i: [Exo2VideoPlayer] Switching video surface to use a standard surface.

**11-18 12:48:59.950 i: [FF] ********************************************************
[FF] Statistics:
[FF] ********************************************************
State: Buffering Loading: Yes Auto Play: Yes
Surface: None
Position: 0:00 Duration: 2:01:50
Window Start: 0:00 Window End: 2:01:50 Position in Period: 0:00
Period Position: 0:00 Period Duration: -21:-19
Video: None

See more info in attached log.

Star Wars (1977) HEVC log.txt (96.4 KB)

Please help.

10

Interesting:

If I select my VLC Media Player App on the TV AND the audio commentary by the visual effects guy, the film streams no problem.

However, doing the same with the Plex app and it stalls. I also notice that the below screen shot indicates a much lower mbts / sec average than is being reported in the Plex Media Server Dashboard when the movie is played by Plex (129 mbits/s)

Star%20Wars%20216p%204K%20audio%20tracks%20list

11

VLC is a read/direct-render architecture.
Plex is a Read/Server - Player/Render architecture.

We need more information to diagnose.

Source video info is only part of it.

  1. source
  2. player
  3. DEBUG logs of a playback attempt. (the ZIP file)
12

Plex Media Server Logs_2019-11-18_15-34-21.zip (2.4 MB)

plex%20direct%20play%20failPNG

13

Thank you for the logs:

  1. HW transcoding is available & utilized.
  2. Subtitles were selected for playback
  3. Audio track selected required conversion

The J3455 , while having HW capability for the video, does not have sufficent CPU speed to render subtitles and transcode the audio at real-time playback requirement.

This is a common problem with Synology systems.

The subtitle problem can be remedied by burning the subtitles into the video external to Plex or by running Plex on a stronger CPU which does have the CPU power.

Nov 18, 2019 15:25:01.998 [0x7f5aeeb7d700] DEBUG - Found session GUID of 2vpbwge84n583xjf4fs0hkg6 in session start.
Nov 18, 2019 15:25:01.998 [0x7f5aeeb7d700] DEBUG - TranscodeUniversalRequest: using augmented profile Firefox
Nov 18, 2019 15:25:01.998 [0x7f5aeeb7d700] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/560?checkFiles=1&includeBandwidths=1&offset=-1&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=rhrtgxtbphoe2wh1ogt4obch
Nov 18, 2019 15:25:01.999 [0x7f5aeeb7d700] DEBUG - Auth: authenticated user 1 as nicolaidenmark
Nov 18, 2019 15:25:02.011 [0x7f5aeeb7d700] DEBUG - It took 0.000000 ms to retrieve 109 items.
Nov 18, 2019 15:25:02.017 [0x7f5aeeb7d700] DEBUG - Audio Stream: 21502, Subtitle Stream: 21513
Nov 18, 2019 15:25:02.020 [0x7f5aeeb7d700] DEBUG - Streaming Resource: Attempting to create AdHoc transcode session rhrtgxtbphoe2wh1ogt4obch
Nov 18, 2019 15:25:02.270 [0x7f5aec855700] WARN - [FFMPEG] - No quality level set; using default (20).
Nov 18, 2019 15:25:02.352 [0x7f5b0eaa6700] DEBUG - Auth: authenticated user 1 as nicolaidenmark
Nov 18, 2019 15:25:02.353 [0x7f5aee2b3700] DEBUG - Request: [192.168.1.47:55648 (Subnet)] GET /myplex/account (12 live) TLS GZIP Signed-in Token (nicolaidenmark)
Nov 18, 2019 15:25:02.354 [0x7f5b0e7b8700] DEBUG - Completed: [192.168.1.47:55648] 200 GET /myplex/account (12 live) TLS GZIP 1ms 2600 bytes (pipelined: 1)
Nov 18, 2019 15:25:02.644 [0x7f5aec855700] DEBUG - Codecs: testing hevc (decoder) with hwdevice vaapi
Nov 18, 2019 15:25:02.644 [0x7f5aeeb7d700] DEBUG - Codecs: testing hevc (decoder) with hwdevice vaapi
Nov 18, 2019 15:25:02.749 [0x7f5aec855700] DEBUG - Codecs: hardware transcoding: testing API vaapi
Nov 18, 2019 15:25:02.749 [0x7f5aeeb7d700] DEBUG - Codecs: hardware transcoding: testing API vaapi
Nov 18, 2019 15:25:02.750 [0x7f5aec855700] DEBUG - Codecs: Testing with profile 'Main 10'
Nov 18, 2019 15:25:02.751 [0x7f5aeeb7d700] DEBUG - Codecs: Testing with profile 'Main 10'
Nov 18, 2019 15:25:02.756 [0x7f5aec855700] DEBUG - Codecs: 10-bit HEVC test succeeded
Nov 18, 2019 15:25:02.757 [0x7f5aeeb7d700] DEBUG - Codecs: 10-bit HEVC test succeeded
Nov 18, 2019 15:25:02.758 [0x7f5aec855700] DEBUG - Scaled up video bitrate to 345960Kbps based on 4.500000x fudge factor.
Nov 18, 2019 15:25:02.758 [0x7f5aec855700] DEBUG - Scaled maximum bitrate for resolution reduction to 121513Kbps.
Nov 18, 2019 15:25:02.760 [0x7f5aeeb7d700] DEBUG - Scaled up video bitrate to 345960Kbps based on 4.500000x fudge factor.
Nov 18, 2019 15:25:02.760 [0x7f5aeeb7d700] DEBUG - Scaled maximum bitrate for resolution reduction to 121513Kbps.
Nov 18, 2019 15:25:02.790 [0x7f5aeeb7d700] DEBUG - MDE: Selected protocol dash; container: mp4
Nov 18, 2019 15:25:02.790 [0x7f5aeeb7d700] DEBUG - MDE: analyzing media item 10553
Nov 18, 2019 15:25:02.790 [0x7f5aeeb7d700] DEBUG - MDE: Star Wars (1977): Direct Play is disabled
Nov 18, 2019 15:25:02.790 [0x7f5aeeb7d700] DEBUG - MDE: Star Wars (1977): media must be transcoded in order to use the dash protocol
Nov 18, 2019 15:25:02.790 [0x7f5aeeb7d700] DEBUG - MDE: Star Wars (1977): selected audio stream is not the first audio stream and direct play stream selection is not enabled
Nov 18, 2019 15:25:02.790 [0x7f5aeeb7d700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc
Nov 18, 2019 15:25:02.790 [0x7f5aeeb7d700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/dca
Nov 18, 2019 15:25:02.791 [0x7f5aeeb7d700] DEBUG - MDE: Star Wars (1977): selected embedded subtitle stream is not the first embedded subtitle stream
Nov 18, 2019 15:25:02.791 [0x7f5aeeb7d700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/dca
Nov 18, 2019 15:25:02.791 [0x7f5aeeb7d700] DEBUG - MDE: Star Wars (1977): selected embedded subtitle stream is not the first embedded subtitle stream
Nov 18, 2019 15:25:02.790 [0x7f5aec855700] DEBUG - MDE: Selected protocol dash; container: mp4
Nov 18, 2019 15:25:02.791 [0x7f5aeeb7d700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/dca
Nov 18, 2019 15:25:02.791 [0x7f5aec855700] DEBUG - MDE: analyzing media item 10553
Nov 18, 2019 15:25:02.791 [0x7f5aeeb7d700] DEBUG - MDE: Star Wars (1977): selected embedded subtitle stream is not the first embedded subtitle stream
Nov 18, 2019 15:25:02.791 [0x7f5aec855700] DEBUG - MDE: Star Wars (1977): Direct Play is disabled
Nov 18, 2019 15:25:02.791 [0x7f5aeeb7d700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/dca
Nov 18, 2019 15:25:02.791 [0x7f5aec855700] DEBUG - MDE: Star Wars (1977): media must be transcoded in order to use the dash protocol
Nov 18, 2019 15:25:02.791 [0x7f5aeeb7d700] DEBUG - MDE: Star Wars (1977): selected embedded subtitle stream is not the first embedded subtitle stream
Nov 18, 2019 15:25:02.791 [0x7f5aec855700] DEBUG - MDE: Star Wars (1977): selected audio stream is not the first audio stream and direct play stream selection is not enabled
Nov 18, 2019 15:25:02.791 [0x7f5aeeb7d700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/dca
Nov 18, 2019 15:25:02.791 [0x7f5aec855700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc
Nov 18, 2019 15:25:02.791 [0x7f5aec855700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/dca
Nov 18, 2019 15:25:02.791 [0x7f5aeeb7d700] DEBUG - MDE: Star Wars (1977): selected embedded subtitle stream is not the first embedded subtitle stream
Nov 18, 2019 15:25:02.791 [0x7f5aeeb7d700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/dca
Nov 18, 2019 15:25:02.791 [0x7f5aec855700] DEBUG - MDE: Star Wars (1977): selected embedded subtitle stream is not the first embedded subtitle stream
Nov 18, 2019 15:25:02.791 [0x7f5aec855700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/dca
Nov 18, 2019 15:25:02.791 [0x7f5aeeb7d700] DEBUG - MDE: Star Wars (1977): selected embedded subtitle stream is not the first embedded subtitle stream
Nov 18, 2019 15:25:02.791 [0x7f5aeeb7d700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/ac3
Nov 18, 2019 15:25:02.791 [0x7f5aec855700] DEBUG - MDE: Star Wars (1977): selected embedded subtitle stream is not the first embedded subtitle stream
Nov 18, 2019 15:25:02.791 [0x7f5aec855700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/dca
Nov 18, 2019 15:25:02.791 [0x7f5aeeb7d700] DEBUG - MDE: Star Wars (1977): selected embedded subtitle stream is not the first embedded subtitle stream
Nov 18, 2019 15:25:02.791 [0x7f5aeeb7d700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/ac3
Nov 18, 2019 15:25:02.791 [0x7f5aec855700] DEBUG - MDE: Star Wars (1977): selected embedded subtitle stream is not the first embedded subtitle stream
Nov 18, 2019 15:25:02.791 [0x7f5aec855700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/dca
Nov 18, 2019 15:25:02.791 [0x7f5aeeb7d700] DEBUG - MDE: Star Wars (1977): selected embedded subtitle stream is not the first embedded subtitle stream
Nov 18, 2019 15:25:02.791 [0x7f5aeeb7d700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/ac3
Nov 18, 2019 15:25:02.791 [0x7f5aec855700] DEBUG - MDE: Star Wars (1977): selected embedded subtitle stream is not the first embedded subtitle stream
Nov 18, 2019 15:25:02.791 [0x7f5aec855700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/dca
Nov 18, 2019 15:25:02.791 [0x7f5aeeb7d700] DEBUG - MDE: Star Wars (1977): selected embedded subtitle stream is not the first embedded subtitle stream
Nov 18, 2019 15:25:02.791 [0x7f5aeeb7d700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/ac3
Nov 18, 2019 15:25:02.791 [0x7f5aec855700] DEBUG - MDE: Star Wars (1977): selected embedded subtitle stream is not the first embedded subtitle stream
Nov 18, 2019 15:25:02.791 [0x7f5aec855700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/dca
Nov 18, 2019 15:25:02.791 [0x7f5aeeb7d700] DEBUG - MDE: Star Wars (1977): selected embedded subtitle stream is not the first embedded subtitle stream
Nov 18, 2019 15:25:02.791 [0x7f5aec855700] DEBUG - MDE: Star Wars (1977): selected embedded subtitle stream is not the first embedded subtitle stream
Nov 18, 2019 15:25:02.791 [0x7f5aeeb7d700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/ac3
Nov 18, 2019 15:25:02.791 [0x7f5aec855700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/ac3
Nov 18, 2019 15:25:02.791 [0x7f5aeeb7d700] DEBUG - MDE: Star Wars (1977): selected embedded subtitle stream is not the first embedded subtitle stream
Nov 18, 2019 15:25:02.791 [0x7f5aec855700] DEBUG - MDE: Star Wars (1977): selected embedded subtitle stream is not the first embedded subtitle stream
Nov 18, 2019 15:25:02.791 [0x7f5aeeb7d700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/ac3
Nov 18, 2019 15:25:02.791 [0x7f5aec855700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/ac3
Nov 18, 2019 15:25:02.791 [0x7f5aeeb7d700] DEBUG - MDE: Star Wars (1977): selected embedded subtitle stream is not the first embedded subtitle stream
Nov 18, 2019 15:25:02.792 [0x7f5aec855700] DEBUG - MDE: Star Wars (1977): selected embedded subtitle stream is not the first embedded subtitle stream
Nov 18, 2019 15:25:02.792 [0x7f5aeeb7d700] DEBUG - Star Wars - video.bitDepth limitation applies: 10 > 8
Nov 18, 2019 15:25:02.792 [0x7f5aec855700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/ac3
Nov 18, 2019 15:25:02.792 [0x7f5aeeb7d700] DEBUG - Star Wars - audio.channels limitation applies: 6 > 2
Nov 18, 2019 15:25:02.792 [0x7f5aec855700] DEBUG - MDE: Star Wars (1977): selected embedded subtitle stream is not the first embedded subtitle stream
Nov 18, 2019 15:25:02.792 [0x7f5aec855700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/ac3
Nov 18, 2019 15:25:02.792 [0x7f5aeeb7d700] DEBUG - MDE: Star Wars (1977): no remuxable profile found, so video stream will be transcoded
Nov 18, 2019 15:25:02.792 [0x7f5aec855700] DEBUG - MDE: Star Wars (1977): selected embedded subtitle stream is not the first embedded subtitle stream
Nov 18, 2019 15:25:02.792 [0x7f5aec855700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/ac3
Nov 18, 2019 15:25:02.792 [0x7f5aec855700] DEBUG - MDE: Star Wars (1977): selected embedded subtitle stream is not the first embedded subtitle stream
Nov 18, 2019 15:25:02.792 [0x7f5aec855700] DEBUG - MDE: Star Wars (1977): no direct play video profile exists for http/mkv/hevc/ac3
Nov 18, 2019 15:25:02.792 [0x7f5aeeb7d700] DEBUG - MDE: Cannot direct stream video stream due to profile or setting limitations
Nov 18, 2019 15:25:02.792 [0x7f5aec855700] DEBUG - MDE: Star Wars (1977): selected embedded subtitle stream is not the first embedded subtitle stream
Nov 18, 2019 15:25:02.792 [0x7f5aec855700] DEBUG - Star Wars - video.bitDepth limitation applies: 10 > 8
Nov 18, 2019 15:25:02.792 [0x7f5aec855700] DEBUG - Star Wars - audio.channels limitation applies: 6 > 2
Nov 18, 2019 15:25:02.792 [0x7f5aec855700] DEBUG - MDE: Star Wars (1977): no remuxable profile found, so video stream will be transcoded
Nov 18, 2019 15:25:02.792 [0x7f5aeeb7d700] DEBUG - Codecs: testing hevc (decoder) with hwdevice vaapi
Nov 18, 2019 15:25:02.792 [0x7f5aec855700] DEBUG - MDE: Cannot direct stream video stream due to profile or setting limitations
Nov 18, 2019 15:25:02.792 [0x7f5aeeb7d700] DEBUG - Codecs: hardware transcoding: testing API vaapi
Nov 18, 2019 15:25:02.792 [0x7f5aec855700] DEBUG - Codecs: testing hevc (decoder) with hwdevice vaapi
Nov 18, 2019 15:25:02.792 [0x7f5aec855700] DEBUG - Codecs: hardware transcoding: testing API vaapi
Nov 18, 2019 15:25:02.793 [0x7f5aeeb7d700] DEBUG - Codecs: Testing with profile 'Main 10'
Nov 18, 2019 15:25:02.794 [0x7f5aec855700] DEBUG - Codecs: Testing with profile 'Main 10'
Nov 18, 2019 15:25:02.799 [0x7f5aeeb7d700] DEBUG - Codecs: 10-bit HEVC test succeeded
Nov 18, 2019 15:25:02.800 [0x7f5aeeb7d700] DEBUG - Scaled up video bitrate to 345960Kbps based on 4.500000x fudge factor.
Nov 18, 2019 15:25:02.800 [0x7f5aeeb7d700] DEBUG - Scaled maximum bitrate for resolution reduction to 121513Kbps.
Nov 18, 2019 15:25:02.801 [0x7f5aeeb7d700] DEBUG - Star Wars - audio.channels limitation applies: 6 > 2
Nov 18, 2019 15:25:02.801 [0x7f5aeeb7d700] DEBUG - MDE: Cannot direct stream audio stream due to profile or setting limitations
Nov 18, 2019 15:25:02.801 [0x7f5aeeb7d700] DEBUG - MDE: Star Wars (1977): selected media 0 / 10553
Nov 18, 2019 15:25:02.801 [0x7f5aec855700] DEBUG - Codecs: 10-bit HEVC test succeeded
Nov 18, 2019 15:25:02.803 [0x7f5aec855700] DEBUG - Scaled up video bitrate to 345960Kbps based on 4.500000x fudge factor.
Nov 18, 2019 15:25:02.803 [0x7f5aec855700] DEBUG - Scaled maximum bitrate for resolution reduction to 121513Kbps.
Nov 18, 2019 15:25:02.803 [0x7f5aec855700] DEBUG - Star Wars - audio.channels limitation applies: 6 > 2
Nov 18, 2019 15:25:02.803 [0x7f5aec855700] DEBUG - MDE: Cannot direct stream audio stream due to profile or setting limitations
Nov 18, 2019 15:25:02.803 [0x7f5aec855700] DEBUG - MDE: Star Wars (1977): selected media 0 / 10553
Nov 18, 2019 15:25:02.804 [0x7f5aeeb7d700] DEBUG - Streaming Resource: Adding session 0x7f5ab413f360:rhrtgxtbphoe2wh1ogt4obch which is using transcoder slot.  Used slots is now 1
Nov 18, 2019 15:25:02.804 [0x7f5aeeb7d700] DEBUG - Streaming Resource: Added session 0x7f5ab413f360:rhrtgxtbphoe2wh1ogt4obch
Nov 18, 2019 15:25:02.804 [0x7f5aeeb7d700] DEBUG - Streaming Resource: Reached Decision id=560 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=10553 part=(id=11097 decision=transcode container=mp4 protocol=dash streams=(Video=(id=21499 decision=transcode bitrate=121513 encoder=h264_vaapi width=2276 height=1280) Audio=(id=21502 decision=transcode bitrate=256 encoder=aac channels=2 rate=48000) Subtitle=(id=21513 decision=transcode bitrate=2147483647 encoder=ass languageCode=eng location=sidecar))))
Nov 18, 2019 15:25:02.804 [0x7f5aeeb7d700] DEBUG - Found session GUID of 2vpbwge84n583xjf4fs0hkg6 in session start.
Nov 18, 2019 15:25:02.804 [0x7f5aec855700] DEBUG - Streaming Resource: Reached Decision id=560 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=10553 part=(id=11097 decision=transcode container=mp4 protocol=dash streams=(Video=(id=21499 decision=transcode bitrate=121513 encoder=h264_vaapi width=2276 height=1280) Audio=(id=21502 decision=transcode bitrate=256 encoder=aac channels=2 rate=48000) Subtitle=(id=21513 decision=transcode bitrate=2147483647 encoder=ass languageCode=eng location=sidecar))))
Nov 18, 2019 15:25:02.804 [0x7f5aeeb7d700] DEBUG - Cleaning directory for session 2vpbwge84n583xjf4fs0hkg6 ()
Nov 18, 2019 15:25:02.805 [0x7f5aeeb7d700] DEBUG - Starting a transcode session 2vpbwge84n583xjf4fs0hkg6 at offset -1.0 (state=3)
Nov 18, 2019 15:25:02.806 [0x7f5aeeb7d700] DEBUG - TPU: hardware transcoding: using hardware decode accelerator vaapi
Nov 18, 2019 15:25:02.806 [0x7f5aeeb7d700] DEBUG - [Universal] Using local file path instead of URL: /volume1/Film - NAS/Films/Star Wars Films/Original Thetrical 4K vers/Star Wars (Original Theatrical, 4K (1977)).mkv
Nov 18, 2019 15:25:02.806 [0x7f5aeeb7d700] DEBUG - TPU: hardware transcoding: zero-copy support present
Nov 18, 2019 15:25:02.806 [0x7f5aeeb7d700] DEBUG - TPU: hardware transcoding: using zero-copy transcoding
Nov 18, 2019 15:25:02.806 [0x7f5aeeb7d700] DEBUG - Codecs: hardware transcoding: testing API vaapi
Nov 18, 2019 15:25:02.808 [0x7f5aeeb7d700] DEBUG - TPU: hardware transcoding: final decoder: vaapi, final encoder: vaapi
Nov 18, 2019 15:25:02.809 [0x7f5aeeb7d700] DEBUG - Job running: EAE_ROOT='/volume1/Plex/tmp_transcoding/pms-b91856af-5094-45bd-9511-a2bc6a443fb3/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' '-hwaccel:0' 'vaapi' '-hwaccel_fallback_threshold:0' '10' '-hwaccel_output_format:0' 'vaapi' '-codec:3' 'dca' '-analyzeduration' '20000000' '-probesize' '20000000' '-i' '/volume1/Film - NAS/Films/Star Wars Films/Original Thetrical 4K vers/Star Wars (Original Theatrical, 4K (1977)).mkv' '-filter_complex' '[0:0]hwupload[0];[0]scale_vaapi=w=2276:h=1280:format=nv12[1];[1]hwupload[2]' '-filter_complex' '[0:3] aresample=async=1:ocl='\''stereo'\'':osr=48000[3]' '-map' '[2]' '-metadata:s:0' 'language=eng' '-codec:0' 'h264_vaapi' '-b:0' '91134k' '-maxrate:0' '121513k' '-bufsize:0' '243026k' '-r:0' '23.975999999999999' '-force_key_frames:0' 'expr:gte(t,0+n_forced*1)' '-map' '[3]' '-metadata:s:1' 'language=eng' '-codec:1' 'aac' '-b:1' '256k' '-f' 'dash' '-seg_duration' '1' '-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/2vpbwge84n583xjf4fs0hkg6/3afe7382-a531-43c6-80c4-3eac1efb82cb/manifest' '-avoid_negative_ts' 'disabled' '-map_metadata' '-1' '-map_chapters' '-1' 'dash' '-map' '0:14' '-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/2vpbwge84n583xjf4fs0hkg6/3afe7382-a531-43c6-80c4-3eac1efb82cb/seglist?stream=subtitles' '-segment_list_type' 'csv' '-segment_list_size' '5' '-segment_list_separate_stream_times' '1' '-segment_format_options' 'ignore_readorder=1' '-segment_list_unfinished' '1' '-fflags' '+flush_packets' 'sub-chunk-%05d' '-start_at_zero' '-copyts' '-vsync' 'cfr' '-y' '-init_hw_device' 'vaapi=vaapi:,driver=iHD,kernel_driver=i915' '-hwaccel_device' 'vaapi' '-filter_hw_device' 'vaapi' '-nostats' '-loglevel' 'quiet' '-loglevel_plex' 'error' '-progressurl' 'http://127.0.0.1:32400/video/:/transcode/session/2vpbwge84n583xjf4fs0hkg6/3afe7382-a531-43c6-80c4-3eac1efb82cb/progress'
Nov 18, 2019 15:25:02.809 [0x7f5aeeb7d700] DEBUG - Jobs: Starting child process with pid 23430
Nov 18, 2019 15:25:02.848 [0x7f5b0e7b8700] DEBUG - Auth: authenticated user 1 as nicolaidenmark
Nov 18, 2019 15:25:02.849 [0x7f5aee88f700] DEBUG - Request: [192.168.1.47:55648 (Subnet)] GET /myplex/account (12 live) TLS GZIP Signed-in Token (nicolaidenmark)
Nov 18, 2019 15:25:02.850 [0x7f5b0e7b8700] DEBUG - Completed: [192.168.1.47:55648] 200 GET /myplex/account (12 live) TLS GZIP 1ms 2600 bytes (pipelined: 2)
Nov 18, 2019 15:25:02.905 [0x7f5b0e7b8700] DEBUG - Completed: [192.168.1.47:55616] 200 GET /video/:/transcode/universal/decision
14

Not sure I understand this. My Plex Sony android app states Direct Play on both video and Audio.
Yet you are saying that my DTS sound needs transcoding?
I have several 1080P videos with DTS audio tracks that play without issues.

What should I do?

15

It is transcoding because of the subtitles.

Try it again with subtitles expressly turned off.

16

No difference.

I was under the impression that SRT subs were easy on Plex and didn’t need transcoding?

Judging by system stats, my NAS does not appear strained?

PNG
PNG690×724 123 KB
cpu%20nas
cpu%20nas1274×392 92.5 KB

17

These are the logs from spotless playing of Return of The Jedi 4K. Also DTS HD MA 2.0.

Why is it tinkering with the same audio track on Star Wars?Plex Media Server Logs_2019-11-18_18-49-01.zip (2.7 MB)

18

Subtitles are a single-threaded task.
On the Synology DS1815+ (for example), a single-CPU 100% commitment = 25% utilization value .

Using the approximate bandwidth values you show in the chart:

Actual bits transferred = 1.2 x Plex indicated bandwidth (This is the overhead of TCP/IP)

  1. 70 Mbps * 1.2 = 84 Mbps data transfer on the 100 Mbps link of the television. 83% utilization is a very common max utilization rate

  2. The spike is well above 100 Mbps of the adapter. If this is where the lag occurs then the answer is obvious. The entire segment of the spike above about 70 Mbps (85 Mbps actual signalling traffic) is above the 84 % I indicate above).

  3. I know of no televisions which have ethernet adapters above 100 Mbps.

Time duration as shown is from about T-27 -> T-20 seconds. A 7 second duration of insufficient data flow will take time to recover

It will transcode audio when:

  1. You select a track
  2. The player (tv) cannot play the available/selected track natively.
19

I Checked: I thought I had other DTS HD MA files. It looks like i don’t. DTS 6.1 (Original Star Wars Complete Saga Blu-Rays) plays just fine. But no matter what audio I choose on the Star Wars (1977) 4K, i stalls. Return of the Jedi (same audio codec plays just fine.

Could this be solved buy an external streaming device (e.g. Nividia Shield Pro 2019 (gigabit ethernet) running the plex player connected to the NAS and then HDMI to TV?

My NAS is the DS918+ with 4 gig of RAM.

20

Any way you increase the network bandwidth will likely solve the problem of those peaks.

The nVidia will @ 1 GbE will do it. Gigabit wifi, with a solid signal, 300n or better effective throughput will also do it (300n is effectively 150 Mbps raw data - half duplex – provided there is no contention for bandwidth). If going the WiFi route, 802.11ac will give breathing room but wired is definitely best.