5.7.2 seems to have an issue where 4K HEVC videos that previously played via direct stream no longer do so. Instead, the stream is forcefully transcoded to 1080p despite the app stating it’s currently on Original quality (4K).
I enabled debugging in the app and saw the following.
For a 4K HEVC video where no video transcoding happens (ignore audio):
Jan 24, 2021 11:52:19.429 [9224] DEBUG - Auth: authenticated user 1 as Snowknight26
Jan 24, 2021 11:52:19.429 [3476] DEBUG - Request: [192.168.1.203:49134 (Subnet)] GET /video/:/transcode/universal/decision?directPlay=0&directStream=1&directStreamAudio=0&protocol=http&fastSeek=1&path=%2Flibrary%2Fmetadata%2F201906&session=4j04wirrz4i9y4bxxc35ym68&mediaIndex=0&partIndex=0&mediaBufferSize=50000&hasMDE=1&subtitleSize=100&videoQuality=100&videoResolution=3840x2160&audioBoost=100©ts=0&location=lan (16 live) TLS GZIP Signed-in Token (Snowknight26)
Jan 24, 2021 11:52:19.429 [3476] DEBUG - [Transcode] Found session GUID of 4j04wirrz4i9y4bxxc35ym68 in session start.
Jan 24, 2021 11:52:19.430 [3476] DEBUG - [Transcode] TranscodeUniversalRequest: using augmented profile Generic
Jan 24, 2021 11:52:19.430 [3476] DEBUG - [Transcode] Downloading document http://127.0.0.1:32400/library/metadata/201906?includeBandwidths=1&offset=-1&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=43675ca6-5a63-429b-9b84-81fe9ce242a9
Jan 24, 2021 11:52:19.430 [3476] DEBUG - [Transcode] Auth: authenticated user 1 as Snowknight26
Jan 24, 2021 11:52:19.433 [3476] DEBUG - [Transcode] We're going to try to auto-select an audio stream for account 1.
Jan 24, 2021 11:52:19.433 [3476] DEBUG - [Transcode] Selecting best audio stream for part ID 172208 (autoselect: 0 language: en)
Jan 24, 2021 11:52:19.433 [3476] DEBUG - [Transcode] We're going to try to auto-select a subtitle.
Jan 24, 2021 11:52:19.433 [3476] DEBUG - [Transcode] Audio Stream: 257520, Subtitle Stream: -1
Jan 24, 2021 11:52:19.434 [3476] DEBUG - [Transcode] Codecs: testing aac_mf (encoder)
Jan 24, 2021 11:52:19.434 [3476] INFO - [Transcode] [FFMPEG] - MFT name: 'Microsoft AAC Audio Encoder MFT'
Jan 24, 2021 11:52:19.435 [3476] DEBUG - [Transcode] MDE: Selected protocol http; container: mkv
Jan 24, 2021 11:52:19.435 [3476] DEBUG - [Transcode] MDE: analyzing media item 171680
Jan 24, 2021 11:52:19.435 [3476] DEBUG - [Transcode] MDE: The Birds (1963): Direct Play is disabled
Jan 24, 2021 11:52:19.435 [3476] DEBUG - [Transcode] MDE: The Birds (1963): no direct play video profile exists for http/mkv/hevc
Jan 24, 2021 11:52:19.435 [3476] DEBUG - [Transcode] MDE: The Birds (1963): no direct play video profile exists for http/mkv/hevc/flac
Jan 24, 2021 11:52:19.435 [3476] DEBUG - [Transcode] MDE: The Birds (1963): Audio Direct Streaming is disabled, so video's audio stream will be transcoded
Jan 24, 2021 11:52:19.435 [3476] DEBUG - [Transcode] Codecs: testing aac_mf (encoder)
Jan 24, 2021 11:52:19.436 [3476] INFO - [Transcode] [FFMPEG] - MFT name: 'Microsoft AAC Audio Encoder MFT'
Jan 24, 2021 11:52:19.436 [3476] DEBUG - [Transcode] MDE: Cannot direct stream audio stream due to profile or setting limitations
Jan 24, 2021 11:52:19.436 [3476] DEBUG - [Transcode] MDE: The Birds (1963): selected media 0 / 171680
Jan 24, 2021 11:52:19.437 [3476] DEBUG - [Transcode] Streaming Resource: Added session 0x5173258:43675ca6-5a63-429b-9b84-81fe9ce242a9
Jan 24, 2021 11:52:19.437 [3476] DEBUG - [Transcode] Streaming Resource: Reached Decision id=201906 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=171680 part=(id=172208 decision=transcode container=mkv protocol=http streams=(Video=(id=257519 decision=copy width=3840 height=2160) Audio=(id=257520 decision=transcode bitrate=258 encoder=aac_mf channels=2 rate=48000))))
Jan 24, 2021 11:52:19.437 [9220] DEBUG - Completed: [192.168.1.203:49134] 200 GET /video/:/transcode/universal/decision?directPlay=0&directStream=1&directStreamAudio=0&protocol=http&fastSeek=1&path=%2Flibrary%2Fmetadata%2F201906&session=4j04wirrz4i9y4bxxc35ym68&mediaIndex=0&partIndex=0&mediaBufferSize=50000&hasMDE=1&subtitleSize=100&videoQuality=100&videoResolution=3840x2160&audioBoost=100©ts=0&location=lan (16 live) TLS GZIP 8ms 3639 bytes (pipelined: 60)
Jan 24, 2021 11:52:19.610 [9220] DEBUG - Auth: authenticated user 1 as Snowknight26
Jan 24, 2021 11:52:19.612 [3476] DEBUG - Request: [192.168.1.203:49134 (Subnet)] POST /log (16 live) TLS GZIP Signed-in Token (Snowknight26)
Jan 24, 2021 11:52:19.612 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] Navigating to screen: PreplayScreen
Jan 24, 2021 11:52:19.612 [3476] DEBUG - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [HubListRefresher] Cropped On Deck, index: 0, size: 10
Jan 24, 2021 11:52:19.612 [3476] DEBUG - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [HubListRefresher] Cropped Recently Added Movies, index: 25, size: 30
Jan 24, 2021 11:52:19.612 [3476] DEBUG - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [HubListRefresher] Cropped Recently Added TV, index: 0, size: 10
Jan 24, 2021 11:52:19.612 [3476] DEBUG - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [HubListRefresher] Cropped Recently Added Music, index: 0, size: 10
Jan 24, 2021 11:52:19.612 [3476] DEBUG - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [HubListRefresher] Cropped Recently Added Photos, index: 0, size: 10
Jan 24, 2021 11:52:19.612 [3476] DEBUG - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [HubListRefresher] Cropped Recently Added Videos, index: 0, size: 10
Jan 24, 2021 11:52:19.612 [3476] DEBUG - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [HubListRefresher] Cropped Recently Added Movies, index: 0, size: 10
Jan 24, 2021 11:52:19.612 [3476] DEBUG - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [HubListRefresher] Cropped Recently Added TV, index: 0, size: 10
Jan 24, 2021 11:52:19.612 [3476] DEBUG - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [HubListRefresher] Cropped Recently Added Music, index: 0, size: 10
Jan 24, 2021 11:52:19.612 [3476] DEBUG - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [HubListRefresher] Cropped Recently Added Photos, index: 0, size: 10
Jan 24, 2021 11:52:19.612 [3476] DEBUG - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [HubListRefresher] Cropped Recently Added Videos, index: 0, size: 10
Jan 24, 2021 11:52:19.612 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] Navigated to screen: PreplayScreen
Jan 24, 2021 11:52:19.612 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] Navigating to screen: VisualMediaPlaybackScreen
Jan 24, 2021 11:52:19.612 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [PlaybackMetrics] Attaching playback metrics to PlayerController
Jan 24, 2021 11:52:19.612 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [SubtitleConnectionManager] Subtitle connection ignored. App is not rendering subtitles.
Jan 24, 2021 11:52:19.612 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [ServerConnectionTestManager] Stopped
Jan 24, 2021 11:52:19.612 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] Navigated to screen: VisualMediaPlaybackScreen
Jan 24, 2021 11:52:19.612 [3476] WARN - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] _onOnPlayPauseInvokedChanged is not defined for @autoCallback property: onPlayPauseInvoked
Jan 24, 2021 11:52:19.612 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [MDE] Application decision, directPlay: false, directStreamVideo: true, directStreamAudio: false
Jan 24, 2021 11:52:19.612 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] Cannot direct play with protocol: http, codec: flac. Not found in profile.
Jan 24, 2021 11:52:19.612 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] Cannot direct stream with protocol: http, codec: flac. Not found in profile.
Jan 24, 2021 11:52:19.613 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] videoStream: {
"id": 257519,
"streamType": 1,
"default": true,
"codec": "hevc",
"index": 0,
"bitrate": 63063,
"bitDepth": 10,
"chromaSubsampling": "4:2:0",
"codedHeight": 2160,
"codedWidth": 3840,
"colorPrimaries": "bt2020",
"colorRange": "tv",
"colorSpace": "bt2020nc",
"colorTrc": "smpte2084",
"frameRate": 23.976,
"height": 2160,
"level": 153,
"profile": "main 10",
"refFrames": 1,
"width": 3840,
"displayTitle": "4K (HEVC Main 10 HDR)",
"extendedDisplayTitle": "4K (HEVC Main 10 HDR)"
}
Jan 24, 2021 11:52:19.613 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] audioStream: {
"id": 257520,
"streamType": 2,
"selected": true,
"default": true,
"codec": "flac",
"index": 1,
"channels": 2,
"language": "English",
"languageCode": "eng",
"audioChannelLayout": "stereo",
"bitDepth": 24,
"samplingRate": 48000,
"title": "Dual Mono",
"displayTitle": "English (FLAC Stereo)",
"extendedDisplayTitle": "Dual Mono (English FLAC Stereo)"
}
Jan 24, 2021 11:52:19.613 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] subtitleStream: none
Jan 24, 2021 11:52:19.613 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [MDE] Server decision, directPlay: false, directStreamVideo: true, directStreamAudio: false
Jan 24, 2021 11:52:19.613 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] reason: App cannot direct play this item. Direct play is disabled.
Jan 24, 2021 11:52:19.613 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] videoStream: {
"bitrate": "63063",
"codec": "hevc",
"colorPrimaries": "bt2020",
"colorTrc": "smpte2084",
"default": "1",
"displayTitle": "4K (HEVC Main 10 HDR)",
"extendedDisplayTitle": "4K (HEVC Main 10 HDR)",
"frameRate": "23.975999999999999",
"height": "2160",
"id": "257519",
"streamType": 1,
"width": "3840",
"decision": "copy",
"location": "direct"
}
Jan 24, 2021 11:52:19.613 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] audioStream: {
"bitrate": "258",
"bitrateMode": "cbr",
"channels": 2,
"codec": "aac",
"default": "1",
"displayTitle": "English (FLAC Stereo)",
"extendedDisplayTitle": "Dual Mono (English FLAC Stereo)",
"id": "257520",
"language": "English",
"languageCode": "eng",
"selected": "1",
"streamType": 2,
"decision": "transcode",
"location": "direct"
}
Jan 24, 2021 11:52:19.613 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] subtitleStream: none
Jan 24, 2021 11:52:19.613 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] augmentations with profile: Generic
Jan 24, 2021 11:52:19.613 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-transcode-target streaming videoProfile http mkv h264,hevc,vp9 aac,ac3,eac3,truehd,dca
Jan 24, 2021 11:52:19.613 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation videoAudioCodec aac upperBound audio.channels 2
Jan 24, 2021 11:52:19.613 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation videoAudioCodec ac3 notMatch audio.channels 7
Jan 24, 2021 11:52:19.613 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation videoAudioCodec eac3 notMatch audio.channels 7
Jan 24, 2021 11:52:19.613 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation videoAudioCodec truehd notMatch audio.channels 7
Jan 24, 2021 11:52:19.613 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation streaming videoAudioCodec hevc http match video.orientation 0
Jan 24, 2021 11:52:19.613 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation videoCodec h264 upperBound video.level 41
Jan 24, 2021 11:52:19.613 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation streaming videoTranscodeTarget h264 http upperBound video.bitDepth 8
Jan 24, 2021 11:52:19.613 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation streaming videoTranscodeTarget h264 http upperBound video.width 1920
Jan 24, 2021 11:52:19.613 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation streaming videoTranscodeTarget h264 http upperBound video.height 1080
Jan 24, 2021 11:52:19.613 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation streaming videoTranscodeTarget hevc http upperBound video.bitDepth 10
Jan 24, 2021 11:52:19.613 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation streaming videoTranscodeTarget hevc http upperBound video.width 3840
Jan 24, 2021 11:52:19.613 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation streaming videoTranscodeTarget hevc http upperBound video.height 2160
Jan 24, 2021 11:52:19.613 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation streaming videoTranscodeTarget hevc http match video.colorTrc bt709|bt470m|bt470bg|smpte170m|smpte240m|bt2020-10|smpte2084
Jan 24, 2021 11:52:19.613 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation streaming videoTranscodeTarget vp9 http upperBound video.bitDepth 8
Jan 24, 2021 11:52:19.613 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation streaming videoTranscodeTarget vp9 http upperBound video.width 3840
Jan 24, 2021 11:52:19.613 [3476] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation streaming videoTranscodeTarget vp9 http upperBound video.height 2160
Jan 24, 2021 11:52:19.614 [3476] DEBUG - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [PlaybackSessionController] Setting up player for playback
Jan 24, 2021 11:52:19.614 [3476] DEBUG - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [PlaybackSessionController] Creating player
Based on the above, the application (Plex for Vizio 5.7.2) asked the server to direct play the video, the server responded with the OK.
Jan 24, 2021 11:52:19.437 [3476] DEBUG - [Transcode] Streaming Resource: Reached Decision id=201906 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=171680 part=(id=172208 decision=transcode container=mkv protocol=http streams=(Video=(id=257519 decision=copy width=3840 height=2160) Audio=(id=257520 decision=transcode bitrate=258 encoder=aac_mf channels=2 rate=48000))))
Moving onto one that previously worked with direct play:
Jan 24, 2021 11:53:12.666 [3324] WARN - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [Player] A critical HTML media error occurred: 4 (Codec); the player cannot recover
Jan 24, 2021 11:53:12.666 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [PlaybackSessionController] playback state changed to: stopped
Jan 24, 2021 11:53:12.666 [3324] ERROR - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] Playback error occurred. Reason: Unknown, An unknown player error occured. code: 4, message: Codec
Jan 24, 2021 11:53:12.666 [3324] WARN - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [PlaybackSessionController] able to retry playback, {
"metadataSourceUri": "server://bfba830750fbec3b95841dd1af6456f1f55b937b/com.plexapp.plugins.library/library/metadata/202691",
"retries": 1,
"allowDirectPlay": false,
"allowDirectStream": true
}
Jan 24, 2021 11:53:12.666 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [MDE] Application decision, directPlay: false, directStreamVideo: true, directStreamAudio: true
Jan 24, 2021 11:53:12.666 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] Application disabled direct play.
Jan 24, 2021 11:53:12.667 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] videoStream: {
"id": 267629,
"streamType": 1,
"default": true,
"codec": "hevc",
"index": 0,
"bitrate": 60564,
"bitDepth": 10,
"chromaSubsampling": "4:2:0",
"codedHeight": 2160,
"codedWidth": 3840,
"colorPrimaries": "bt2020",
"colorRange": "tv",
"colorSpace": "bt2020nc",
"colorTrc": "smpte2084",
"frameRate": 23.976,
"height": 2160,
"level": 153,
"profile": "main 10",
"refFrames": 1,
"width": 3840,
"displayTitle": "4K (HEVC Main 10 HDR)",
"extendedDisplayTitle": "4K (HEVC Main 10 HDR)"
}
Jan 24, 2021 11:53:12.667 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] audioStream: {
"id": 267630,
"streamType": 2,
"selected": true,
"default": true,
"codec": "dca",
"index": 1,
"channels": 6,
"bitrate": 1536,
"language": "English",
"languageCode": "eng",
"audioChannelLayout": "5.1(side)",
"bitDepth": 24,
"profile": "ma",
"samplingRate": 48000,
"displayTitle": "English (DTS-HD MA 5.1)",
"extendedDisplayTitle": "English (DTS-HD MA 5.1)"
}
Jan 24, 2021 11:53:12.667 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] subtitleStream: none
Jan 24, 2021 11:53:12.667 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [MDE] Server decision, directPlay: false, directStreamVideo: true, directStreamAudio: true
Jan 24, 2021 11:53:12.667 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] reason: App cannot direct play this item. Direct play is disabled.
Jan 24, 2021 11:53:12.667 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] videoStream: {
"bitrate": "60564",
"codec": "hevc",
"colorPrimaries": "bt2020",
"colorTrc": "smpte2084",
"default": "1",
"displayTitle": "4K (HEVC Main 10 HDR)",
"extendedDisplayTitle": "4K (HEVC Main 10 HDR)",
"frameRate": "23.975999999999999",
"height": "2160",
"id": "267629",
"streamType": 1,
"width": "3840",
"decision": "copy",
"location": "direct"
}
Jan 24, 2021 11:53:12.667 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] audioStream: {
"bitrate": "1536",
"bitrateMode": "cbr",
"channels": 6,
"codec": "dca",
"default": "1",
"displayTitle": "English (DTS-HD MA 5.1)",
"extendedDisplayTitle": "English (DTS-HD MA 5.1)",
"id": "267630",
"language": "English",
"languageCode": "eng",
"selected": "1",
"streamType": 2,
"decision": "copy",
"location": "direct"
}
Jan 24, 2021 11:53:12.667 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] subtitleStream: none
Jan 24, 2021 11:53:12.667 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] augmentations with profile: Generic
Jan 24, 2021 11:53:12.667 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-transcode-target streaming videoProfile http mkv h264,hevc,vp9 aac,ac3,eac3,truehd,dca
Jan 24, 2021 11:53:12.667 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation videoAudioCodec aac upperBound audio.channels 2
Jan 24, 2021 11:53:12.667 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation videoAudioCodec ac3 notMatch audio.channels 7
Jan 24, 2021 11:53:12.667 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation videoAudioCodec eac3 notMatch audio.channels 7
Jan 24, 2021 11:53:12.667 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation videoAudioCodec truehd notMatch audio.channels 7
Jan 24, 2021 11:53:12.667 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation streaming videoAudioCodec hevc http match video.orientation 0
Jan 24, 2021 11:53:12.667 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation videoCodec h264 upperBound video.level 41
Jan 24, 2021 11:53:12.667 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation streaming videoTranscodeTarget h264 http upperBound video.bitDepth 8
Jan 24, 2021 11:53:12.667 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation streaming videoTranscodeTarget h264 http upperBound video.width 1920
Jan 24, 2021 11:53:12.667 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation streaming videoTranscodeTarget h264 http upperBound video.height 1080
Jan 24, 2021 11:53:12.667 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation streaming videoTranscodeTarget hevc http upperBound video.bitDepth 10
Jan 24, 2021 11:53:12.667 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation streaming videoTranscodeTarget hevc http upperBound video.width 3840
Jan 24, 2021 11:53:12.667 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation streaming videoTranscodeTarget hevc http upperBound video.height 2160
Jan 24, 2021 11:53:12.667 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation streaming videoTranscodeTarget hevc http match video.colorTrc bt709|bt470m|bt470bg|smpte170m|smpte240m|bt2020-10|smpte2084
Jan 24, 2021 11:53:12.667 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation streaming videoTranscodeTarget vp9 http upperBound video.bitDepth 8
Jan 24, 2021 11:53:12.668 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation streaming videoTranscodeTarget vp9 http upperBound video.width 3840
Jan 24, 2021 11:53:12.668 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] add-limitation streaming videoTranscodeTarget vp9 http upperBound video.height 2160
Jan 24, 2021 11:53:12.668 [3324] DEBUG - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [PlaybackSessionController] Setting up player for playback
Jan 24, 2021 11:53:12.668 [3324] DEBUG - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [PlaybackSessionController] Creating player
Jan 24, 2021 11:53:12.668 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [playerCreator] Buffer size limit is 33.4 MB (23.4 MB). Limiting buffer goal to 5 seconds. Limiting bandwidth to 4.7 MB/s
Jan 24, 2021 11:53:12.668 [3324] DEBUG - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [PlaybackSessionController] Created player
Jan 24, 2021 11:53:12.668 [3324] DEBUG - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [PlaybackSessionController] Set player
Jan 24, 2021 11:53:12.668 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [SubtitleConnectionManager] Subtitle connection ignored. App is not rendering subtitles.
Jan 24, 2021 11:53:12.668 [3324] DEBUG - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [PlaybackSessionController] Checking for Ad
Jan 24, 2021 11:53:12.668 [3324] DEBUG - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [PlaybackSessionController] Finished checking for Ad
Jan 24, 2021 11:53:12.668 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [PlaybackSessionController] playback state changed to: buffering
Jan 24, 2021 11:53:12.668 [3324] DEBUG - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [PlaybackSessionController] Opening playback
Jan 24, 2021 11:53:12.668 [3324] WARN - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [Player] A critical HTML media error occurred: 4 (Codec); the player cannot recover
Jan 24, 2021 11:53:12.668 [3324] INFO - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [PlaybackSessionController] playback state changed to: stopped
Jan 24, 2021 11:53:12.668 [3324] ERROR - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] Playback error occurred. Reason: Unknown, An unknown player error occured. code: 4, message: Codec
Jan 24, 2021 11:53:12.668 [3324] WARN - [Plex for Vizio] [Snowknight26:xugc54i4ecj84qou7w33d3oz] [PlaybackSessionController] able to retry playback, {
"metadataSourceUri": "server://bfba830750fbec3b95841dd1af6456f1f55b937b/com.plexapp.plugins.library/library/metadata/202691",
"retries": 2,
"allowDirectPlay": false,
"allowDirectStream": false
}
Jan 24, 2021 11:53:12.668 [9224] DEBUG - Completed: [192.168.1.203:49134] 200 POST /log (18 live) TLS GZIP 1ms 274 bytes (pipelined: 88)
Jan 24, 2021 11:53:12.673 [9224] DEBUG - Auth: authenticated user 1 as Snowknight26
Jan 24, 2021 11:53:12.673 [3324] DEBUG - Request: [192.168.1.203:49134 (Subnet)] GET /library/metadata/202691?checkFiles=1&includeChapters=1&includeMarkers=1 (18 live) TLS GZIP Signed-in Token (Snowknight26)
Jan 24, 2021 11:53:12.677 [3324] DEBUG - It took 0.000000 ms to retrieve 107 items.
Jan 24, 2021 11:53:12.680 [3324] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 24, 2021 11:53:12.680 [3324] DEBUG - Selecting best audio stream for part ID 173310 (autoselect: 0 language: en)
Jan 24, 2021 11:53:12.680 [3324] DEBUG - We're going to try to auto-select a subtitle.
Jan 24, 2021 11:53:12.680 [3324] DEBUG - Audio Stream: 267630, Subtitle Stream: -1
Jan 24, 2021 11:53:12.681 [9220] DEBUG - Completed: [192.168.1.203:49134] 200 GET /library/metadata/202691?checkFiles=1&includeChapters=1&includeMarkers=1 (18 live) TLS GZIP 7ms 5391 bytes (pipelined: 89)
Jan 24, 2021 11:53:12.754 [9220] DEBUG - Auth: authenticated user 1 as Snowknight26
Jan 24, 2021 11:53:12.754 [3324] DEBUG - Request: [192.168.1.203:49134 (Subnet)] GET /video/:/transcode/universal/decision?directPlay=0&directStream=0&directStreamAudio=0&protocol=http&fastSeek=1&path=%2Flibrary%2Fmetadata%2F202691&session=x1bwxirvh5arpt7gltk8u5qr&mediaIndex=0&partIndex=0&mediaBufferSize=50000&hasMDE=1&subtitleSize=100&videoQuality=100&videoResolution=3840x2160&audioBoost=100©ts=0&location=lan (18 live) TLS GZIP Signed-in Token (Snowknight26)
Jan 24, 2021 11:53:12.754 [3324] DEBUG - [Transcode] Found session GUID of x1bwxirvh5arpt7gltk8u5qr in session start.
Jan 24, 2021 11:53:12.755 [3324] DEBUG - [Transcode] TranscodeUniversalRequest: using augmented profile Generic
Jan 24, 2021 11:53:12.755 [3324] DEBUG - [Transcode] Downloading document http://127.0.0.1:32400/library/metadata/202691?includeBandwidths=1&offset=-1&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=43675ca6-5a63-429b-9b84-81fe9ce242a9
Jan 24, 2021 11:53:12.755 [3324] DEBUG - [Transcode] Auth: authenticated user 1 as Snowknight26
Jan 24, 2021 11:53:12.759 [3324] DEBUG - [Transcode] It took 0.000000 ms to retrieve 107 items.
Jan 24, 2021 11:53:12.760 [3324] DEBUG - [Transcode] We're going to try to auto-select an audio stream for account 1.
Jan 24, 2021 11:53:12.760 [3324] DEBUG - [Transcode] Selecting best audio stream for part ID 173310 (autoselect: 0 language: en)
Jan 24, 2021 11:53:12.760 [3324] DEBUG - [Transcode] We're going to try to auto-select a subtitle.
Jan 24, 2021 11:53:12.760 [3324] DEBUG - [Transcode] Audio Stream: 267630, Subtitle Stream: -1
Jan 24, 2021 11:53:12.761 [3324] DEBUG - [Transcode] Scaled up video bitrate to 272538Kbps based on 4.500000x fudge factor.
Jan 24, 2021 11:53:12.761 [3324] DEBUG - [Transcode] Scaled maximum bitrate for resolution reduction to 68134Kbps.
Jan 24, 2021 11:53:12.761 [3324] DEBUG - [Transcode] Codecs: testing aac_mf (encoder)
Jan 24, 2021 11:53:12.762 [3324] INFO - [Transcode] [FFMPEG] - MFT name: 'Microsoft AAC Audio Encoder MFT'
Jan 24, 2021 11:53:12.763 [3324] DEBUG - [Transcode] MDE: Selected protocol http; container: mkv
Jan 24, 2021 11:53:12.763 [3324] DEBUG - [Transcode] MDE: analyzing media item 172779
Jan 24, 2021 11:53:12.763 [3324] DEBUG - [Transcode] MDE: Tenet (2020): Direct Play is disabled
Jan 24, 2021 11:53:12.763 [3324] DEBUG - [Transcode] MDE: Tenet (2020): no direct play video profile exists for http/mkv/hevc
Jan 24, 2021 11:53:12.763 [3324] DEBUG - [Transcode] MDE: Tenet (2020): no direct play video profile exists for http/mkv/hevc/dca
Jan 24, 2021 11:53:12.763 [3324] DEBUG - [Transcode] MDE: Tenet (2020): Direct Streaming is disabled, so video stream will be transcoded
Jan 24, 2021 11:53:12.763 [3324] DEBUG - [Transcode] MDE: Tenet (2020): no remuxable profile found, so video stream will be transcoded
Jan 24, 2021 11:53:12.763 [3324] DEBUG - [Transcode] MDE: Cannot direct stream video stream due to profile or setting limitations
Jan 24, 2021 11:53:12.763 [3324] DEBUG - [Transcode] Scaled up video bitrate to 272538Kbps based on 4.500000x fudge factor.
Jan 24, 2021 11:53:12.763 [3324] DEBUG - [Transcode] Scaled maximum bitrate for resolution reduction to 68134Kbps.
Jan 24, 2021 11:53:12.763 [3324] DEBUG - [Transcode] MDE: Tenet (2020): Audio Direct Streaming is disabled, so video's audio stream will be transcoded
Jan 24, 2021 11:53:12.763 [3324] DEBUG - [Transcode] Codecs: testing aac_mf (encoder)
Jan 24, 2021 11:53:12.763 [3324] INFO - [Transcode] [FFMPEG] - MFT name: 'Microsoft AAC Audio Encoder MFT'
Jan 24, 2021 11:53:12.764 [3324] DEBUG - [Transcode] MDE: Cannot direct stream audio stream due to profile or setting limitations
Jan 24, 2021 11:53:12.764 [3324] DEBUG - [Transcode] MDE: Tenet (2020): selected media 0 / 172779
Jan 24, 2021 11:53:12.764 [3324] DEBUG - [Transcode] Streaming Resource: Adding session 0xafe17a0:43675ca6-5a63-429b-9b84-81fe9ce242a9 which is using transcoder slot. Used slots is now 1
Jan 24, 2021 11:53:12.764 [3324] DEBUG - [Transcode] Streaming Resource: Added session 0xafe17a0:43675ca6-5a63-429b-9b84-81fe9ce242a9
Jan 24, 2021 11:53:12.764 [3324] DEBUG - [Transcode] Streaming Resource: Reached Decision id=202691 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=172779 part=(id=173310 decision=transcode container=mkv protocol=http streams=(Video=(id=267629 decision=transcode bitrate=68134 encoder=libx264 width=1920 height=1080) Audio=(id=267630 decision=transcode bitrate=640 encoder=ac3 channels=6 rate=48000))))
That’s not good, error straight off the bat.
Nevertheless, Plex for Vizio tells the server “directStreamVideo: true, directStreamAudio: true”, server responds with “directStreamVideo: true, directStreamAudio: true”.
So… it shouldn’t be transcoding.
Yet, if we look at the very last line, we see:
Jan 24, 2021 11:53:12.764 [3324] DEBUG - [Transcode] Streaming Resource: Reached Decision id=202691 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=172779 part=(id=173310 decision=transcode container=mkv protocol=http streams=(Video=(id=267629 decision=transcode bitrate=68134 encoder=libx264 width=1920 height=1080) Audio=(id=267630 decision=transcode bitrate=640 encoder=ac3 channels=6 rate=48000))))
Huh? It’s transcoding both the video to 1080p H.264 and the audio to 640kbps DD? Both application and server OK’d the direct stream without transcoding just a second ago.
Probably has something to do with the error on the first line.
This happens on at least the following TVs I’ve been able to test:
Let me know if any more debug info is needed, though it seems easy to reproduce. At the moment a majority of 4K HEVC content cannot be played without transcoding at all.