Player Version#: 8.12
So here we go another topic about the Android TV App
tbh, it does from looking on this forum that the app is currently in a bit of a state… there seem to be so many issues both big and small…
In my case it seems to be related to passthrough issues.
I have reverted back to 8.11 and disabled passthrough as at least that way all the files will play.
If I run 8.12 I have these issues
Test 1:
Passthrough set to off
Attempt playback of 5.1 EAC3
Android App: Plays File
PMS Dashboard: Direct Play
AV: PCM
Attempt playback of any 5.1 DTS-HD
Android App: An error occurred
PMS Dashboard: nothing
AV: nothing
log file for decision
01-31 15:19:55.070 i: [MediaDecisionEngine] Decision: CanPlay: true CanDirectPlay: false CanDirectStreamVideo: true CanDirectStreamAudio: false CanDirectPlaySubtitle: false CanTranscodeSubtitle: false CanDisplayVideo: true Transcode Reason: DTS audio is not supported by the device
So that looks correct.
We See the result from FFMPEG:
01-31 15:20:00.067 i: [FFmpeg] [INFO] Input #0, hls, from '':
01-31 15:20:00.069 i: [FFmpeg] [INFO] Duration:
01-31 15:20:00.070 i: [FFmpeg] [INFO] 01:41:52.00
01-31 15:20:00.074 i: [FFmpeg] [INFO] , start:
01-31 15:20:00.077 i: [FFmpeg] [INFO] 10.000000
01-31 15:20:00.079 i: [FFmpeg] [INFO] , bitrate:
01-31 15:20:00.083 i: [FFmpeg] [INFO] N/A
01-31 15:20:00.125 i: [FFmpeg] [INFO]
01-31 15:20:00.127 i: [FFmpeg] [INFO] Program 0
01-31 15:20:00.130 i: [FFmpeg] [INFO] Metadata:
01-31 15:20:00.132 i: [FFmpeg] [INFO] variant_bitrate :
01-31 15:20:00.136 i: [FFmpeg] [INFO] 19122000
01-31 15:20:00.138 i: [FFmpeg] [INFO]
01-31 15:20:00.141 i: [FFmpeg] [INFO] Stream #0:0
01-31 15:20:00.144 i: [FFmpeg] [INFO] (eng)
01-31 15:20:00.148 i: [FFmpeg] [INFO] : Video: h264, none(progressive), 1920x1080
01-31 15:20:00.154 i: [FFmpeg] [INFO] ,
01-31 15:20:00.159 i: [FFmpeg] [INFO] 1k tbr,
01-31 15:20:00.161 i: [FFmpeg] [INFO] 1k tbn,
01-31 15:20:00.164 i: [FFmpeg] [INFO] 1k tbc
01-31 15:20:00.165 i: [FFmpeg] [INFO]
01-31 15:20:00.167 i: [FFmpeg] [INFO] Metadata:
01-31 15:20:00.169 i: [FFmpeg] [INFO] variant_bitrate :
01-31 15:20:00.170 i: [FFmpeg] [INFO] 19122000
01-31 15:20:00.171 i: [FFmpeg] [INFO]
01-31 15:20:00.175 i: [FFmpeg] [INFO] _STATISTICS_TAGS-eng:
01-31 15:20:00.177 i: [FFmpeg] [INFO] BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES SOURCE_ID
01-31 15:20:00.180 i: [FFmpeg] [INFO]
01-31 15:20:00.182 i: [FFmpeg] [INFO] BPS-eng :
01-31 15:20:00.185 i: [FFmpeg] [INFO] 16751551
01-31 15:20:00.187 i: [FFmpeg] [INFO]
01-31 15:20:00.193 i: [FFmpeg] [INFO] DURATION-eng :
01-31 15:20:00.195 i: [FFmpeg] [INFO] 01:41:51.314000000
01-31 15:20:00.197 i: [FFmpeg] [INFO]
01-31 15:20:00.199 i: [FFmpeg] [INFO] NUMBER_OF_FRAMES-eng:
01-31 15:20:00.202 i: [FFmpeg] [INFO] 146525
01-31 15:20:00.207 i: [FFmpeg] [INFO]
01-31 15:20:00.210 i: [FFmpeg] [INFO] NUMBER_OF_BYTES-eng:
01-31 15:20:00.214 i: [FFmpeg] [INFO] 12796749092
01-31 15:20:00.218 i: [FFmpeg] [INFO]
01-31 15:20:00.226 i: [FFmpeg] [INFO] SOURCE_ID-eng :
01-31 15:20:00.235 i: [FFmpeg] [INFO] 001011
01-31 15:20:00.239 i: [FFmpeg] [INFO]
01-31 15:20:00.244 i: [FFmpeg] [INFO] _STATISTICS_WRITING_APP-eng:
01-31 15:20:00.249 i: [FFmpeg] [INFO] mkvmerge v52.0.0 ('Secret For The Mad') 64-bit
01-31 15:20:00.253 i: [FFmpeg] [INFO]
01-31 15:20:00.257 i: [VideoStream] Dolby Vision is unsupported on this display or device.
01-31 15:20:00.263 i: [FFmpeg] [INFO] _STATISTICS_WRITING_DATE_UTC-eng:
01-31 15:20:00.275 i: [FFmpeg] [INFO] 2021-01-21 09:24:21
01-31 15:20:00.276 i: [FFmpeg] [INFO]
01-31 15:20:00.278 i: [FFmpeg] [INFO] ENCODER :
01-31 15:20:00.281 i: [FFmpeg] [INFO] Lavf58.27.104
01-31 15:20:00.290 i: [FFmpeg] [INFO]
01-31 15:20:00.292 i: [FFmpeg] [INFO] Stream #0:1
01-31 15:20:00.294 i: [FFmpeg] [INFO] (eng)
01-31 15:20:00.296 i: [FFmpeg] [INFO] : Audio: dts (DTS-HD MA), 48000 Hz, 5.1(side), s16p, 1536 kb/s
01-31 15:20:00.297 i: [FFmpeg] [INFO]
01-31 15:20:00.299 i: [FF] Container[hls] No Title - 0 kbps, 1 hr 41 min [CT: 0us, ST: 10000000us, SRT: -9223372036854775808us].
01-31 15:20:00.300 i: [FFmpeg] [INFO] Metadata:
01-31 15:20:00.302 i: [FFmpeg] [INFO] variant_bitrate :
01-31 15:20:00.304 i: [FFmpeg] [INFO] 19122000
01-31 15:20:00.309 i: [FFmpeg] [INFO]
01-31 15:20:00.311 i: [FFmpeg] [INFO] title :
01-31 15:20:00.312 i: [FFmpeg] [INFO] DTS-HD MA 5.1
01-31 15:20:00.315 i: [FFmpeg] [INFO]
01-31 15:20:00.316 i: [FFmpeg] [INFO] _STATISTICS_TAGS-eng:
01-31 15:20:00.317 i: [FFmpeg] [INFO] BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES SOURCE_ID
01-31 15:20:00.318 i: [FFmpeg] [INFO]
01-31 15:20:00.319 i: [FFmpeg] [INFO] BPS-eng :
01-31 15:20:00.320 i: [FFmpeg] [INFO] 2352459
01-31 15:20:00.321 i: [FFmpeg] [INFO]
01-31 15:20:00.321 i: [FFmpeg] [INFO] DURATION-eng :
01-31 15:20:00.325 i: [FFmpeg] [INFO] 01:41:51.318000000
01-31 15:20:00.326 i: [FFmpeg] [INFO]
01-31 15:20:00.328 i: [FFmpeg] [INFO] NUMBER_OF_FRAMES-eng:
01-31 15:20:00.330 i: [FFmpeg] [INFO] 572936
01-31 15:20:00.332 i: [FF] VideoStream[0-h264] 1920x1080 [SAR 1:1, DAR 1920:1080]. H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 - Unknown Profile (video/avc), 19.1 Mbps 1 hr 41 min 0.00fps, No Title / English (eng), 2 / 57 bytes, .
01-31 15:20:00.333 i: [FFmpeg] [INFO]
01-31 15:20:00.335 i: [FFmpeg] [INFO] NUMBER_OF_BYTES-eng:
01-31 15:20:00.336 i: [FFmpeg] [INFO] 1797078448
01-31 15:20:00.337 i: [FFmpeg] [INFO]
01-31 15:20:00.338 i: [FFmpeg] [INFO] SOURCE_ID-eng :
01-31 15:20:00.342 i: [FFmpeg] [INFO] 001100
01-31 15:20:00.343 i: [FFmpeg] [INFO]
01-31 15:20:00.345 i: [FFmpeg] [INFO] _STATISTICS_WRITING_APP-eng:
01-31 15:20:00.346 i: [FFmpeg] [INFO] mkvmerge v52.0.0 ('Secret For The Mad') 64-bit
01-31 15:20:00.347 i: [FFmpeg] [INFO]
01-31 15:20:00.349 i: [FFmpeg] [INFO] _STATISTICS_WRITING_DATE_UTC-eng:
01-31 15:20:00.350 i: [FFmpeg] [INFO] 2021-01-21 09:24:21
01-31 15:20:00.352 i: [FFmpeg] [INFO]
01-31 15:20:00.353 i: [FFmpeg] [INFO] Container metadata entries found: 0.
01-31 15:20:00.355 i: [FFmpeg] [INFO] Stream metadata entries found: 11.
01-31 15:20:00.358 i: [FFmpeg] [INFO] Stream metadata entries found: 11.
01-31 15:20:00.360 i: [FF] AudioStream[1-dts] 6 channels over 1551 (5.1(side)), 48000 Hz, 0,0 padding. DCA (DTS Coherent Acoustics) - DTS-HD Profile (audio/vnd.dts.hd), 19.1 Mbps -54 sec 0.00fps, DTS-HD MA 5.1 / English (eng), 0 bytes, .
As far as I can see that shows 5.1 (So 6 Channels)
So next comes exoplayer
01-31 15:20:00.362 i: [ExoPlayer][EventLogger] timeline [eventTime=5.61, mediaPos=0.00, window=0, period=0, periodCount=1, windowCount=1, reason=SOURCE_UPDATE
01-31 15:20:00.364 i: [ExoPlayer][EventLogger] period [6112.00]
01-31 15:20:00.365 i: [ExoPlayer][EventLogger] window [6112.00, true, false]
01-31 15:20:00.367 i: [ExoPlayer][EventLogger] ]
01-31 15:20:00.368 i: [Player][ExoPlayer] onTimelineChanged (Position: 0 ms, Duration: 6112000 ms)
01-31 15:20:00.369 i: [FF][MediaPeriod] Selecting tracks.
01-31 15:20:00.370 i: [LoadControl] Assumed Bitrate: 400000, Segment Count: 46875
01-31 15:20:00.371 i: [LoadControl] Detected memory pressure, reducing segment count to fit available memory: 512 MB
01-31 15:20:00.374 i: [LoadControl] Final Segment count: 1638 (102 MB / 4 Seconds)
01-31 15:20:00.376 i: [ExoPlayer][EventLogger] videoEnabled [eventTime=5.62, mediaPos=0.00, window=0, period=0]
01-31 15:20:00.377 i: [ExoPlayer][EventLogger] audioEnabled [eventTime=5.62, mediaPos=0.00, window=0, period=0]
01-31 15:20:00.382 i: [ExoPlayer][EventLogger] tracks [eventTime=5.62, mediaPos=0.00, window=0, period=0
01-31 15:20:00.383 i: [ExoPlayer][EventLogger] Plex.Video.Renderer []
01-31 15:20:00.385 i: [ExoPlayer][EventLogger] MediaCodecVideoRenderer [
01-31 15:20:00.386 i: [ExoPlayer][EventLogger] Group:0, adaptive_supported=N/A [
01-31 15:20:00.387 i: [ExoPlayer][EventLogger] [X] Track:0, id=0, mimeType=video/avc, bitrate=19122000, codecs=h264, res=1920x1080, fps=0.0, supported=YES
01-31 15:20:00.388 i: [ExoPlayer][EventLogger] ]
01-31 15:20:00.394 i: [ExoPlayer][EventLogger] Metadata [
01-31 15:20:00.396 i: [ExoPlayer][EventLogger] original_init_data: (Value: 0 Buffer: 133 bytes)
01-31 15:20:00.397 i: [ExoPlayer][EventLogger] scantype_progressive: (Value: 1 Buffer: 0 bytes)
01-31 15:20:00.400 i: [ExoPlayer][EventLogger] ]
01-31 15:20:00.402 i: [ExoPlayer][EventLogger] ]
01-31 15:20:00.404 i: [ExoPlayer][EventLogger] Plex.Audio.Renderer [
01-31 15:20:00.407 i: [ExoPlayer][EventLogger] Group:0, adaptive_supported=N/A [
01-31 15:20:00.411 i: [ExoPlayer][EventLogger] [X] Track:0, id=1, mimeType=audio/vnd.dts.hd, bitrate=19122000, codecs=dts, channels=6, sample_rate=48000, language=english, supported=YES
01-31 15:20:00.417 i: [ExoPlayer][EventLogger] ]
01-31 15:20:00.418 i: [ExoPlayer][EventLogger] Metadata [
01-31 15:20:00.420 i: [ExoPlayer][EventLogger] block_align: (Value: 0 Buffer: 0 bytes)
01-31 15:20:00.421 i: [ExoPlayer][EventLogger] channel_layout: (Value: 1551 Buffer: 0 bytes)
01-31 15:20:00.425 i: [ExoPlayer][EventLogger] original_init_data: (Value: 0 Buffer: 0 bytes)
01-31 15:20:00.429 i: [ExoPlayer][EventLogger] ]
01-31 15:20:00.433 i: [ExoPlayer][EventLogger] ]
01-31 15:20:00.435 i: [ExoPlayer][EventLogger] MediaCodecAudioRenderer []
01-31 15:20:00.440 i: [ExoPlayer][EventLogger] TextRenderer []
01-31 15:20:00.442 i: [ExoPlayer][EventLogger] MetadataRenderer []
01-31 15:20:00.444 i: [ExoPlayer][EventLogger] ]
01-31 15:20:00.446 i: [Player][ExoPlayer] Setting initial track selection...
Still shows 6 Channels
Then we get WARN message from FFMPEG
01-31 15:20:00.448 i: [FFmpeg] [WARN] [dca @ 0x838ee000] Channel layout '7.1' with 8 channels does not match specified number of channels 6: ignoring specified channel layout
Why is it doing that?
And then it goes splat:
01-31 15:20:00.450 i: [TrackSelectorHelper] Resetting (Video: 1, Audio: 1, Text: 0)
01-31 15:20:00.451 i: [TrackSelectorHelper] Type: 2 Auto
01-31 15:20:00.453 i: [TrackSelectorHelper] Type: 1 Auto
01-31 15:20:00.455 i: [TrackSelectorHelper] Type: 3 Disabled
01-31 15:20:00.456 i: [Player][ExoPlayer] Switching video surface to use MediaCodec surface.
01-31 15:20:00.458 i: [Player] onSurfaceChangeRequested
01-31 15:20:00.459 i: [ExoPlayer][EventLogger] videoInputFormat [eventTime=5.68, mediaPos=0.00, window=0, period=0, id=0, mimeType=video/avc, bitrate=19122000, codecs=h264, res=1920x1080, fps=0.0]
01-31 15:20:00.462 i: [ExoPlayer][EventLogger] audioDecoderInitialized [eventTime=5.70, mediaPos=0.00, window=0, period=0, Plex.Audio.DCA]
01-31 15:20:00.465 i: [ExoPlayer][EventLogger] audioInputFormat [eventTime=5.71, mediaPos=0.00, window=0, period=0, id=1, mimeType=audio/vnd.dts.hd, bitrate=19122000, codecs=dts, channels=6, sample_rate=48000, language=english]
01-31 15:20:00.467 i: [TranscodeSession] Updating session status
01-31 15:20:00.470 i: Fetching [method:GET] https://144-76-69-138.05bc013c0bc94db69cdf0eb6544864a7.plex.direct:32400/transcode/sessions/2e99d387a143e03a-com-plexapp-android?X-Plex-Token=...-Vxt
01-31 15:20:00.471 i: [ExoPlayer][EventLogger] surfaceSize [eventTime=5.74, mediaPos=0.00, window=0, period=0, 1920, 1080]
01-31 15:20:00.472 i: [TranscodeSession] Resolution: -1x-1, Video: Remux, Audio: Remux, Speed: 50.900002, Throttled: false
01-31 15:20:00.490 i: [ExoPlayer][EventLogger] videoDecoderInitialized [eventTime=6.00, mediaPos=0.00, window=0, period=0, OMX.MTK.VIDEO.DECODER.AVC]
01-31 15:20:00.498 i: [ExoPlayer][EventLogger] videoDisabled [eventTime=6.01, mediaPos=0.00, window=0, period=0]
01-31 15:20:00.500 i: [ExoPlayer][EventLogger] audioDisabled [eventTime=6.01, mediaPos=0.00, window=0, period=0]
01-31 15:20:00.530 i: [FF][MediaPeriod] Releasing.
01-31 15:20:00.532 i: [FF] Loading canceled (released: true)
01-31 15:20:00.536 i: [FFmpeg] [INFO] Demuxer has been successfully interupted.
01-31 15:20:00.541 i: [FF] Releasing demuxer.
01-31 15:20:00.544 i: [FFmpeg] [INFO] Demuxer closing.
01-31 15:20:00.550 e: [ExoPlayer][EventLogger] playerFailed [eventTime=6.05, mediaPos=0.00, window=0, period=0
com.google.android.exoplayer2.ExoPlaybackException: Plex.Audio.Renderer error, index=2, format=Format(1, null, null, audio/vnd.dts.hd, dts, 19122000, english, [-1, -1, -1.0], [6, 48000]), format_supported=YES
at com.google.android.exoplayer2.l0.handleMessage(SourceFile:44)
at android.os.Handler.dispatchMessage(Handler.java:101)
at android.os.Looper.loop(Looper.java:164)
at android.os.HandlerThread.run(HandlerThread.java:65)
Caused by: com.google.android.exoplayer2.audio.AudioSink$InitializationException: AudioTrack init failed: 0, Config(48000, 252, 196992)
at com.google.android.exoplayer2.audio.DefaultAudioSink$d.a(SourceFile:4)
at com.google.android.exoplayer2.audio.DefaultAudioSink.D(SourceFile:2)
at com.google.android.exoplayer2.audio.DefaultAudioSink.Q(SourceFile:2)
at com.google.android.exoplayer2.audio.DefaultAudioSink.k(SourceFile:16)
at com.google.android.exoplayer2.audio.y.drainOutputBuffer(SourceFile:24)
at com.google.android.exoplayer2.audio.y.render(SourceFile:16)
at com.google.android.exoplayer2.l0.n(SourceFile:14)
at com.google.android.exoplayer2.l0.handleMessage(SourceFile:24)
... 3 more
Suppressed: com.google.android.exoplayer2.audio.AudioSink$InitializationException: AudioTrack init failed: 0, Config(48000, 252, 196992)
... 11 more
]
01-31 15:20:00.557 e: [Player][ExoPlayer] Playback error detected
01-31 15:20:00.564 i: [Player][Timeline] Handling player error
01-31 15:20:00.565 i: [Player][Timeline] Playback stopped (error: true)
01-31 15:20:00.567 i: [Player][Timeline] Player error not known by server, reporting original player error (or a playback interrupted one)
01-31 15:20:00.570 i: [Player] Error reported: UnknownError (null)
01-31 15:20:00.574 i: [FFmpeg] [INFO] Demuxer has been interupted...
01-31 15:20:00.579 i: [Player][Timeline] Playback stopped (error: false)
01-31 15:20:00.589 i: [FFmpeg] [INFO] Demuxer has been closed, interrupting FFmpeg...
01-31 15:20:00.594 e: An exception occurred: com.google.android.exoplayer2.ExoPlaybackException: Plex.Audio.Renderer error, index=2, format=Format(1, null, null, audio/vnd.dts.hd, dts, 19122000, english, [-1, -1, -1.0], [6, 48000]), format_supported=YES
01-31 15:20:00.595 i: [FFmpeg] [INFO] Demuxer has been interupted...
01-31 15:20:00.597 e: Stacktrace: com.google.android.exoplayer2.ExoPlaybackException: Plex.Audio.Renderer error, index=2, format=Format(1, null, null, audio/vnd.dts.hd, dts, 19122000, english, [-1, -1, -1.0], [6, 48000]), format_supported=YES
at com.google.android.exoplayer2.l0.handleMessage(SourceFile:44)
at android.os.Handler.dispatchMessage(Handler.java:101)
at android.os.Looper.loop(Looper.java:164)
at android.os.HandlerThread.run(HandlerThread.java:65)
Caused by: com.google.android.exoplayer2.audio.AudioSink$InitializationException: AudioTrack init failed: 0, Config(48000, 252, 196992)
at com.google.android.exoplayer2.audio.DefaultAudioSink$d.a(SourceFile:4)
at com.google.android.exoplayer2.audio.DefaultAudioSink.D(SourceFile:2)
at com.google.android.exoplayer2.audio.DefaultAudioSink.Q(SourceFile:2)
at com.google.android.exoplayer2.audio.DefaultAudioSink.k(SourceFile:16)
at com.google.android.exoplayer2.audio.y.drainOutputBuffer(SourceFile:24)
at com.google.android.exoplayer2.audio.y.render(SourceFile:16)
at com.google.android.exoplayer2.l0.n(SourceFile:14)
at com.google.android.exoplayer2.l0.handleMessage(SourceFile:24)
... 3 more
Suppressed: com.google.android.exoplayer2.audio.AudioSink$InitializationException: AudioTrack init failed: 0, Config(48000, 252, 196992)
... 11 more
01-31 15:20:00.600 i: [FFmpeg] [INFO] Demuxer has been closed, interrupting FFmpeg...
01-31 15:20:00.602 i: [TranscodeSession] Stopping...
01-31 15:20:00.603 i: [Player][Error] Displaying player error...
01-31 15:20:00.605 i: [FFmpeg] [INFO] Demuxer has been interupted...
01-31 15:20:00.609 i: [DialogBehaviour] Showing dialog.
01-31 15:20:00.610 i: [FFmpeg] [INFO] Demuxer has been closed, interrupting FFmpeg...
01-31 15:20:00.612 i: [ExoPlayer][EventLogger] loading [eventTime=6.09, mediaPos=0.00, window=0, period=0, false]
01-31 15:20:00.615 i: [ExoPlayer][EventLogger] state [eventTime=6.10, mediaPos=0.00, window=0, period=0, IDLE]
01-31 15:20:00.620 i: [BufferHelper] Assumed Bitrate: 400000, Segment Count: 46875
01-31 15:20:00.621 i: [BufferHelper] Detected memory pressure, reducing segment count to fit available memory: 512
01-31 15:20:00.626 i: [BufferHelper] Segment count: 3276
01-31 15:20:00.627 i: [TranscodeSession] Notifying server that we've stopped
Surely it should tell PMS to transcode the audio?
Test 2:
Passthrough set to HDMI
Attempt playback of 5.1 EAC3
Android App: Plays File
PMS Dashboard: Direct Play
AV: Dolby Digital+
Attempt playback of Remux file with 5.1 DTS-HD
Android App: Plays File
PMS Dashboard: Direct Play
AV: DTS
Another File
Attempt playback of Encoded file with 5.1 DTS-HD
Android App: Plays File audio plays for a few seconds then audio stops
PMS Dashboard: Direct Play
AV: DTS for a few seconds then nothing
(I am guessing this may be an issue with this particular file, but see nothing obvious in the log to indicate a problem)
If I change the passthrough to Optical, then :
Another File
Attempt playback of Encoded file with 5.1 DTS-HD
Android App: Plays File
PMS Dashboard: Direct Stream
AV: DTS
But changing to optical means files EAC3 now gets transcoded
With 8.11 I still get issues with the problem file if passthrough is enabled.
But if I disable passthrough on 8.11 then all the files play.
