No black out, screen looks like tv has paused, freeze frame when it hangs.
As mentioned previously mine is also now set to use the SD card, problem is still persisting however, albeit perhaps not as frequently.
As I say I have no issues with other streaming apps, plex uses much much much higher bitrate and runs perfect. I've just switched from a BBC One HD to channel to BBC News HD again and within a few minutes it has frozen again. I've submitted diagnosis via the channels app and have captured the logs my end too. Will take a look shortly and post here what it shows.
Logs from moment I started playing to moment it stopped:
01-20 13:54:48.803 1970 2015 V mpv : [ffmpeg/video] h264_mediacodec: MediaCodec started successfully: codec = OMX.Nvidia.h264.decode, ret = 0
01-20 13:54:48.803 1970 2015 V mpv : event: audio-reconfig
01-20 13:54:48.803 1970 2015 V mpv : [vd] Selected codec: h264 (H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10)
01-20 13:54:48.803 1970 2015 V mpv : [vf] User filter list:
01-20 13:54:48.803 1970 2015 V mpv : [vf] (empty)
01-20 13:54:48.804 1970 2015 V mpv : [ad] Codec list:
01-20 13:54:48.804 1970 2015 V mpv : [ad] aac_latm - AAC LATM (Advanced Audio Coding LATM syntax)
01-20 13:54:48.804 1970 2015 V mpv : [ad] Opening decoder aac_latm
01-20 13:54:48.804 1970 2015 V mpv : [ad] Requesting 1 threads for decoding.
01-20 13:54:48.804 1970 2015 V mpv : event: audio-reconfig
01-20 13:54:48.804 1970 2015 V mpv : event: file-loaded
01-20 13:54:48.804 1970 2015 V mpv : [ad] Selected codec: aac_latm (AAC LATM (Advanced Audio Coding LATM syntax))
01-20 13:54:48.804 1970 2015 V mpv : property: buffering = false
01-20 13:54:48.805 1970 1970 V PlayerActivity: state LOADING -> LOADED
01-20 13:54:48.805 1970 2015 V mpv : property: track-list has 3 entries
01-20 13:54:48.806 1970 2015 V mpv : [af] User filter list:
01-20 13:54:48.806 1970 2015 V mpv : [af] (empty)
01-20 13:54:48.806 1970 2015 V mpv : [cplayer] Starting playback...
01-20 13:54:48.806 1970 2015 I mpv : [cplayer] Track switched:
01-20 13:54:48.806 1970 2015 I mpv : [cplayer] (+) Video --vid=1 (h264 1920x1080 25.000fps)
01-20 13:54:48.806 1970 2015 I mpv : [cplayer] (+) Audio --aid=1 --alang=eng (aac_latm 2ch 48000Hz)
01-20 13:54:48.806 1970 2015 I mpv : [cplayer] Subs --sid=1 --slang=eng (dvb_subtitle)
01-20 13:54:48.806 1970 2015 V mpv : [cplayer] Set property: aid=1 -> 1
01-20 13:54:48.813 3300 2826 D NvOsDebugPrintf: NVMEDIA: RefreshRate = 50.000000
01-20 13:54:48.813 3300 2826 D NvOsDebugPrintf: NVMEDIA: bEnableVideoSmootheningbEnable = 0
01-20 13:54:48.813 3300 2826 D NvOsDebugPrintf: NVMEDIA: cbBeginSequence: 168: BeginSequence 1920x1088, bVPR = 0
01-20 13:54:48.813 3300 2826 D NvOsDebugPrintf: NVMEDIA: cbBeginSequence: 489: DecodeBuffers = 5, pnvsi->eCodec = 0
01-20 13:54:48.816 3300 2826 D NvOsDebugPrintf: NVMEDIA: cbBeginSequence: 568: Display Resolution : (1920x1080)
01-20 13:54:48.816 3300 2826 D NvOsDebugPrintf: NVMEDIA: cbBeginSequence: 569: Display Aspect Ratio : (1920x1080)
01-20 13:54:48.816 3300 2826 D NvOsDebugPrintf: NVMEDIA: cbBeginSequence: 647: ColorFormat : 5
01-20 13:54:48.816 3300 2826 D NvOsDebugPrintf: NVMEDIA: cbBeginSequence:658 ColorSpace = NvColorSpace_YCbCr709
01-20 13:54:48.816 3300 2826 D NvOsDebugPrintf: NVMEDIA: deinterlaceType = 2, DeinterlacingRate = 2
01-20 13:54:48.816 3300 2826 D NvOsDebugPrintf: NVMEDIA: cbBeginSequence: 748: Layout 3: Interlaced Output Resolution : (1920x1088)
01-20 13:54:48.816 3300 2826 D NvOsDebugPrintf: NVMEDIA: InterlaceBufferAlloc !!!!!!!!!!!!!!!!!!!!!
01-20 13:54:48.816 3300 2826 D NvOsDebugPrintf: NVMEDIA: NvMediaMixerInit: 119: frameWidth = 1920, frameHeight = 1088
01-20 13:54:48.824 3300 2830 D NvOsDebugPrintf: NVMEDIA: DeinterlaceThread: 782: DeinterlaceThread is created
01-20 13:54:48.832 3300 2826 D NvOsDebugPrintf: NVMEDIA: cbBeginSequence: 915: NumOfSurfaces = 4, InteraceStream = 1, InterlaceEnabled = 1, bSecure = 0, MVC = 0 Semiplanar = 1, bReinit = 1, BitDepthForSurface = 8 LumaBitDepth = 8, ChromaBitDepth = 8, ChromaFormat = 5
01-20 13:54:48.832 3300 2826 D NvOsDebugPrintf: NVMEDIA: cbBeginSequence: 917: BeginSequence ColorPrimaries = 1, TransferCharacteristics = 1, MatrixCoefficients = 1
01-20 13:54:48.832 3300 2826 D NvOsDebugPrintf: Allocating new output: 1920x1088 (x 4), ThumbnailMode = 0
01-20 13:54:48.832 3300 2826 D NvOsDebugPrintf:
01-20 13:54:48.832 3300 2826 D NvOsDebugPrintf: bitDepth ========================================> 8
01-20 13:54:48.832 3300 2826 D NvOsDebugPrintf: NewStreamInfo->bValidMasteringDispData ==========> 0
01-20 13:54:48.832 3300 2826 D NvOsDebugPrintf: bAllowClientValues ==============================> 0
01-20 13:54:48.832 3300 2826 D NvOsDebugPrintf: NewStreamInfo->bValidContentLightLevelInfo ======> 0
01-20 13:54:48.832 3300 2826 D NvOsDebugPrintf: OPENMAX: HandleNewStreamFormat: 3614: Send OMX_EventPortSettingsChanged: nFrameWidth = 1920, nFrameHeight = 1080
01-20 13:54:48.832 3300 2826 D NvOsDebugPrintf: NVMEDIA: FrameRate = 25
01-20 13:54:48.833 1970 2822 I ACodec : Get OMX_IndexConfigScale: 0x10000/0x10000
01-20 13:54:48.833 1970 2822 I ACodec : Scaled Width is 1920, height is 1080
01-20 13:54:48.836 1970 2822 D SurfaceUtils: set up nativeWindow 0x6f67c808 for 1920x1080, color 0x146, rotation 0, usage 0x2900
01-20 13:54:48.863 3300 2826 D NvOsDebugPrintf: reference in DPB was never decoded
01-20 13:54:48.864 1970 2015 V mpv : [af] [in] 48000Hz stereo 2ch floatp
01-20 13:54:48.864 1970 2015 V mpv : [af] [userspeed] 48000Hz stereo 2ch floatp
01-20 13:54:48.865 1970 2015 V mpv : [af] [userspeed] (disabled)
01-20 13:54:48.865 1970 2015 V mpv : [af] [convert] 48000Hz stereo 2ch floatp
01-20 13:54:48.865 1970 2015 V mpv : [ao] Trying audio driver 'audiotrack'
01-20 13:54:48.865 1970 2015 V mpv : [ao/audiotrack] requested format: 48000 Hz, stereo channels, floatp
01-20 13:54:48.867 1970 2015 V mpv : [ao/audiotrack] AudioTrack.nativeOutputSampleRate = 48000
01-20 13:54:48.876 1970 2015 V mpv : [ao/audiotrack] Setting bufferSize = 19200 (driver=6160, min=19200, max=28800)
01-20 13:54:48.878 3231 7043 I APM_AudioPolicyManager: setMsdEnable: dolby_audio_enable = 1, is_media_streaming_app = 0 enable = 0 mMsdEnable = 0 is_dolby_formats = 0
01-20 13:54:48.881 3231 7043 I APM_AudioPolicyManager: getOutputForAttr() device 0x400, sampling rate 48000, format 0x1, channel mask 0x3, flags 0x8
01-20 13:54:48.881 3231 7043 W AudioFlinger: createTrack_l(): mismatch between requested flags (00000008) and output flags (00000006)
01-20 13:54:48.881 3231 7043 D AudioFlinger: Client defaulted notificationFrames to 2400 for frameCount 4800
01-20 13:54:48.883 1970 2014 W AudioTrack: Use of stream types is deprecated for operations other than volume control
01-20 13:54:48.883 1970 2014 W AudioTrack: See the documentation of AudioTrack() for what to use instead with android.media.AudioAttributes to qualify your playback use case
01-20 13:54:48.883 1970 2015 V mpv : [ao/audiotrack] device buffer: 9600 samples.
01-20 13:54:48.883 1970 2015 V mpv : [ao/audiotrack] using soft-buffer of 9600 samples.
01-20 13:54:48.883 1970 2015 V mpv : event: audio-reconfig
01-20 13:54:48.884 1970 2015 I mpv : [cplayer] AO: [audiotrack] 48000Hz stereo 2ch s16
01-20 13:54:48.884 1970 2015 V mpv : [cplayer] AO: Description: Android AudioTrack audio output
01-20 13:54:48.884 1970 2015 V mpv : [autoconvert] inserting resampler
01-20 13:54:48.884 1970 2015 V mpv : [swresample] format change, reinitializing resampler
01-20 13:54:48.884 1970 2015 V mpv : [swresample] 48000Hz stereo floatp -> 48000Hz stereo s16
01-20 13:54:48.884 1970 2015 V mpv : [af] [out] 48000Hz stereo 2ch s16
01-20 13:54:49.074 1970 1970 V mpvstats: AV: 0.000 A-V: 0.000 Dropped: 0 Cache: 0.000s + 0KB
01-20 13:54:49.160 1970 2822 I ACodec : Get OMX_IndexConfigScale: 0x10000/0x10000
01-20 13:54:49.160 1970 2822 I ACodec : Scaled Width is 1920, height is 1080
01-20 13:54:49.185 1970 2015 I mpv : [vd] Using hardware decoding (mediacodec).
01-20 13:54:49.185 1970 2015 V mpv : [vd] Decoder format: 1920x1080 mediacodec auto/auto/auto/auto/auto CL=unknown (auto 0.000000/0.000000/0.000000)
01-20 13:54:49.185 1970 2015 V mpv : [vd] Forcing user-set aspect ratio.
01-20 13:54:49.185 1970 2015 V mpv : [vf] [in] 1920x1080 [134217729:134217728] mediacodec bt.709/bt.709/bt.1886/limited/display SP=1.000000 CL=mpeg2/4/h264
01-20 13:54:49.185 1970 2015 V mpv : [vf] [userdeint] 1920x1080 [134217729:134217728] mediacodec bt.709/bt.709/bt.1886/limited/display SP=1.000000 CL=mpeg2/4/h264
01-20 13:54:49.186 1970 2015 V mpv : [vf] [userdeint] (disabled)
01-20 13:54:49.186 1970 2015 V mpv : [vf] [autorotate] 1920x1080 [134217729:134217728] mediacodec bt.709/bt.709/bt.1886/limited/display SP=1.000000 CL=mpeg2/4/h264
01-20 13:54:49.186 1970 2015 V mpv : [vf] [autorotate] (disabled)
01-20 13:54:49.186 1970 2015 V mpv : [vf] [convert] 1920x1080 [134217729:134217728] mediacodec bt.709/bt.709/bt.1886/limited/display SP=1.000000 CL=mpeg2/4/h264
01-20 13:54:49.186 1970 2015 V mpv : [vf] [convert] (disabled)
01-20 13:54:49.186 1970 2015 V mpv : [vf] [out] 1920x1080 [134217729:134217728] mediacodec bt.709/bt.709/bt.1886/limited/display SP=1.000000 CL=mpeg2/4/h264
01-20 13:54:49.186 1970 2015 I mpv : [cplayer] VO: [mediacodec_embed] 1920x1080 => 1920x1080 mediacodec
01-20 13:54:49.186 1970 2015 V mpv : [cplayer] VO: Description: Android (Embedded MediaCodec Surface)
01-20 13:54:49.186 1970 2015 V mpv : [vo/mediacodec_embed] reconfig to 1920x1080 [134217729:134217728] mediacodec bt.709/bt.709/bt.1886/limited/display SP=1.000000 CL=mpeg2/4/h264
01-20 13:54:49.187 3080 3080 E SELinux : avc: denied { find } for interface=vendor.nvidia.hardware.cpl.service::INvCplHalService sid=u:r:untrusted_app:s0:c123,c256,c512,c768 pid=1970 scontext=u:r:untrusted_app:s0:c123,c256,c512,c768 tcontext=u:object_r:hal_cplservice_hwservice:s0 tclass=hwservice_manager permissive=0
01-20 13:54:49.187 1970 2820 E libnvcpl_vendor: NvCplGetAppProfileSettingInt failed to get INvCplHalService
01-20 13:54:49.187 3080 3080 E SELinux : avc: denied { find } for interface=vendor.nvidia.hardware.cpl.service::INvCplHalService sid=u:r:untrusted_app:s0:c123,c256,c512,c768 pid=1970 scontext=u:r:untrusted_app:s0:c123,c256,c512,c768 tcontext=u:object_r:hal_cplservice_hwservice:s0 tclass=hwservice_manager permissive=0
01-20 13:54:49.187 1970 2820 E libnvcpl_vendor: NvCplGetAppProfileSettingInt failed to get INvCplHalService
01-20 13:54:49.226 1970 2015 V mpv : [vo/mediacodec_embed] Testing FBO format rgba16f
01-20 13:54:49.226 1970 2015 D mpv : [vo/mediacodec_embed] Resizing texture: 16x16
01-20 13:54:49.228 1970 2015 V mpv : [vo/mediacodec_embed] Using FBO format rgba16f.
01-20 13:54:49.228 1970 2015 V mpv : event: video-reconfig
01-20 13:54:49.229 1970 2015 V mpv : [vo/mediacodec_embed] Disabling HDR peak computation (one or more of the following is not supported: compute shaders=0, SSBO=0).
01-20 13:54:49.229 1970 2015 V mpv : [vo/mediacodec_embed] No advanced processing required. Enabling dumb mode.
01-20 13:54:49.229 1970 2015 V mpv : [vo/mediacodec_embed] Window size: 1920x1080
01-20 13:54:49.229 1970 2015 V mpv : [vo/mediacodec_embed] Video source: 1920x1080 (134217729:134217728)
01-20 13:54:49.229 1970 2015 V mpv : [vo/mediacodec_embed] Video display: (0, 0) 1920x1080 -> (0, 0) 1920x1080
01-20 13:54:49.230 1970 2015 V mpv : [vo/mediacodec_embed] Video scale: 1.000000/1.000000
01-20 13:54:49.230 1970 2015 V mpv : [vo/mediacodec_embed] OSD borders: l=0 t=0 r=0 b=0
01-20 13:54:49.230 1970 2015 V mpv : [vo/mediacodec_embed] Video borders: l=0 t=0 r=0 b=0
01-20 13:54:49.231 1970 2015 V mpv : event: unpause
01-20 13:54:49.231 1970 2015 V mpv : event: video-reconfig
01-20 13:54:49.231 1970 2015 V mpv : [cplayer] first video frame after restart shown
01-20 13:54:49.232 1970 2015 D mpv : [cplayer] Setting option 'cache-pause-initial' = "0.3" (flags = 64) -> 0
01-20 13:54:49.232 1970 2015 D mpv : [cplayer] Setting option 'cache-pause-wait' = "0.1" (flags = 64) -> 0
01-20 13:54:49.232 1970 2015 D mpv : [cplayer] Setting option 'cache-pause-fill' = "0.5" (flags = 64) -> 0
01-20 13:54:49.232 1970 2015 V mpv : [cplayer] Enter buffering (buffer went from 100% -> 39%) [0.120000s].
01-20 13:54:49.246 1970 2015 V mpv : property: buffering = true
01-20 13:54:49.247 1970 1970 V PlayerActivity: state LOADED -> BUFFERING
01-20 13:54:49.249 3218 3218 D hwcomposer: SuperRes: Selecting filter NONE because NOT REQUIRED
01-20 13:54:50.191 1970 2015 V mpv : [cplayer] starting audio playback
01-20 13:54:50.192 1970 2015 V mpv : event: playback-restart
01-20 13:54:50.192 1970 2015 V mpv : [cplayer] playback restart complete @ 2.553722
01-20 13:54:50.556 3668 4118 I bt_btif_avrc: register_notification_rsp: Avrcp device is not connected, handle: 0x0
01-20 13:54:50.556 3668 4118 I chatty : uid=1002(bluetooth) BluetoothAvrcpH identical 4 lines
01-20 13:54:50.556 3668 4118 I bt_btif_avrc: register_notification_rsp: Avrcp device is not connected, handle: 0x0
01-20 13:54:50.583 1970 2015 V mpv : event: unpause
01-20 13:54:50.583 1970 2015 V mpv : [cplayer] End buffering (waited 1.326213 secs) [0.469333s].
01-20 13:54:50.584 1970 2015 V mpv : property: buffering = false
01-20 13:54:50.584 1970 1970 V PlayerActivity: state BUFFERING -> PLAYING
01-20 13:54:52.094 1970 1970 V mpvstats: AV: 3.974 A-V: 0.000 Dropped: 0 Cache: 0.469s + 0KB
01-20 13:54:52.183 3508 3552 D NetlinkTracker/wlan0: addressUpdated: 2a00:23c7:3c17:6600:204:4bff:feef:f448/64 on wlan0 flags 0 scope 0
01-20 13:54:52.184 3508 3552 D NetlinkTracker/wlan0: addressUpdated: 2a00:23c7:3c17:6600:65bf:8880:3a4d:91dd/64 on wlan0 flags 1 scope 0
01-20 13:54:52.184 3508 3552 D NetlinkTracker/wlan0: interfaceDnsServerInfo: [2a00:23c7:3c17:6600::1]
01-20 13:54:54.764 1970 2015 W mpv : [lavf] error reading packet: Out of memory.
01-20 13:54:54.764 1970 2015 V mpv : [lavf] EOF reached.
01-20 13:54:54.781 1970 2015 W mpv : [lavf] error reading packet: Out of memory.
01-20 13:54:54.801 1970 2015 I chatty : uid=10123(com.getchannels.app) mpv-events identical 1 line
01-20 13:54:54.854 1970 2015 W mpv : [lavf] error reading packet: Out of memory.
01-20 13:54:54.854 1970 2015 V mpv : [lavf] EOF reached.
01-20 13:54:54.954 1970 2015 W mpv : [lavf] error reading packet: Out of memory.
01-20 13:54:55.042 1970 2015 W mpv : [lavf] error reading packet: Out of memory.
01-20 13:54:55.043 1970 2015 V mpv : [lavf] EOF reached.
01-20 13:54:55.052 1970 2015 W mpv : [lavf] error reading packet: Out of memory.
01-20 13:54:55.084 1970 2015 I chatty : uid=10123(com.getchannels.app) mpv-events identical 2 lines
01-20 13:54:55.093 1970 2015 W mpv : [lavf] error reading packet: Out of memory.
01-20 13:54:55.114 1970 1970 V mpvstats: AV: 6.994 A-V: 0.000 Dropped: 0 Cache: 0.280s + 0KB
01-20 13:54:55.214 1970 2015 E mpv : [ad] Error decoding audio.
01-20 13:54:55.214 1970 2015 W mpv : [ad] Invalid audio PTS: 7.402667 -> 7.509333
01-20 13:54:55.214 1970 2015 E mpv : [ad] Error decoding audio.
01-20 13:54:55.214 1970 2015 W mpv : [ad] Invalid audio PTS: 7.530667 -> 7.680000
01-20 13:54:55.273 1970 2015 E mpv : [ad] Error decoding audio.
01-20 13:54:55.274 1970 2015 W mpv : [ad] Invalid audio PTS: 7.701333 -> 7.850667
01-20 13:54:55.275 1970 2015 E mpv : [ad] Error decoding audio.
01-20 13:54:55.275 1970 2015 W mpv : [lavf] error reading packet: Out of memory.
01-20 13:54:55.275 1970 2015 V mpv : [lavf] EOF reached.
01-20 13:54:55.294 1970 2015 W mpv : [lavf] error reading packet: Out of memory.
01-20 13:54:55.294 1970 2015 V mpv : [af] filter input EOF
01-20 13:54:55.294 1970 2015 V mpv : [af] filter output EOF
01-20 13:54:55.305 1970 2015 W mpv : [lavf] error reading packet: Out of memory.
01-20 13:54:55.315 3300 2826 D NvOsDebugPrintf: NVMEDIA: FrameRate(for last 120 frames) = 25.000000, AvgFrameRate = 25.000000
01-20 13:54:55.334 1970 2015 W mpv : [ad] Invalid audio PTS: 7.893333 -> 8.021333
01-20 13:54:55.335 1970 2015 V mpv : event: unpause
01-20 13:54:55.335 1970 2015 V mpv : [cplayer] Enter buffering (buffer went from 100% -> 0%) [0.000000s].
01-20 13:54:55.335 1970 2015 V mpv : property: buffering = true
01-20 13:54:55.335 1970 1970 V PlayerActivity: state PLAYING -> BUFFERING
01-20 13:54:55.336 3668 4118 I bt_btif_avrc: register_notification_rsp: Avrcp device is not connected, handle: 0x0
01-20 13:54:55.336 3668 4118 I chatty : uid=1002(bluetooth) BluetoothAvrcpH identical 4 lines
01-20 13:54:55.336 3668 4118 I bt_btif_avrc: register_notification_rsp: Avrcp device is not connected, handle: 0x0
01-20 13:54:55.337 1970 1970 V mpvstats: demuxer-cache-state: {seekable-ranges=[{start=-2.222222974523902E-7, end=7.893333111118409}], cache-end=8.021333111115382, reader-pts=8.021333111115382, cache-duration=0.0, eof=false, underrun=true, idle=false, total-bytes=3881664, fw-bytes=28736, debug-low-level-seeks=0, debug-ts-last=89493.87561111111, ts-per-stream=[{type=video, duration=0.08000000000174623, reader=89493.60800000001, end=89493.68800000001}, {type=audio, duration=0.0, reader=89493.87561111111, end=89493.87561111111}]}
01-20 13:54:55.343 1970 2015 V mpv : [cplayer] Still buffering (buffer went from 0% -> 4%) [0.021333s].
01-20 13:54:55.343 1970 2015 E mpv : [ad] Error decoding audio.
01-20 13:54:55.343 1970 2015 V mpv : [cplayer] Still buffering (buffer went from 4% -> 0%) [0.000000s].
01-20 13:54:55.354 1970 2015 W mpv : [lavf] error reading packet: Out of memory.
01-20 13:54:55.355 1970 2015 V mpv : [lavf] EOF reached.
01-20 13:54:55.356 1970 2015 V mpv : event: unpause
01-20 13:54:55.356 1970 2015 V mpv : [cplayer] End buffering (waited 0.020367 secs) [0.000000s].
01-20 13:54:55.356 1970 2015 V mpv : [af] filter input EOF
01-20 13:54:55.356 3668 4118 I bt_btif_avrc: register_notification_rsp: Avrcp device is not connected, handle: 0x0
01-20 13:54:55.356 3668 4118 I chatty : uid=1002(bluetooth) BluetoothAvrcpH identical 4 lines
01-20 13:54:55.356 3668 4118 I bt_btif_avrc: register_notification_rsp: Avrcp device is not connected, handle: 0x0
01-20 13:54:55.356 1970 2015 V mpv : [af] filter output EOF
01-20 13:54:55.358 1970 2015 V mpv : property: buffering = false
01-20 13:54:55.358 3668 4118 I bt_btif_avrc: register_notification_rsp: Avrcp device is not connected, handle: 0x0
01-20 13:54:55.358 3668 4118 I chatty : uid=1002(bluetooth) BluetoothAvrcpH identical 4 lines
01-20 13:54:55.358 3668 4118 I bt_btif_avrc: register_notification_rsp: Avrcp device is not connected, handle: 0x0
01-20 13:54:55.371 1970 1970 V PlayerActivity: state BUFFERING -> PLAYING
01-20 13:54:55.442 3300 2824 D NvOsDebugPrintf: NVMEDIA: NvMMLiteNVMEDIADecDoWork: 2503: NVMMLITE_NVMEDIA: EOS detected
01-20 13:54:55.447 1970 2015 W mpv : [lavf] error reading packet: Out of memory.
01-20 13:54:55.794 3300 2826 D NvOsDebugPrintf: NVMEDIA: NVMEDIABufferProcessing: 1241: Processing of EOS
01-20 13:54:56.029 3300 2826 D NvOsDebugPrintf: NVMEDIA: NVMEDIABufferProcessing: 1319: Processing of EOS Done
01-20 13:54:56.038 1970 2015 V mpv : [vf] filter input EOF
01-20 13:54:56.038 1970 2015 V mpv : [vf] filter output EOF
01-20 13:54:56.038 1970 2015 D mpv : [cplayer] video EOF (status=4)
01-20 13:54:56.039 5442 30449 I chromium: [5442:30449:INFO:ssdp_device.c(101)] SSDP packets sent for 31 seconds = 3
01-20 13:54:56.054 3668 4118 I bt_btif_avrc: register_notification_rsp: Avrcp device is not connected, handle: 0x0
01-20 13:54:56.054 3668 4118 I chatty : uid=1002(bluetooth) BluetoothAvrcpH identical 4 lines
01-20 13:54:56.054 3668 4118 I bt_btif_avrc: register_notification_rsp: Avrcp device is not connected, handle: 0x0
01-20 13:54:56.054 1970 2015 V mpv : [cplayer] video EOF reached
01-20 13:54:56.054 1970 2015 D mpv : [cplayer] video EOF (status=5)
01-20 13:54:56.054 1970 2015 V mpv : [cplayer] starting audio playback
01-20 13:54:56.055 1970 2015 W mpv : [lavf] error reading packet: Out of memory.
01-20 13:54:56.085 1970 2015 D mpv : [cplayer] video EOF (status=5)
01-20 13:54:56.086 1970 2015 V mpv : event: audio-reconfig
01-20 13:54:56.086 1970 2015 V mpv : [cplayer] audio EOF reached
01-20 13:54:56.087 1970 2015 D mpv : [cplayer] video EOF (status=5)
01-20 13:54:56.087 1970 2015 V mpv : [cplayer] EOF code: 1
01-20 13:54:56.087 1970 2015 V mpv : [ad] Uninit decoder.
01-20 13:54:56.087 1970 2015 V mpv : [vd] Uninit decoder.
01-20 13:54:56.092 3300 2828 D NvOsDebugPrintf: NVMEDIA: NVMEDIAFrameStatusReporting: 2011: Closing NVMEDIA Frame Status Thread -------------
01-20 13:54:56.093 3300 2829 D NvOsDebugPrintf: NVMEDIA: NVMEDIAVPRFloorSizeSettingThread: 1830: Closing NVMEDIAVPRFloorSizeSettingThread -------------
01-20 13:54:56.093 3300 2827 D NvOsDebugPrintf: NVMEDIA: NVMEDIAFrameDelivery: 1866: Closing NVMEDIA Frame Delivery Thread -------------
01-20 13:54:56.109 3300 2830 D NvOsDebugPrintf: NVMEDIA: DeinterlaceThread: 860: Closing Deinterlace Thread
01-20 13:54:56.115 3300 2824 D NvOsDebugPrintf: NVMEDIA: NvMMLiteTVMRDecBlockClose: 3703: Done
01-20 13:54:56.116 1970 2821 D SurfaceUtils: disconnecting from surface 0x6f67c808, reason disconnectFromSurface
01-20 13:54:56.130 1970 2015 V mpv : event: video-reconfig
01-20 13:54:56.134 3668 4118 I bt_btif_avrc: register_notification_rsp: Avrcp device is not connected, handle: 0x0
01-20 13:54:56.135 3668 4118 I chatty : uid=1002(bluetooth) BluetoothAvrcpH identical 4 lines
01-20 13:54:56.135 3668 4118 I bt_btif_avrc: register_notification_rsp: Avrcp device is not connected, handle: 0x0
01-20 13:54:56.137 1970 2015 V mpv : event: audio-reconfig
01-20 13:54:56.140 1970 2015 D mpv : [cplayer] Terminating demuxers...
01-20 13:54:56.146 1970 2817 V Streamer: finished
01-20 13:54:56.161 1970 2015 V mpv : event: tracks-changed
01-20 13:54:56.161 1970 2015 V mpv : event: end-file
01-20 13:54:56.161 1970 2015 D mpv : [cplayer] Done terminating demuxers.
01-20 13:54:56.161 1970 1970 V PlayerActivity: state PLAYING -> STOPPED
01-20 13:54:56.161 1970 2015 V mpv : [cplayer] finished playback, success (reason 0)
01-20 13:54:56.161 1970 2015 I mpv : [cplayer]
01-20 13:54:56.163 3233 3317 D SurfaceFlinger: Connected to Power Manager
01-20 13:54:56.163 1970 2015 D mpv : [vo/mediacodec_embed] flushing shader cache
01-20 13:54:56.169 1970 2015 V mpv : event: video-reconfig
01-20 13:54:56.169 1970 2015 V mpv : event: idle
01-20 13:54:56.169 1970 2015 V mpv : [cplayer] Set property: aid=1 -> 1
01-20 13:54:56.169 1970 2015 V mpv : property: buffering = false
01-20 13:54:56.170 1970 2015 V mpv : property: track-list has 0 entries
01-20 13:54:58.115 1970 1970 V mpvstats: AV: 7.934 A-V: 0.000 Dropped: 0 Cache: 0.000s + 0KB
01-20 13:54:59.137 3191 4038 W nvaudio_hw: stop_output_stream: 1 underruns seen on pcm device hdmi-playback
01-20 13:54:59.249 3508 3552 D NetlinkTracker/wlan0: addressUpdated: 2a00:23c7:3c17:6600:204:4bff:feef:f448/64 on wlan0 flags 0 scope 0
01-20 13:54:59.249 3508 3552 D NetlinkTracker/wlan0: addressUpdated: 2a00:23c7:3c17:6600:65bf:8880:3a4d:91dd/64 on wlan0 flags 1 scope 0
01-20 13:54:59.249 3508 3552 D NetlinkTracker/wlan0: interfaceDnsServerInfo: [2a00:23c7:3c17:6600::1]
01-20 13:55:00.702 4054 4054 D AtvRemote.ABridgeX: AudioPolicy is being unregistered
01-20 13:55:00.702 3508 4530 I AudioService: unregisterAudioPolicyAsync for android.os.BinderProxy@68390c1
01-20 13:55:00.703 3231 4124 I APM_AudioPolicyManager: setDeviceConnectionStateInt() device: 0x8000, state 0, address -581846373:ap:51mixr:0 name remote-submix
01-20 13:55:00.739 3231 2806 D AudioFlinger: ro.audio.silent will be ignored for threads on AUDIO_DEVICE_OUT_REMOTE_SUBMIX
01-20 13:55:00.740 3191 4038 D r_submix: out_set_parameters(): shutting down MonoPipe sink
01-20 13:55:00.741 3191 4038 D r_submix: adev_close_output_stream() addr = -581846373:ap:51mixr:0
01-20 13:55:00.741 3191 4038 D r_submix: submix_audio_device_release_pipe_l(idx=9) addr=-581846373:ap:51mixr:0
01-20 13:55:00.741 3191 4038 D r_submix: submix_audio_device_destroy_pipe_l(): pipe destroyed
01-20 13:55:01.097 5442 5567 I chromium: [5442:5567:INFO:mdns_app_filter.cc(2579)] MdnsAppFilter: responses sent in 102 seconds: 24
01-20 13:55:01.115 1970 1970 V mpvstats: AV: 7.934 A-V: 0.000 Dropped: 0 Cache: 0.000s + 0KB
01-20 13:55:22.867 3294 3343 E storaged: getDiskStats failed with result NOT_SUPPORTED and size 0
01-20 13:55:31.119 1970 1970 I chatty : uid=10123(com.getchannels.app) identical 10 lines
01-20 13:55:34.119 1970 1970 V mpvstats: AV: 7.934 A-V: 0.000 Dropped: 0 Cache: 0.000s + 0KB
01-20 13:55:37.040 5442 5442 I chromium: [5442:5442:INFO:metrics_recorder.cc(106)] Metrics stat: total=2
01-20 13:55:37.040 5442 5442 I chromium: Cast.Discovery.Mdns.Request.In=1
01-20 13:55:37.040 5442 5442 I chromium: Cast.Discovery.Mdns.Response.Out=1
01-20 13:55:37.121 1970 1970 V mpvstats: AV: 7.934 A-V: 0.000 Dropped: 0 Cache: 0.000s + 0KB
01-20 13:55:43.123 1970 1970 I chatty : uid=10123(com.getchannels.app) identical 2 lines
01-20 13:55:46.124 1970 1970 V mpvstats: AV: 7.934 A-V: 0.000 Dropped: 0 Cache: 0.000s + 0KB
01-20 13:55:46.250 3508 3552 D NetlinkTracker/wlan0: addressUpdated: 2a00:23c7:3c17:6600:204:4bff:feef:f448/64 on wlan0 flags 0 scope 0
01-20 13:55:46.250 3508 3552 D NetlinkTracker/wlan0: addressUpdated: 2a00:23c7:3c17:6600:65bf:8880:3a4d:91dd/64 on wlan0 flags 1 scope 0
01-20 13:55:46.251 3508 3552 D NetlinkTracker/wlan0: interfaceDnsServerInfo: [2a00:23c7:3c17:6600::1]
01-20 13:55:49.125 1970 1970 V mpvstats: AV: 7.934 A-V: 0.000 Dropped: 0 Cache: 0.000s + 0KB
01-20 13:55:49.321 5442 30449 I chromium: [5442:30449:INFO:ssdp_device.c(101)] SSDP packets sent for 53 seconds = 1
01-20 13:55:52.126 1970 1970 V mpvstats: AV: 7.934 A-V: 0.000 Dropped: 0 Cache: 0.000s + 0KB
01-20 13:55:58.126 1970 1970 I chatty : uid=10123(com.getchannels.app) identical 2 lines
As Aman had reported I am getting out of memory messages it seems. My TV channel streams in the UK are different to yours, different codecs, differing resolutions in video and sound are in play, so what is working for you, doesn't mean it should work for me too.
Hopefully hear back from Aman once he's had chance to look into this in more detail.
U posted a image of a Connect tuner, so that indicated you were refering to OTA tv stream, NOT TVE stream....if you mentioned u were having this issue with TVE, sorry, i missed, that
The TVE stream I would think would be identical for all users, since the source is the networks own website data stream direct. If that has a hickup, which it can easy if on wifi, then maybe the device cant recover from it. I have played a TVE stream for as long as 9 hours, with no issue wired.
I am curious as to why the logs indicate MPV (the player) is out of memory. And is that RAM memory, or storage space memory? the output is not as clearer written/detailed as it could be. Programmers use the term "memory" a bit loosely i have noticed over the years.
The tuner picture I posted above is from my HDHomeRun Connect Duo (SiliconDust HDHR4-2DT(UK) DVB-T2) that is connected to my House Aerial. I haven't got the latest square version of the Duo, but the previous iteration, it's grey in colour.
I am not sure what TVE stands for but assuming OTA is Over The Air then that is what mine is.
whoops...i think i got confused threads going on here with my email notifications of replys. Someone replyed and said they were having TVE stream freezing.
TVE is web streaming via a streaming service login...the channels u mention are only on those services, not OTA for us here in USA....lol.
Also, i see QAM256 in the Modulation lock...here QAM is a cable TV thing, not OTA via antenna. here it is 8vsb. No idea on the differences, maybe UK has better higher bitrate and more cable tv like OTA standard...
I have a HDHR Connect Q and a Mohou indoor antenna.
Can you try setting Decoder to Software in the app.
My gut feeling is there is some new ffmpeg configuration required, specific for new 2019 shield, to make streaming more stable.
To answer Aman's post, I tried with software encoding yesterday when you originally requested it, and had the same issue, although didn't verify the same out of memory error via my own logging attempts, as it pre-dated it.
I did submit error reports via the app when software decode was selected. The only one I haven't tried is hybrid.
Do you want me to have another go with Software anyway?
Same problem with Software Encoding:
01-20 18:16:06.296 7682 7790 V Streamer: started with 8589933380 byte buffer in /mnt/expand/53d42424-e034-4fbf-9218-ca3830162943/user/0/com.getchannels.app/cache/channels-streamer.ts
01-20 18:16:06.296 7682 7790 V Streamer: seek(0) = 0 -> 0
01-20 18:16:06.297 7682 7730 D mpv : [stream_callback] Stream opened successfully.
01-20 18:16:06.297 7682 7730 V mpv : [demux] Trying demuxers for level=force.
01-20 18:16:06.297 7682 7730 D mpv : [demux] Trying demuxer: lavf (force-level: force)
01-20 18:16:06.310 7682 7730 V mpv : [lavf] Found 'mpegts' at score=50 size=2048.
01-20 18:16:06.310 7682 7730 V mpv : [lavf] avformat_open_input() finished after 2048 bytes.
01-20 18:16:06.310 7682 7730 D mpv : [ffmpeg/demuxer] mpegts: Before avformat_find_stream_info() pos: 0 bytes read:2048 seeks:0 nb_streams:3
01-20 18:16:06.375 7682 7730 D mpv : [ffmpeg/video] h264: Reinit context to 1920x1088, pix_fmt: yuv420p
01-20 18:16:06.462 7682 7730 E mpv : [ffmpeg/video] h264: mmco: unref short failure
01-20 18:16:06.463 7682 7730 V mpv : [ffmpeg/demuxer] mpegts: Skipping duration calculation in estimate_timings_from_pts
01-20 18:16:06.463 7682 7790 V Streamer: seek(0) = 611816 -> 0
01-20 18:16:06.464 7682 7730 D mpv : [ffmpeg/demuxer] mpegts: After avformat_find_stream_info() pos: 0 bytes read:611816 seeks:1 frames:72
01-20 18:16:06.465 7682 7730 V mpv : [lavf] avformat_find_stream_info() finished after 0 bytes.
01-20 18:16:06.465 7682 7730 V mpv : [demux] Detected file format: mpegts (libavformat)
01-20 18:16:06.465 7682 7730 V mpv : [cplayer] Opening done: hdhr://107
01-20 18:16:06.465 7682 7730 V mpv : event: tracks-changed
01-20 18:16:06.465 7682 7730 I chatty : uid=10123(com.getchannels.app) mpv-events identical 1 line
01-20 18:16:06.465 7682 7730 V mpv : event: tracks-changed
01-20 18:16:06.465 7682 7730 D mpv : [global] config path: 'sub' -/-> '/mnt/expand/53d42424-e034-4fbf-9218-ca3830162943/user/0/com.getchannels.app/files/mpv/sub'
01-20 18:16:06.466 7682 7730 V mpv : event: metadata-update
01-20 18:16:06.467 7682 7730 V mpv : property: track-list has 3 entries
01-20 18:16:06.471 3080 3080 E SELinux : avc: denied { find } for interface=vendor.nvidia.hardware.cpl.service::INvCplHalService sid=u:r:untrusted_app:s0:c123,c256,c512,c768 pid=7682 scontext=u:r:untrusted_app:s0:c123,c256,c512,c768 tcontext=u:object_r:hal_cplservice_hwservice:s0 tclass=hwservice_manager permissive=0
01-20 18:16:06.471 7682 7793 E libnvcpl_vendor: NvCplGetAppProfileSettingInt failed to get INvCplHalService
01-20 18:16:06.471 3080 3080 E SELinux : avc: denied { find } for interface=vendor.nvidia.hardware.cpl.service::INvCplHalService sid=u:r:untrusted_app:s0:c123,c256,c512,c768 pid=7682 scontext=u:r:untrusted_app:s0:c123,c256,c512,c768 tcontext=u:object_r:hal_cplservice_hwservice:s0 tclass=hwservice_manager permissive=0
01-20 18:16:06.471 7682 7793 E libnvcpl_vendor: NvCplGetAppProfileSettingInt failed to get INvCplHalService
01-20 18:16:06.502 7682 7730 V mpv : event: audio-reconfig
01-20 18:16:06.503 7682 7730 V mpv : event: audio-reconfig
01-20 18:16:06.503 7682 7730 V mpv : event: file-loaded
01-20 18:16:06.503 7682 7682 V PlayerActivity: state LOADING -> LOADED
01-20 18:16:06.503 7682 7730 V mpv : [lavf] select track 0
01-20 18:16:06.503 7682 7730 V mpv : property: buffering = false
01-20 18:16:06.504 7682 7730 V mpv : property: track-list has 3 entries
01-20 18:16:06.504 7682 7730 V mpv : [lavf] select track 1
01-20 18:16:06.504 7682 7730 I mpv : [cplayer] (+) Video --vid=1 (h264 1920x1080 25.000fps)
01-20 18:16:06.504 7682 7730 I mpv : [cplayer] (+) Audio --aid=1 --alang=eng (aac_latm 2ch 48000Hz)
01-20 18:16:06.504 7682 7730 I mpv : [cplayer] Subs --sid=1 --slang=eng (dvb_subtitle)
01-20 18:16:06.504 7682 7730 F mpv : [vo/gpu/opengl] Could not create EGL context for Desktop OpenGL!
01-20 18:16:06.504 7682 7730 V mpv : [vd] Container reported FPS: 25.000000
01-20 18:16:06.504 7682 7730 V mpv : [vd] Codec list:
01-20 18:16:06.504 7682 7730 V mpv : [vd] h264 - H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
01-20 18:16:06.504 7682 7730 V mpv : [vd] h264_mediacodec (h264) - H.264 Android MediaCodec decoder
01-20 18:16:06.504 7682 7730 V mpv : [vd] Opening decoder h264
01-20 18:16:06.504 7682 7730 V mpv : [vd] No hardware decoding requested.
01-20 18:16:06.505 7682 7730 V mpv : [vd] Using software decoding.
01-20 18:16:06.505 7682 7730 V mpv : [vd] Detected 4 logical cores.
01-20 18:16:06.505 7682 7730 V mpv : [vd] Requesting 5 threads for decoding.
01-20 18:16:06.505 7682 7730 V mpv : [vd] Selected codec: h264 (H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10)
01-20 18:16:06.505 7682 7730 D mpv : [user_filter_wrapper] Setting option 'graph' = 'fastdeint=linear' (flags = 0)
01-20 18:16:06.505 7682 7730 V mpv : [vf] User filter list:
01-20 18:16:06.505 7682 7730 V mpv : [vf] lavfi (lavfi.00)
01-20 18:16:06.505 7682 7730 V mpv : [ad] Codec list:
01-20 18:16:06.505 7682 7730 V mpv : [ad] aac_latm - AAC LATM (Advanced Audio Coding LATM syntax)
01-20 18:16:06.505 7682 7730 V mpv : [ad] Opening decoder aac_latm
01-20 18:16:06.506 7682 7730 V mpv : [ad] Requesting 1 threads for decoding.
01-20 18:16:06.506 7682 7730 V mpv : [ad] Selected codec: aac_latm (AAC LATM (Advanced Audio Coding LATM syntax))
01-20 18:16:06.506 7682 7730 V mpv : [af] User filter list:
01-20 18:16:06.506 7682 7730 V mpv : [af] (empty)
01-20 18:16:06.506 7682 7730 V mpv : [cplayer] Starting playback...
01-20 18:16:06.506 7682 7730 I mpv : [cplayer] Track switched:
01-20 18:16:06.506 7682 7730 I mpv : [cplayer] (+) Video --vid=1 (h264 1920x1080 25.000fps)
01-20 18:16:06.506 7682 7730 I mpv : [cplayer] (+) Audio --aid=1 --alang=eng (aac_latm 2ch 48000Hz)
01-20 18:16:06.506 7682 7730 I mpv : [cplayer] Subs --sid=1 --slang=eng (dvb_subtitle)
01-20 18:16:06.507 7682 7730 V mpv : [cplayer] Set property: aid=1 -> 1
01-20 18:16:06.507 7682 7730 V mpv : [af] [in] 48000Hz stereo 2ch floatp
01-20 18:16:06.507 7682 7730 V mpv : [af] [userspeed] 48000Hz stereo 2ch floatp
01-20 18:16:06.507 7682 7730 V mpv : [af] [userspeed] (disabled)
01-20 18:16:06.507 7682 7730 V mpv : [af] [convert] 48000Hz stereo 2ch floatp
01-20 18:16:06.507 7682 7730 V mpv : [ao] Trying audio driver 'audiotrack'
01-20 18:16:06.507 7682 7730 V mpv : [ao/audiotrack] requested format: 48000 Hz, stereo channels, floatp
01-20 18:16:06.509 7682 7730 V mpv : [ao/audiotrack] AudioTrack.nativeOutputSampleRate = 48000
01-20 18:16:06.518 7682 7730 V mpv : [ao/audiotrack] Setting bufferSize = 19200 (driver=6160, min=19200, max=28800)
01-20 18:16:06.519 3231 4123 I APM_AudioPolicyManager: setMsdEnable: dolby_audio_enable = 1, is_media_streaming_app = 0 enable = 0 mMsdEnable = 0 is_dolby_formats = 0
01-20 18:16:06.522 3231 4123 I APM_AudioPolicyManager: getOutputForAttr() device 0x400, sampling rate 48000, format 0x1, channel mask 0x3, flags 0x8
01-20 18:16:06.522 3231 4123 W AudioFlinger: createTrack_l(): mismatch between requested flags (00000008) and output flags (00000006)
01-20 18:16:06.522 3231 4123 D AudioFlinger: Client defaulted notificationFrames to 2400 for frameCount 4800
01-20 18:16:06.524 7682 7727 W AudioTrack: Use of stream types is deprecated for operations other than volume control
01-20 18:16:06.524 7682 7727 W AudioTrack: See the documentation of AudioTrack() for what to use instead with android.media.AudioAttributes to qualify your playback use case
01-20 18:16:06.524 7682 7730 V mpv : [ao/audiotrack] device buffer: 9600 samples.
01-20 18:16:06.524 7682 7730 V mpv : [ao/audiotrack] using soft-buffer of 9600 samples.
01-20 18:16:06.524 7682 7730 I mpv : [cplayer] AO: [audiotrack] 48000Hz stereo 2ch s16
01-20 18:16:06.524 7682 7730 V mpv : [cplayer] AO: Description: Android AudioTrack audio output
01-20 18:16:06.525 7682 7730 V mpv : event: audio-reconfig
01-20 18:16:06.525 7682 7730 V mpv : [autoconvert] inserting resampler
01-20 18:16:06.525 7682 7730 V mpv : [swresample] format change, reinitializing resampler
01-20 18:16:06.525 7682 7730 V mpv : [swresample] 48000Hz stereo floatp -> 48000Hz stereo s16
01-20 18:16:06.525 7682 7730 V mpv : [af] [out] 48000Hz stereo 2ch s16
01-20 18:16:06.525 7682 7730 D mpv : [ffmpeg/video] h264: Reinit context to 1920x1088, pix_fmt: yuv420p
01-20 18:16:06.526 7682 7730 D mpv : [vd] DR parameter change to 1920x1090 yuv420p align=16
01-20 18:16:06.526 7682 7730 D mpv : [vd] Allocating new DR image...
01-20 18:16:06.526 7682 7730 D mpv : [vd] ...failed..
01-20 18:16:06.526 7682 7730 V mpv : [vd] DR failed - disabling.
01-20 18:16:06.534 7682 7730 E mpv : [ffmpeg/video] h264: co located POCs unavailable
01-20 18:16:06.542 7682 7730 D mpv : [ffmpeg/video] h264: Increasing reorder buffer to 2
01-20 18:16:06.601 7682 7730 E mpv : [ffmpeg/video] h264: reference picture missing during reorder
01-20 18:16:06.601 7682 7730 E mpv : [ffmpeg/video] h264: Missing reference picture, default is 65848
01-20 18:16:06.676 7682 7730 E mpv : [ffmpeg/video] h264: reference picture missing during reorder
01-20 18:16:06.676 7682 7730 E mpv : [ffmpeg/video] h264: Missing reference picture, default is 65864
01-20 18:16:06.679 7682 7730 E mpv : [ffmpeg/video] h264: mmco: unref short failure
01-20 18:16:06.817 7682 7730 D mpv : [ffmpeg/video] h264: Increasing reorder buffer to 3
01-20 18:16:06.835 7682 7730 V mpv : [vd] Decoder format: 1920x1080 yuv420p bt.709/bt.709/bt.1886/limited/auto CL=mpeg2/4/h264 (auto 0.000000/0.000000/0.000000)
01-20 18:16:06.835 7682 7730 V mpv : [vd] Forcing user-set aspect ratio.
01-20 18:16:06.835 7682 7730 V mpv : [vf] [in] 1920x1080 [134217729:134217728] yuv420p bt.709/bt.709/bt.1886/limited/display SP=1.000000 CL=mpeg2/4/h264
01-20 18:16:06.835 7682 7730 V mpv : [vf] [userdeint] 1920x1080 [134217729:134217728] yuv420p bt.709/bt.709/bt.1886/limited/display SP=1.000000 CL=mpeg2/4/h264
01-20 18:16:06.836 7682 7730 V mpv : [vf] [userdeint] (disabled)
01-20 18:16:06.836 7682 7730 V mpv : [vf] [lavfi] 1920x1080 [134217729:134217728] yuv420p bt.709/bt.709/bt.1886/limited/display SP=1.000000 CL=mpeg2/4/h264
01-20 18:16:06.836 7682 7730 D mpv : [ffmpeg] mpv_src_in0: w:1920 h:1080 pixfmt:yuv420p tb:1/1000000 fr:25/1 sar:134217729/134217728 sws_param:
01-20 18:16:06.836 7682 7730 D mpv : [lavfi] Filter graph:
01-20 18:16:06.836 7682 7730 D mpv : [lavfi] +--------------------+
01-20 18:16:06.836 7682 7730 D mpv : [lavfi] mpv_src_in0:default--[1920x1080 134217729:134217728 yuv420p]--default| Parsed_fastdeint_0 |default--[1920x1080 134217729:134217728 yuv420p]--mpv_sink_out0:default
01-20 18:16:06.836 7682 7730 D mpv : [lavfi] | (fastdeint) |
01-20 18:16:06.836 7682 7730 D mpv : [lavfi] +--------------------+
01-20 18:16:06.836 7682 7730 D mpv : [lavfi]
01-20 18:16:06.836 7682 7730 D mpv : [lavfi] +---------------+
01-20 18:16:06.836 7682 7730 D mpv : [lavfi] Parsed_fastdeint_0:default--[1920x1080 134217729:134217728 yuv420p]--default| mpv_sink_out0 |
01-20 18:16:06.836 7682 7730 D mpv : [lavfi] | (buffersink) |
01-20 18:16:06.836 7682 7730 D mpv : [lavfi] +---------------+
01-20 18:16:06.837 7682 7730 D mpv : [lavfi]
01-20 18:16:06.837 7682 7730 D mpv : [lavfi] +-------------+
01-20 18:16:06.837 7682 7730 D mpv : [lavfi] | mpv_src_in0 |default--[1920x1080 134217729:134217728 yuv420p]--Parsed_fastdeint_0:default
01-20 18:16:06.837 7682 7730 D mpv : [lavfi] | (buffer) |
01-20 18:16:06.837 7682 7730 D mpv : [lavfi] +-------------+
01-20 18:16:06.837 7682 7730 D mpv : [lavfi]
01-20 18:16:06.837 7682 7730 D mpv : [lavfi]
01-20 18:16:06.860 7682 7730 V mpv : [vf] [autorotate] 1920x1080 [134217729:134217728] yuv420p bt.709/bt.709/bt.1886/limited/display SP=1.000000 CL=mpeg2/4/h264
01-20 18:16:06.860 7682 7730 V mpv : [vf] [autorotate] (disabled)
01-20 18:16:06.860 7682 7730 V mpv : [vf] [convert] 1920x1080 [134217729:134217728] yuv420p bt.709/bt.709/bt.1886/limited/display SP=1.000000 CL=mpeg2/4/h264
01-20 18:16:06.860 7682 7730 V mpv : [vf] [convert] (disabled)
01-20 18:16:06.860 7682 7730 V mpv : [vf] [out] 1920x1080 [134217729:134217728] yuv420p bt.709/bt.709/bt.1886/limited/display SP=1.000000 CL=mpeg2/4/h264
01-20 18:16:06.860 7682 7730 I mpv : [cplayer] VO: [gpu] 1920x1080 => 1920x1080 yuv420p
01-20 18:16:06.860 7682 7730 V mpv : [cplayer] VO: Description: Shader-based GPU Renderer
01-20 18:16:06.863 7682 7730 V mpv : event: video-reconfig
01-20 18:16:06.871 3233 3317 D SurfaceFlinger: Connected to Power Manager
01-20 18:16:06.874 7682 7730 V mpv : [cplayer] first video frame after restart shown
01-20 18:16:06.874 7682 7730 D mpv : [cplayer] Setting option 'cache-pause-initial' = "0.3" (flags = 64) -> 0
01-20 18:16:06.874 7682 7730 D mpv : [cplayer] Setting option 'cache-pause-wait' = "0.1" (flags = 64) -> 0
01-20 18:16:06.874 7682 7730 D mpv : [cplayer] Setting option 'cache-pause-fill' = "0.5" (flags = 64) -> 0
01-20 18:16:06.890 7682 7730 V mpv : event: video-reconfig
01-20 18:16:06.900 3668 4118 I bt_btif_avrc: register_notification_rsp: Avrcp device is not connected, handle: 0x0
01-20 18:16:06.900 3668 4118 I chatty : uid=1002(bluetooth) BluetoothAvrcpH identical 4 lines
01-20 18:16:06.900 3668 4118 I bt_btif_avrc: register_notification_rsp: Avrcp device is not connected, handle: 0x0
01-20 18:16:06.901 7682 7730 V mpv : [cplayer] starting audio playback
01-20 18:16:06.952 7682 7730 V mpv : event: playback-restart
01-20 18:16:06.952 7682 7682 V PlayerActivity: state LOADED -> PLAYING
01-20 18:16:06.953 7682 7730 V mpv : [cplayer] playback restart complete @ 1.823056
01-20 18:16:09.294 7682 7682 V mpvstats: AV: 4.083 A-V: 0.000 Dropped: 0 Cache: 0.341s + 0KB
01-20 18:16:09.575 3508 3552 D NetlinkTracker/wlan0: addressUpdated: 2a00:23c7:3c17:6600:204:4bff:feef:f448/64 on wlan0 flags 0 scope 0
01-20 18:16:09.575 3508 3552 D NetlinkTracker/wlan0: addressUpdated: 2a00:23c7:3c17:6600:65bf:8880:3a4d:91dd/64 on wlan0 flags 1 scope 0
01-20 18:16:09.576 3508 3552 D NetlinkTracker/wlan0: interfaceDnsServerInfo: [2a00:23c7:3c17:6600::1]
01-20 18:16:12.304 7682 7682 V mpvstats: AV: 7.063 A-V: 0.000 Dropped: 0 Cache: 0.235s + 0KB
01-20 18:16:15.305 7682 7682 V mpvstats: AV: 10.083 A-V: 0.000 Dropped: 0 Cache: 0.277s + 0KB
01-20 18:16:17.975 4054 4054 D AtvRemote.ABridgeX: AudioPolicy is being unregistered
01-20 18:16:17.975 3508 7037 I AudioService: unregisterAudioPolicyAsync for android.os.BinderProxy@7a16537
01-20 18:16:17.975 3231 4123 I APM_AudioPolicyManager: setDeviceConnectionStateInt() device: 0x8000, state 0, address -581846373:ap:58mixr:0 name remote-submix
01-20 18:16:18.034 3231 7417 D AudioFlinger: ro.audio.silent will be ignored for threads on AUDIO_DEVICE_OUT_REMOTE_SUBMIX
01-20 18:16:18.035 3191 3397 D r_submix: out_set_parameters(): shutting down MonoPipe sink
01-20 18:16:18.036 3191 3397 D r_submix: adev_close_output_stream() addr = -581846373:ap:58mixr:0
01-20 18:16:18.036 3191 3397 D r_submix: submix_audio_device_release_pipe_l(idx=9) addr=-581846373:ap:58mixr:0
01-20 18:16:18.036 3191 3397 D r_submix: submix_audio_device_destroy_pipe_l(): pipe destroyed
01-20 18:16:18.305 7682 7682 V mpvstats: AV: 13.083 A-V: 0.000 Dropped: 0 Cache: 0.405s + 0KB
01-20 18:16:18.589 3508 3552 D NetlinkTracker/wlan0: addressUpdated: 2a00:23c7:3c17:6600:204:4bff:feef:f448/64 on wlan0 flags 0 scope 0
01-20 18:16:18.590 3508 3552 D NetlinkTracker/wlan0: addressUpdated: 2a00:23c7:3c17:6600:65bf:8880:3a4d:91dd/64 on wlan0 flags 1 scope 0
01-20 18:16:18.590 3508 3552 D NetlinkTracker/wlan0: interfaceDnsServerInfo: [2a00:23c7:3c17:6600::1]
01-20 18:16:21.306 7682 7682 V mpvstats: AV: 16.083 A-V: 0.000 Dropped: 0 Cache: 0.320s + 0KB
01-20 18:16:23.642 3294 3343 E storaged: getDiskStats failed with result NOT_SUPPORTED and size 0
01-20 18:16:24.309 7682 7682 V mpvstats: AV: 19.083 A-V: 0.000 Dropped: 0 Cache: 0.384s + 0KB
01-20 18:16:26.676 3508 3552 D NetlinkTracker/wlan0: addressUpdated: 2a00:23c7:3c17:6600:204:4bff:feef:f448/64 on wlan0 flags 0 scope 0
01-20 18:16:26.676 3508 3552 D NetlinkTracker/wlan0: addressUpdated: 2a00:23c7:3c17:6600:65bf:8880:3a4d:91dd/64 on wlan0 flags 1 scope 0
01-20 18:16:26.676 3508 3552 D NetlinkTracker/wlan0: interfaceDnsServerInfo: [2a00:23c7:3c17:6600::1]
01-20 18:16:27.310 7682 7682 V mpvstats: AV: 22.103 A-V: 0.000 Dropped: 0 Cache: 0.256s + 0KB
01-20 18:16:28.039 6788 6858 I chromium: [6788:6858:INFO:ssdp_device.c(101)] SSDP packets sent for 39 seconds = 4
01-20 18:16:30.311 7682 7682 V mpvstats: AV: 25.103 A-V: 0.000 Dropped: 0 Cache: 0.320s + 0KB
01-20 18:16:30.630 6788 6828 I chromium: [6788:6828:INFO:mdns_app_filter.cc(2579)] MdnsAppFilter: responses sent in 63 seconds: 1
01-20 18:16:33.311 7682 7682 V mpvstats: AV: 28.103 A-V: 0.000 Dropped: 0 Cache: 0.405s + 0KB
01-20 18:16:33.639 3508 3552 D NetlinkTracker/wlan0: addressUpdated: 2a00:23c7:3c17:6600:204:4bff:feef:f448/64 on wlan0 flags 0 scope 0
01-20 18:16:33.640 3508 3552 D NetlinkTracker/wlan0: addressUpdated: 2a00:23c7:3c17:6600:65bf:8880:3a4d:91dd/64 on wlan0 flags 1 scope 0
01-20 18:16:33.640 3508 3552 D NetlinkTracker/wlan0: interfaceDnsServerInfo: [2a00:23c7:3c17:6600::1]
01-20 18:16:35.384 6788 6788 I chromium: [6788:6788:INFO:metrics_recorder.cc(106)] Metrics stat: total=3
01-20 18:16:35.384 6788 6788 I chromium: Cast.Discovery.Mdns.Query.Cast.Count=1
01-20 18:16:35.384 6788 6788 I chromium: Cast.Discovery.Mdns.Request.In=1
01-20 18:16:35.384 6788 6788 I chromium: Cast.Discovery.Mdns.Response.Out=1
01-20 18:16:36.305 6788 6828 I chromium: [6788:6828:INFO:mdns_app_filter.cc(2766)] Mdns cast queries recently sent: queries=1
01-20 18:16:36.312 7682 7682 V mpvstats: AV: 31.103 A-V: 0.000 Dropped: 0 Cache: 0.299s + 0KB
01-20 18:16:36.471 6788 6832 I chromium: [6788:6832:INFO:wifi_proximity.cc(176)] Request scan.
01-20 18:16:37.159 4096 30392 E WakeLock: GCM_HB_ALARM release without a matched acquire!
01-20 18:16:37.199 4096 30394 W GCM : Raising heartbeat interval to 1740000, good=30 bad=0
01-20 18:16:37.313 3508 3548 I ActivityManager: Start proc 7813:com.google.android.gms:snet/u0a6 for service com.google.android.gms/.chimera.SnetIntentOperationService
01-20 18:16:37.320 7813 7813 E ndroid.gms:sne: Not starting debugger since process cannot load the jdwp agent.
01-20 18:16:37.374 7813 7813 I ndroid.gms:sne: The ClassLoaderContext is a special shared library.
01-20 18:16:37.445 7813 7813 W DynamiteModule: Local module descriptor class for providerinstaller not found.
01-20 18:16:37.453 7813 7813 W ProviderHelper: Unknown dynamite feature providerinstaller
01-20 18:16:37.464 7813 7813 I DynamiteModule: Considering local module providerinstaller:0 and remote module providerinstaller:0
01-20 18:16:37.464 7813 7813 W ProviderInstaller: Failed to load providerinstaller module: No acceptable module found. Local version is 0 and remote version is 0.
01-20 18:16:37.473 7813 7813 V NativeCrypto: Registering com/google/android/gms/org/conscrypt/NativeCrypto's 286 native methods...
01-20 18:16:37.490 7813 7813 D NetworkSecurityConfig: Using Network Security Config from resource network_security_config debugBuild: false
01-20 18:16:37.493 7813 7813 I ProviderInstaller: Installed default security provider GmsCore_OpenSSL
01-20 18:16:37.512 7813 7813 I Safeboot: Checking safeboot...
01-20 18:16:37.513 7813 7813 I Safeboot: Not entering safeboot; wrong process.
01-20 18:16:37.588 7813 7813 W PrimesInit: Primes instant initialization
01-20 18:16:37.594 7813 7837 W Primes : background initialization
01-20 18:16:37.798 3508 3532 I system_server: Background concurrent copying GC freed 148124(7MB) AllocSpace objects, 31(1452KB) LOS objects, 42% free, 12MB/21MB, paused 158us total 115.198ms
01-20 18:16:39.312 7682 7682 V mpvstats: AV: 34.103 A-V: 0.000 Dropped: 0 Cache: 0.341s + 0KB
01-20 18:16:40.602 3508 3552 D NetlinkTracker/wlan0: addressUpdated: 2a00:23c7:3c17:6600:204:4bff:feef:f448/64 on wlan0 flags 0 scope 0
01-20 18:16:40.603 3508 3552 D NetlinkTracker/wlan0: addressUpdated: 2a00:23c7:3c17:6600:65bf:8880:3a4d:91dd/64 on wlan0 flags 1 scope 0
01-20 18:16:40.603 3508 3552 D NetlinkTracker/wlan0: interfaceDnsServerInfo: [2a00:23c7:3c17:6600::1]
01-20 18:16:42.313 7682 7682 V mpvstats: AV: 37.103 A-V: 0.000 Dropped: 0 Cache: 0.235s + 0KB
01-20 18:16:45.313 7682 7682 V mpvstats: AV: 40.103 A-V: 0.000 Dropped: 0 Cache: 0.277s + 0KB
01-20 18:16:48.314 7682 7682 V mpvstats: AV: 43.103 A-V: 0.000 Dropped: 0 Cache: 0.277s + 0KB
01-20 18:16:50.023 3508 3552 D NetlinkTracker/wlan0: addressUpdated: 2a00:23c7:3c17:6600:204:4bff:feef:f448/64 on wlan0 flags 0 scope 0
01-20 18:16:50.023 3508 3552 D NetlinkTracker/wlan0: addressUpdated: 2a00:23c7:3c17:6600:65bf:8880:3a4d:91dd/64 on wlan0 flags 1 scope 0
01-20 18:16:50.023 3508 3552 D NetlinkTracker/wlan0: interfaceDnsServerInfo: [2a00:23c7:3c17:6600::1]
01-20 18:16:51.315 7682 7682 V mpvstats: AV: 46.103 A-V: 0.000 Dropped: 0 Cache: 0.320s + 0KB
01-20 18:16:53.554 7682 7730 W mpv : [lavf] error reading packet: Out of memory.
01-20 18:16:53.554 7682 7730 V mpv : [lavf] EOF reached.
01-20 18:16:53.567 7682 7730 W mpv : [lavf] error reading packet: Out of memory.
01-20 18:16:53.588 7682 7730 W mpv : [lavf] error reading packet: Out of memory.
01-20 18:16:53.628 7682 7730 E mpv : [ad] Error decoding audio.
01-20 18:16:53.628 7682 7730 I chatty : uid=10123(com.getchannels.app) mpv-events identical 1 line
01-20 18:16:53.628 7682 7730 E mpv : [ad] Error decoding audio.
01-20 18:16:53.628 7682 7730 V mpv : [af] filter input EOF
01-20 18:16:53.628 7682 7730 V mpv : [af] filter output EOF
01-20 18:16:53.653 7682 7730 W mpv : [ad] Invalid audio PTS: 48.832000 -> 49.152000
01-20 18:16:53.654 7682 7730 V mpv : event: unpause
01-20 18:16:53.654 3668 4118 I bt_btif_avrc: register_notification_rsp: Avrcp device is not connected, handle: 0x0
01-20 18:16:53.654 3668 4118 I chatty : uid=1002(bluetooth) BluetoothAvrcpH identical 4 lines
01-20 18:16:53.654 3668 4118 I bt_btif_avrc: register_notification_rsp: Avrcp device is not connected, handle: 0x0
01-20 18:16:53.654 7682 7730 V mpv : [cplayer] Enter buffering (buffer went from 100% -> 0%) [0.000000s].
01-20 18:16:53.654 7682 7730 V mpv : property: buffering = true
01-20 18:16:53.655 7682 7682 V PlayerActivity: state PLAYING -> BUFFERING
01-20 18:16:53.656 7682 7682 V mpvstats: demuxer-cache-state: {seekable-ranges=[{start=6.983055111111753, end=48.98133288888857}], cache-end=49.15199955555545, reader-pts=49.15199955555545, cache-duration=0.0, eof=false, underrun=true, idle=false, total-bytes=32433920, fw-bytes=440640, debug-low-level-seeks=0, debug-ts-last=9768.899255555556, ts-per-stream=[{type=video, duration=0.7600000000002183, reader=9768.33031111111, end=9769.090311111111}, {type=audio, duration=0.0, reader=9768.899255555556, end=9768.899255555556}]}
01-20 18:16:53.661 7682 7730 E mpv : [ad] Error decoding audio.
01-20 18:16:53.681 7682 7730 W mpv : [lavf] error reading packet: Out of memory.
01-20 18:16:53.681 7682 7730 V mpv : [lavf] EOF reached.
01-20 18:16:53.682 7682 7730 V mpv : event: unpause
01-20 18:16:53.682 7682 7730 V mpv : [cplayer] End buffering (waited 0.028269 secs) [0.000000s].
01-20 18:16:53.682 7682 7730 V mpv : [af] filter input EOF
01-20 18:16:53.682 7682 7730 V mpv : property: buffering = false
01-20 18:16:53.682 7682 7730 V mpv : [af] filter output EOF
01-20 18:16:53.683 3668 4118 I bt_btif_avrc: register_notification_rsp: Avrcp device is not connected, handle: 0x0
01-20 18:16:53.684 3668 4118 I chatty : uid=1002(bluetooth) BluetoothAvrcpH identical 10 lines
01-20 18:16:53.684 3668 4118 I bt_btif_avrc: register_notification_rsp: Avrcp device is not connected, handle: 0x0
01-20 18:16:53.688 7682 7682 V PlayerActivity: state BUFFERING -> PLAYING
01-20 18:16:53.785 7682 7730 W mpv : [lavf] error reading packet: Out of memory.
01-20 18:16:53.827 7682 7730 V mpv : event: unpause
01-20 18:16:53.827 7682 7730 W mpv : [ad] Invalid audio PTS: 49.173333 -> 49.322667
01-20 18:16:53.828 7682 7730 V mpv : property: buffering = true
01-20 18:16:53.828 7682 7730 V mpv : [cplayer] Enter buffering (buffer went from 100% -> 0%) [0.000000s].
01-20 18:16:53.848 7682 7682 V PlayerActivity: state PLAYING -> BUFFERING
01-20 18:16:53.848 7682 7682 V mpvstats: demuxer-cache-state: {seekable-ranges=[{start=6.983055111111753, end=49.17333288888949}], cache-end=49.322666222222324, reader-pts=49.322666222222324, cache-duration=0.0, eof=false, underrun=true, idle=false, total-bytes=32396544, fw-bytes=396928, debug-low-level-seeks=0, debug-ts-last=9769.069922222223, ts-per-stream=[{type=video, duration=0.6399999999994179, reader=9768.450311111112, end=9769.090311111111}, {type=audio, duration=0.0, reader=9769.069922222223, end=9769.069922222223}]}
01-20 18:16:53.878 7682 7730 V mpv : [cplayer] Still buffering (buffer went from 0% -> 4%) [0.021333s].
01-20 18:16:53.878 7682 7730 E mpv : [ad] Error decoding audio.
01-20 18:16:53.878 7682 7730 V mpv : [cplayer] Still buffering (buffer went from 4% -> 0%) [0.000000s].
01-20 18:16:53.899 7682 7730 W mpv : [lavf] error reading packet: Out of memory.
01-20 18:16:53.900 7682 7730 V mpv : [lavf] EOF reached.
01-20 18:16:53.900 3668 4118 I bt_btif_avrc: register_notification_rsp: Avrcp device is not connected, handle: 0x0
01-20 18:16:53.900 3668 4118 I chatty : uid=1002(bluetooth) BluetoothAvrcpH identical 4 lines
01-20 18:16:53.900 3668 4118 I bt_btif_avrc: register_notification_rsp: Avrcp device is not connected, handle: 0x0
01-20 18:16:53.932 7682 7730 V mpv : event: unpause
01-20 18:16:53.932 7682 7730 V mpv : [cplayer] End buffering (waited 0.072684 secs) [0.000000s].
01-20 18:16:53.932 7682 7730 V mpv : [af] filter input EOF
01-20 18:16:53.932 7682 7730 V mpv : [af] filter output EOF
01-20 18:16:53.932 7682 7730 W mpv : [lavf] error reading packet: Out of memory.
01-20 18:16:53.934 7682 7730 V mpv : property: buffering = false
Aman, I have put the full software log error file in the Google Drive Folder I PM you the other day. There is one labeled hardware and one labelled software now from today. In the software log file, the first error is due to me starting a stream which had no sound, so I had to kill channels app to get it back and then start again.
If it isn't the ffmpg settings that require tweaking perhaps its the java code itself needs to be a little bit more frugal on the main memory. From my simple java mobile app exploits many years ago, I remember the pain of ensuring I reuse objects and having to call System.gc() every so often to keep the memory tuned. The new nvidia shield isn't so forgiving with it's 2GB Ram. If they had stuck 3 GB RAM within it and supported 64 bit mode we probably wouldn't all be reading this now.
Hi Aman, what is the next steps with this one, any potential ETA on the fix, have you manged to replicate the issue in house? I appreciate we are probably looking at weeks for a resolution depending on other priorities.
More than happy to help test any developer builds if it helps, just let me know.
I'm guessing this is related to the UK interlaced h264 streams, which is going to make it hard for me to reproduce. I also don't have the new shield.
Next step in debugging I would suggest signing up for the DVR beta and try to record and watch to see if the issue also affects recordings or only live tv.
Let me get this right, I've already bought the standard channels product which has a bug in it on the new shield and now I need to buy the DVR which I don't need to help your investigation?
The DVR has a 30-day free trial. You don't need to buy anything. Understanding whether the issue occurs on recorded TV will help us narrow down whether the issue is occurring in the player or the live tv buffer.
Hey there. I’m in the uk. Are there any channels you’d like me to check. I have a shield too, but not the cylinder one.
I have the original shield 2015 version and I don't have the issue, the problem is only confirmed on my new 2019 cylinder version at the moment. If you have the new 2019 pro version then it would be good to test on that.
Channel wise I've had issues on most channels I don't think it has to be HD although that is mostly what I've been testing with. BBC News HD I've completed most of the tests with lately. I think my original tests were with Pick and this is SD.