media icon indicating copy to clipboard operation
media copied to clipboard
verified publisher icon androidx

While playing Widevine-DASH videos on certain devices with the MediaTek 'Dimensity' chipset, executing the 'seekTo' action often results in the player encountering "errorCode=ERROR_CODE_DECODING_FAILED".

Open myvideotom opened this issue 2 years ago • 44 comments

Version

Media3 1.1.0 / ExoPlayer 2.19.0

More version details

No response

Devices that reproduce the issue

Device:OPPO Ren8 5G, android 12 Screenshot_20240118_144144 Screenshot_20240118_144223

Devices that do not reproduce the issue

No response

Reproducible in the demo app?

Yes

Reproduction steps

1.Open the Media3 demo app and play the specified Widevine-DASH video. 2.As the video starts playing, perform the 'seekTo' action several times.

https://github.com/androidx/media/assets/156993608/f8079558-4999-4d38-b8b4-7e0721093128

Expected result

During video playback, executing the 'seekTo' command should start the video at the specified timestamp, allowing it to resume normal playback.

Actual result

After executing the 'seekTo' command multiple times during video playback, the video starts at the specified timestamp. However, the screen tends to freeze with only audio playing, followed by the appearance of the "ERROR_CODE_DECODING_FAILED" error message.

error info: playerFailed [eventTime=35.75, mediaPos=550.65, window=0, period=0, errorCode=ERROR_CODE_DECODING_FAILED androidx.media3.exoplayer.ExoPlaybackException: MediaCodecVideoRenderer error, index=0, format=Format(1, null, null, video/avc, avc1.4D4029, 7481447, null, [1920, 1080, -1.0], [-1, -1]), format_supported=YES at androidx.media3.exoplayer.ExoPlayerImplInternal.handleMessage(ExoPlayerImplInternal.java:586) at android.os.Handler.dispatchMessage(Handler.java:102) at android.os.Looper.loopOnce(Looper.java:233) at android.os.Looper.loop(Looper.java:344) at android.os.HandlerThread.run(HandlerThread.java:67) Caused by: androidx.media3.exoplayer.video.MediaCodecVideoDecoderException: Decoder failed: c2.mtk.avc.decoder.secure at androidx.media3.exoplayer.video.MediaCodecVideoRenderer.createDecoderException(MediaCodecVideoRenderer.java:1642) at androidx.media3.exoplayer.mediacodec.MediaCodecRenderer.render(MediaCodecRenderer.java:805) at androidx.media3.exoplayer.ExoPlayerImplInternal.doSomeWork(ExoPlayerImplInternal.java:1015) at androidx.media3.exoplayer.ExoPlayerImplInternal.handleMessage(ExoPlayerImplInternal.java:510) at android.os.Handler.dispatchMessage(Handler.java:102)  at android.os.Looper.loopOnce(Looper.java:233)  at android.os.Looper.loop(Looper.java:344)  at android.os.HandlerThread.run(HandlerThread.java:67)  Caused by: android.media.MediaCodec$CodecException: Error 0x80000000 ]

Media

{ "name": "test", "uri": "https://dastrm.myvideo.net.tw/Media/20210710/Smooth/VID01000000515.....", "drm_scheme": "widevine", "drm_license_uri": "https://widevine-license.....", "extension": "mpd", "drm_key_request_properties": { "acquirelicenseassertion": "CIvx2lPc5hG/Js7Ay.............." } }

Bug Report

  • [X] You will email the zip file produced by adb bugreport to [email protected] after filing this issue.

myvideotom avatar Jan 18 '24 07:01 myvideotom

log.txt 7:59:28.079 I [sendFeedbackInfo:L303] send:MM_FB_EventID#21001#ClientPid#31072#ReportLevel#0#timeUsage#16#videoComponent#c2.mtk.avc.decoder.secure#videoHeight#1080#videoMime#video/avc#videoWidth#1920 17:59:28.079 D setEvent event:atlas_event_atlasserver_feedback 17:59:28.081 D ~OplusCCodec [137]: (0x7ea3f59700) 17:59:28.081 D setEvent event:atlas_event_video_monitor_network_switch 17:59:28.082 D initiateShutdown [393]: (0x7ea3f5b4c0) keepComponentAllocated=0 17:59:28.082 D [c2.android.aac.decoder#631] MediaCodec discarded an unknown buffer 17:59:28.082 D videoDecoderReleased [eventTime=17.98, mediaPos=1283.09, window=0, period=0, c2.mtk.avc.decoder.secure] 17:59:28.082 D [c2.android.aac.decoder#631] MediaCodec discarded an unknown buffer 17:59:28.082 D [c2.android.aac.decoder#631] MediaCodec discarded an unknown buffer 17:59:28.082 D [c2.android.aac.decoder#631] MediaCodec discarded an unknown buffer 17:59:28.084 I onLastStrongRef automatically unlinking death recipients 17:59:28.085 D bufferpool2 0x7e396a9e28 : 0(0 size) total buffers - 0(0 size) used buffers - 0/4 (recycle/alloc) - 4/510 (fetch/transfer) 17:59:28.086 D ~OplusCCodec [137]: (0x7ea3f5b4c0) 17:59:28.087 D audioDecoderReleased [eventTime=17.98, mediaPos=1283.09, window=0, period=0, c2.android.aac.decoder] 17:59:28.104 E playerFailed [eventTime=17.99, mediaPos=1283.09, window=0, period=0, errorCode=ERROR_CODE_DECODING_FAILED androidx.media3.exoplayer.ExoPlaybackException: MediaCodecVideoRenderer error, index=0, format=Format(1, null, null, video/avc, avc1.4D4029, 7481447, null, [1920, 1080, -1.0], [-1, -1]), format_supported=YES at androidx.media3.exoplayer.ExoPlayerImplInternal.handleMessage(ExoPlayerImplInternal.java:586) at android.os.Handler.dispatchMessage(Handler.java:102) at android.os.Looper.loopOnce(Looper.java:233) at android.os.Looper.loop(Looper.java:344) at android.os.HandlerThread.run(HandlerThread.java:67) Caused by: androidx.media3.exoplayer.video.MediaCodecVideoDecoderException: Decoder failed: c2.mtk.avc.decoder.secure at androidx.media3.exoplayer.video.MediaCodecVideoRenderer.createDecoderException(MediaCodecVideoRenderer.java:1642) at androidx.media3.exoplayer.mediacodec.MediaCodecRenderer.render(MediaCodecRenderer.java:805) at androidx.media3.exoplayer.ExoPlayerImplInternal.doSomeWork(ExoPlayerImplInternal.java:1015) at androidx.media3.exoplayer.ExoPlayerImplInternal.handleMessage(ExoPlayerImplInternal.java:510) at android.os.Handler.dispatchMessage(Handler.java:102)  at android.os.Looper.loopOnce(Looper.java:233)  at android.os.Looper.loop(Looper.java:344)  at android.os.HandlerThread.run(HandlerThread.java:67)  Caused by: android.media.MediaCodec$CodecException: Error 0x80000000 ] 17:59:28.106 D loading [eventTime=18.00, mediaPos=1283.09, window=0, period=0, false]

myvideotom avatar Jan 18 '24 10:01 myvideotom

Hello @myvideotom,

Thank you for reporting your issue. We will need the bug report for this issue so if you can please send the full report to [email protected] then that would be great.

microkatz avatar Jan 18 '24 13:01 microkatz

I have sent an email to [email protected].

myvideotom avatar Jan 19 '24 06:01 myvideotom

hi microkatz,

  • Additional information: The original video format is converted from Smooth Streaming format to Dash format(on-the-fly)
  • May I ask why the flags do not include BundledChunkExtractor.java\createProgressiveMediaExtractor\FragmentedMp4Extractor.FLAG_WORKAROUND_EVERY_VIDEO_FRAME_IS_SYNC_FRAME? image

myvideotom avatar Jan 22 '24 08:01 myvideotom

I have reported issue related to Media3 (ExoPlayer) at https://github.com/androidx/media/issues.

The following is detailed information about this issue.

l Issue: https://github.com/androidx/media/issues/997

l Device Info: The files attached within (DeviceInfo1.png, DeviceInfo2.png).

l Step(video_send.mp4):

1.Open the Media3 demo app(https://github.com/androidx/media) and play the specified Widevine-DASH video. 2.As the video starts playing, perform the 'seekTo' action several times.

l Detail log: The files attached within(errorLogDetail.txt)

l Actual result

After executing the 'seekTo' command multiple times during video playback, the video starts at the specified timestamp. However, the screen tends to freeze with only audio playing, followed by the appearance of the "ERROR_CODE_DECODING_FAILED" error message.

error info: playerFailed [eventTime=35.75, mediaPos=550.65, window=0, period=0, errorCode=ERROR_CODE_DECODING_FAILED androidx.media3.exoplayer.ExoPlaybackException: MediaCodecVideoRenderer error, index=0, format=Format(1, null, null, video/avc, avc1.4D4029, 7481447, null, [1920, 1080, -1.0], [-1, -1]), format_supported=YES at androidx.media3.exoplayer.ExoPlayerImplInternal.handleMessage(ExoPlayerImplInternal.java:586) at android.os.Handler.dispatchMessage(Handler.java:102) at android.os.Looper.loopOnce(Looper.java:233) at android.os.Looper.loop(Looper.java:344) at android.os.HandlerThread.run(HandlerThread.java:67) Caused by: androidx.media3.exoplayer.video.MediaCodecVideoDecoderException: Decoder failed: c2.mtk.avc.decoder.secure at androidx.media3.exoplayer.video.MediaCodecVideoRenderer.createDecoderException(MediaCodecVideoRenderer.java:1642) at androidx.media3.exoplayer.mediacodec.MediaCodecRenderer.render(MediaCodecRenderer.java:805) at androidx.media3.exoplayer.ExoPlayerImplInternal.doSomeWork(ExoPlayerImplInternal.java:1015) at androidx.media3.exoplayer.ExoPlayerImplInternal.handleMessage(ExoPlayerImplInternal.java:510) at android.os.Handler.dispatchMessage(Handler.java:102) at android.os.Looper.loopOnce(Looper.java:233) at android.os.Looper.loop(Looper.java:344) at android.os.HandlerThread.run(HandlerThread.java:67) Caused by: android.media.MediaCodec$CodecException: Error 0x80000000 ]

l Dash(widevine) info:

l { "name": "test", "uri": https://dache.myvideo.net.tw/Media/20210710/Smooth/VID01000000515H/.... , "drm_scheme": "widevine", "drm_license_uri": https://widevine-license.drmkeyserver.com/widevine, "extension": "mpd", "drm_key_request_properties": { "acquirelicenseassertion": "CIvx2lPc5hG/Js7AyTLOAfwHRuSmFZfZJoDb+hHjnPXexe/Exh6x2+iq1mipVwHwmVJju41x4rliE/B0Uq2ZZ4Ar6HCJgU4OQn/uJUuftPC16fZMJuTqjwlspbrAub8JKCPzuqUHTNDI2kvPM1p+NH9ivZN9/ajuPwpsobAAUMBdmw6Iuh2rTUyPQujzIcDZLkv6ltYkYIIM15Sdi6IVBr7HOSZ1E/zk+2jbfQVpR/oLdYdhwduq/A/wk6obYDXVrEwjUuy0FGSJlhPcQp/Oe89tA9yc/MnDtZbNZ3L5VjIes6mQLCQj+Ptzibk/8fEBPkw2mP3+7vbnsQoMpRPPN2MhAfZVBhRjrFwr+sNDQXiUyd7nEMxW/v02Q1B28ie3zBIAGfrPh5zYhL89bCdEMh+Lz5Cb/1x4inPUPul79NQKg8GKUpNS86D8ysX56BnqIRDfEafz/24jtRqWFQsMxCsrz2rhp2Moxo8VugI+AhJAgyG2AYmk4wBp......" } }

Michael Katz @.***> 於 2024年1月18日 週四 21:52 寫道：

Hello @myvideotom https://github.com/myvideotom,

Thank you for reporting your issue. We will need the bug report for this issue so if you can please send the full report to @.*** then that would be great.

— Reply to this email directly, view it on GitHub https://github.com/androidx/media/issues/997#issuecomment-1898522009, or unsubscribe https://github.com/notifications/unsubscribe-auth/BFNYQSF4EZ6KLAIN3T4NTJ3YPESLRAVCNFSM6AAAAABB7YUXVCVHI2DSMVQWIX3LMV43OSLTON2WKQ3PNVWWK3TUHMYTQOJYGUZDEMBQHE . You are receiving this because you were mentioned.Message ID: @.***>

--------- beginning of system 14:18:10.911 E Failed to measure fs-verity, errno 1: /data/app/~~x-Nzpl_Ga5U1kD-cN-FNtQ==/androidx.media3.demo.main-EC0p_HacGbMP7x-8PfT7LQ==/base.apk 14:18:11.122 E Failed to measure fs-verity, errno 1: /data/app/~~x-Nzpl_Ga5U1kD-cN-FNtQ==/androidx.media3.demo.main-EC0p_HacGbMP7x-8PfT7LQ==/base.apk 14:18:12.914 E makeSurface ActivityRecord{c2c967e u0 androidx.media3.demo.main/.SampleChooserActivity t123} windowType 2 14:18:12.992 W Application androidx.media3.demo.main is waiting for the debugger on port 8100... 14:18:16.945 I ActivityThread: bindApplication delay 3962 androidx.media3.demo.main 11450 14:18:16.945 I Blocked msg = Package name: androidx.media3.demo.main [ schedGroup: 5 schedPolicy: 0 ] process the message: { when=-3s979ms what=110 target=android.app.ActivityThread$H obj=AppBindData{appInfo=ApplicationInfo{3e2d8b2 androidx.media3.demo.main}} } took 3962 ms 14:18:17.059 E sInstance is null, start a new sInstance 14:18:17.206 I ActivityThread: activityStart delay 260 androidx.media3.demo.main 11450 14:18:17.347 I JankWin:androidx.media3.demo.main/androidx.media3.demo.main.SampleChooserActivity type:1 action:9 cost:92 FV:16 16-92-0-14-0-0-43-0-29-1-1-0-0-0-6-1-1-0-1-0-0-0-0; SF:no-need cannot access task track path JV:cannot access jank version 14:23:31.690 E makeSurface ActivityRecord{3811cd1 u0 androidx.media3.demo.main/.SampleChooserActivity t124} windowType 2 14:23:31.789 W Application androidx.media3.demo.main is waiting for the debugger on port 8100... 14:23:34.953 I ActivityThread: bindApplication delay 3174 androidx.media3.demo.main 12032 14:23:34.954 I Blocked msg = Package name: androidx.media3.demo.main [ schedGroup: 5 schedPolicy: 0 ] process the message: { when=-3s190ms what=110 target=android.app.ActivityThread$H obj=AppBindData{appInfo=ApplicationInfo{c7415b9 androidx.media3.demo.main}} } took 3174 ms 14:23:35.032 E sInstance is null, start a new sInstance 14:23:35.159 I ActivityThread: activityStart delay 205 androidx.media3.demo.main 12032 14:23:35.211 I Skipped: false 1 cost 20.640013 refreshRate 11111111 processName androidx.media3.demo.main 14:23:35.275 I JankWin:androidx.media3.demo.main/androidx.media3.demo.main.SampleChooserActivity type:1 action:9 cost:81 FV:16 16-81-16-5-0-0-43-0-9-1-1-0-0-0-6-2-1-0-1-0-0-0-0; SF:no-need cannot access task track path JV:cannot access jank version 14:23:39.477 E makeSurface ActivityRecord{1ecb08b u0 androidx.media3.demo.main/.PlayerActivity t124} windowType 2 14:23:39.484 I Skipped: false 1 cost 16.82405 refreshRate 11111111 processName androidx.media3.demo.main 14:23:39.680 I ActivityThread: viewInflate delay 125 androidx.media3.demo.main:layout/player_activity 12032 14:23:39.684 I ActivityThread: activityStart delay 152 androidx.media3.demo.main 12032 14:23:39.793 I Skipped: false 23 cost 260.0956 refreshRate 11111111 processName androidx.media3.demo.main 14:23:39.839 E makeSurface ActivityRecord{1ecb08b u0 androidx.media3.demo.main/.PlayerActivity t124} windowType 2 14:23:39.840 E makeSurface ActivityRecord{1ecb08b u0 androidx.media3.demo.main/.PlayerActivity t124} windowType 2 14:23:39.841 I Skipped: false 3 cost 43.52832 refreshRate 11111111 processName androidx.media3.demo.main 14:23:39.842 E makeSurface ActivityRecord{3811cd1 u0 androidx.media3.demo.main/.SampleChooserActivity t124} windowType 2 14:23:39.842 I JankWin:androidx.media3.demo.main/androidx.media3.demo.main.PlayerActivity type:1 action:9 cost:301 FV:11 11-301-255-4-0-0-35-0-2-0-0-0-0-0-2-1-1-0-2-0-0-0-0; SF:no-need cannot access task track path JV:cannot access jank version 14:23:39.842 E makeSurface ActivityRecord{3811cd1 u0 androidx.media3.demo.main/.SampleChooserActivity t124} windowType 2 14:23:47.447 I Skipped: false 2 cost 24.458963 refreshRate 11111111 processName androidx.media3.demo.main 14:23:47.468 I Skipped: false 1 cost 11.805709 refreshRate 11111111 processName androidx.media3.demo.main 14:23:47.505 I Skipped: true 1 cost 16.3764 refreshRate 11111111 processName androidx.media3.demo.main 14:23:48.650 E makeSurface ActivityRecord{3811cd1 u0 androidx.media3.demo.main/.SampleChooserActivity t124} windowType 2 14:23:48.651 E makeSurface ActivityRecord{3811cd1 u0 androidx.media3.demo.main/.SampleChooserActivity t124} windowType 2 14:23:48.653 E makeSurface ActivityRecord{1ecb08b u0 androidx.media3.demo.main/.PlayerActivity t124 f}} windowType 2 14:23:48.654 E makeSurface ActivityRecord{1ecb08b u0 androidx.media3.demo.main/.PlayerActivity t124 f}} windowType 2 14:23:48.654 I Skipped: false 1 cost 17.81263 refreshRate 11111111 processName androidx.media3.demo.main 14:23:48.776 I Skipped: true 1 cost 17.563063 refreshRate 11111111 processName androidx.media3.demo.main 14:23:48.801 I Skipped: false 1 cost 21.16695 refreshRate 11111111 processName androidx.media3.demo.main 14:23:49.216 I Skipped: true 1 cost 16.565643 refreshRate 11111111 processName androidx.media3.demo.main 14:23:50.407 E makeSurface ActivityRecord{656f62e u0 androidx.media3.demo.main/.PlayerActivity t124} windowType 2 14:23:50.416 I Skipped: false 1 cost 15.138372 refreshRate 11111111 processName androidx.media3.demo.main 14:23:50.520 I ActivityThread: activityStart delay 90 androidx.media3.demo.main 12032 14:23:50.558 I Skipped: false 11 cost 127.82401 refreshRate 11111111 processName androidx.media3.demo.main 14:23:50.606 E makeSurface ActivityRecord{656f62e u0 androidx.media3.demo.main/.PlayerActivity t124} windowType 2 14:23:50.606 E makeSurface ActivityRecord{656f62e u0 androidx.media3.demo.main/.PlayerActivity t124} windowType 2 14:23:50.607 E makeSurface ActivityRecord{3811cd1 u0 androidx.media3.demo.main/.SampleChooserActivity t124} windowType 2 14:23:50.608 E makeSurface ActivityRecord{3811cd1 u0 androidx.media3.demo.main/.SampleChooserActivity t124} windowType 2 14:23:50.610 I Skipped: false 3 cost 41.708202 refreshRate 11111111 processName androidx.media3.demo.main 14:23:50.613 I JankWin:androidx.media3.demo.main/androidx.media3.demo.main.PlayerActivity type:1 action:9 cost:171 FV:11 11-175-122-5-0-0-37-0-2-1-0-0-0-0-1-1-1-0-2-0-0-0-0; SF:no-need cannot access task track path JV:cannot access jank version 14:23:51.370 I Skipped: false 1 cost 17.623327 refreshRate 16666667 processName androidx.media3.demo.main 14:23:57.846 I Skipped: false 1 cost 12.959524 refreshRate 11111111 processName androidx.media3.demo.main --------- beginning of main 14:24:02.167 D pause(33): prior state:STATE_ACTIVE output 21 stream 3 session 313 14:24:02.183 I Skipped: false 1 cost 11.902352 refreshRate 11111111 processName androidx.media3.demo.main 14:24:02.188 I [signalFlush:L713] 14:24:02.210 I query failed after returning 18 values (BAD_INDEX) 14:24:02.211 W query -- param skipped: index = 1342179345. 14:24:02.211 W query -- param skipped: index = 2415921170. 14:24:02.211 W query -- param skipped: index = 1610614798. 14:24:02.213 I [signalFlush:L713] 14:24:02.214 I query failed after returning 19 values (BAD_INDEX) 14:24:02.215 W query -- param skipped: index = 1342179345. 14:24:02.215 W query -- param skipped: index = 2415921170. 14:24:02.215 W query -- param skipped: index = 1610614798. 14:24:02.217 D flush(33): 0x794388f400, prior state:STATE_PAUSED 14:24:02.218 D stop(33): prior state:STATE_FLUSHED output 21 stream 3 session 313 14:24:02.219 D ~AudioTrack(33): 0x794388f400 14:24:02.219 D ~AudioTrack, releasing mStatus =0 session id 313 from 12032 on behalf of 12032 14:24:02.219 D stop(33): prior state:STATE_FLUSHED output 21 stream 3 session 313 14:24:02.220 D stopAndJoinCallbacks(33) done 14:24:02.492 D INSP: type=audio,case=1,set=0x79842f8200,stream_type=-1,sample_rate=44100,format=0x1,channel_mask=0x3,frame_count=11025,flags=0x0,notification_frames=0,session_id=313,transfer_type=3,uid=-1,pid=-1 14:24:02.492 D INSP: type=audio,case=1,attributes=1,content_type=0,usage=1,source=-1,flags=0xa00 14:24:02.492 D set() streamType -1, sampleRate 44100, format 0x1, channelMask 0x3, frameCount 11025, flags #0, notificationFrames 0, sessionId 313, transferType 3, uid -1, pid -1 cbf 1 14:24:02.492 D set(): Building AudioTrack with attributes: usage=1 content=0 flags=0xa00 tags=[] 14:24:02.492 D set(): 0x79842f8200, Create AudioTrackThread, tid = 13490 14:24:02.503 D createTrack_l(34): 0x79842f8200, mCblk = 0x7aa560d000, mLatency = 356, mAfLatency = 106, frameCount = 11025, mSampleRate = 44100, mFlags = 0x8, mReqFrameCount = 11025, mNotificationFramesAct = 5512 14:24:02.506 D setVolume(34): 0x79842f8200, left = 1.000000, right = 1.000000 14:24:02.506 D setVolume left 1.000 right 1.000 , callingPid 12032 14:24:02.891 D start(34): prior state:STATE_STOPPED output 21 stream 3 session 313 14:24:02.891 D start(34): 0x79842f8200, prior state:STATE_STOPPED 14:24:02.918 D getTimestamp_l(34): device stall time corrected using current time 860562409897 14:24:02.956 I 0x798439f600 c2.mtk.avc.decoder.secure 1920x1080 inputFps=28 outputFps=19 renderFps=1, discardFps=0 14:24:03.957 I 0x798439f600 c2.mtk.avc.decoder.secure 1920x1080 inputFps=30 outputFps=30 renderFps=28, discardFps=0 14:24:04.736 D pause(34): prior state:STATE_ACTIVE output 21 stream 3 session 313 14:24:04.745 I [signalFlush:L713] 14:24:04.749 I Skipped: false 1 cost 15.816764 refreshRate 11111111 processName androidx.media3.demo.main 14:24:04.774 I query failed after returning 18 values (BAD_INDEX) 14:24:04.774 W query -- param skipped: index = 1342179345. 14:24:04.774 W query -- param skipped: index = 2415921170. 14:24:04.774 W query -- param skipped: index = 1610614798. 14:24:04.776 I [signalFlush:L713] 14:24:04.779 I query failed after returning 19 values (BAD_INDEX) 14:24:04.779 W query -- param skipped: index = 1342179345. 14:24:04.779 W query -- param skipped: index = 2415921170. 14:24:04.779 W query -- param skipped: index = 1610614798. 14:24:04.783 D flush(34): 0x79842f8200, prior state:STATE_PAUSED 14:24:04.784 D stop(34): prior state:STATE_FLUSHED output 21 stream 3 session 313 14:24:04.785 D ~AudioTrack(34): 0x79842f8200 14:24:04.785 D ~AudioTrack, releasing mStatus =0 session id 313 from 12032 on behalf of 12032 14:24:04.785 D stop(34): prior state:STATE_FLUSHED output 21 stream 3 session 313 14:24:04.785 D stopAndJoinCallbacks(34) done 14:24:04.933 D INSP: type=audio,case=1,set=0x79842f8200,stream_type=-1,sample_rate=44100,format=0x1,channel_mask=0x3,frame_count=11025,flags=0x0,notification_frames=0,session_id=313,transfer_type=3,uid=-1,pid=-1 14:24:04.933 D INSP: type=audio,case=1,attributes=1,content_type=0,usage=1,source=-1,flags=0xa00 14:24:04.933 D set() streamType -1, sampleRate 44100, format 0x1, channelMask 0x3, frameCount 11025, flags #0, notificationFrames 0, sessionId 313, transferType 3, uid -1, pid -1 cbf 1 14:24:04.933 D set(): Building AudioTrack with attributes: usage=1 content=0 flags=0xa00 tags=[] 14:24:04.933 D set(): 0x79842f8200, Create AudioTrackThread, tid = 13501 14:24:04.943 D createTrack_l(35): 0x79842f8200, mCblk = 0x7aa560d100, mLatency = 356, mAfLatency = 106, frameCount = 11025, mSampleRate = 44100, mFlags = 0x8, mReqFrameCount = 11025, mNotificationFramesAct = 5512 14:24:04.945 D setVolume(35): 0x79842f8200, left = 1.000000, right = 1.000000 14:24:04.945 D setVolume left 1.000 right 1.000 , callingPid 12032 14:24:04.957 I 0x798439f600 c2.mtk.avc.decoder.secure 1920x1080 inputFps=23 outputFps=23 renderFps=23, discardFps=16 14:24:05.434 D start(35): prior state:STATE_STOPPED output 21 stream 3 session 313 14:24:05.434 D start(35): 0x79842f8200, prior state:STATE_STOPPED 14:24:05.475 D getTimestamp_l(35): device stall time corrected using current time 863119279820 14:24:05.957 I 0x798439f600 c2.mtk.avc.decoder.secure 1920x1080 inputFps=24 outputFps=3 renderFps=1, discardFps=0 14:24:06.958 I 0x798439f600 c2.mtk.avc.decoder.secure 1920x1080 inputFps=0 outputFps=0 renderFps=2, discardFps=0 14:24:07.958 I 0x798439f600 c2.mtk.avc.decoder.secure 1920x1080 inputFps=0 outputFps=0 renderFps=0, discardFps=0 14:24:08.840 E ViewRootImpl[PlayerActivity]#4 isEGL=1, mPendingRelease.size()=1, mMaxAcquiredBuffers=4, currentMaxAcquiredBufferCount=2 14:24:08.959 I 0x798439f600 c2.mtk.avc.decoder.secure 1920x1080 inputFps=0 outputFps=0 renderFps=0, discardFps=0 14:24:09.506 E ViewRootImpl[PlayerActivity]#4 isEGL=1, mPendingRelease.size()=2, mMaxAcquiredBuffers=4, currentMaxAcquiredBufferCount=2 14:24:09.960 I 0x798439f600 c2.mtk.avc.decoder.secure 1920x1080 inputFps=0 outputFps=0 renderFps=0, discardFps=0 14:24:10.960 I 0x798439f600 c2.mtk.avc.decoder.secure 1920x1080 inputFps=0 outputFps=0 renderFps=0, discardFps=0 14:24:10.960 I send video stutter message MM_FB_EventID#11002#ClientPid#12032#Component#c2.mtk.avc.decoder.secure#VideoInputFps#0#VideoOutputFps#0#VideoRenderFps#0#VideoHeight#1080#VideoWidth#1920#IsMediaPlayer#0#LogLevel#3#FpsArray#24/0/0/0/0/0#ModifiedVersion#5 14:24:11.312 W [c2.mtk.avc.decoder.secure] previous call to queue exceeded timeout 14:24:11.316 E Codec reported err 0x80000000, actionCode 0, while in state 6/STARTED 14:24:11.316 I SurfaceView[androidx.media3.demo.main/androidx.media3.demo.main.PlayerActivity]#5(BLAST Consumer)5 disconnect: api 3 14:24:11.321 E BLASTBufferItemConsumer::onDisconnect() 14:24:11.325 E Video codec error android.media.MediaCodec$CodecException: Error 0x80000000 14:24:11.334 E SurfaceView[androidx.media3.demo.main/androidx.media3.demo.main.PlayerActivity]#5(BLAST Consumer)5 cancelBuffer: BufferQueue has no connected producer 14:24:11.334 E SurfaceView[androidx.media3.demo.main/androidx.media3.demo.main.PlayerActivity]#5(BLAST Consumer)5 cancelBuffer: BufferQueue has no connected producer 14:24:11.334 E SurfaceView[androidx.media3.demo.main/androidx.media3.demo.main.PlayerActivity]#5(BLAST Consumer)5 cancelBuffer: BufferQueue has no connected producer 14:24:11.334 E SurfaceView[androidx.media3.demo.main/androidx.media3.demo.main.PlayerActivity]#5(BLAST Consumer)5 cancelBuffer: BufferQueue has no connected producer 14:24:11.334 E SurfaceView[androidx.media3.demo.main/androidx.media3.demo.main.PlayerActivity]#5(BLAST Consumer)5 cancelBuffer: BufferQueue has no connected producer 14:24:11.334 E SurfaceView[androidx.media3.demo.main/androidx.media3.demo.main.PlayerActivity]#5(BLAST Consumer)5 dequeueBuffer: BufferQueue has no connected producer 14:24:11.335 E SurfaceView[androidx.media3.demo.main/androidx.media3.demo.main.PlayerActivity]#5(BLAST Consumer)5 dequeueBuffer: BufferQueue has no connected producer 14:24:11.335 E SurfaceView[androidx.media3.demo.main/androidx.media3.demo.main.PlayerActivity]#5(BLAST Consumer)5 cancelBuffer: BufferQueue has no connected producer 14:24:11.335 E SurfaceView[androidx.media3.demo.main/androidx.media3.demo.main.PlayerActivity]#5(BLAST Consumer)5 cancelBuffer: BufferQueue has no connected producer 14:24:11.335 E SurfaceView[androidx.media3.demo.main/androidx.media3.demo.main.PlayerActivity]#5(BLAST Consumer)5 cancelBuffer: BufferQueue has no connected producer 14:24:11.335 E SurfaceView[androidx.media3.demo.main/androidx.media3.demo.main.PlayerActivity]#5(BLAST Consumer)5 cancelBuffer: BufferQueue has no connected producer 14:24:11.336 E SurfaceView[androidx.media3.demo.main/androidx.media3.demo.main.PlayerActivity]#5(BLAST Consumer)5 cancelBuffer: BufferQueue has no connected producer 14:24:11.336 E SurfaceView[androidx.media3.demo.main/androidx.media3.demo.main.PlayerActivity]#5(BLAST Consumer)5 cancelBuffer: BufferQueue has no connected producer 14:24:11.336 E SurfaceView[androidx.media3.demo.main/androidx.media3.demo.main.PlayerActivity]#5(BLAST Consumer)5 cancelBuffer: BufferQueue has no connected producer 14:24:11.336 E SurfaceView[androidx.media3.demo.main/androidx.media3.demo.main.PlayerActivity]#5(BLAST Consumer)5 cancelBuffer: BufferQueue has no connected producer 14:24:11.346 E SurfaceView[androidx.media3.demo.main/androidx.media3.demo.main.PlayerActivity]#5(BLAST Consumer)5 dequeueBuffer: BufferQueue has no connected producer 14:24:11.347 I onLastStrongRef automatically unlinking death recipients 14:24:11.350 E Playback error androidx.media3.exoplayer.ExoPlaybackException: MediaCodecVideoRenderer error, index=0, format=Format(1, null, null, video/avc, avc1.4D4029, 7481447, null, [1920, 1080, -1.0], [-1, -1]), format_supported=YES at androidx.media3.exoplayer.ExoPlayerImplInternal.handleMessage(ExoPlayerImplInternal.java:586) at android.os.Handler.dispatchMessage(Handler.java:102) at android.os.Looper.loopOnce(Looper.java:233) at android.os.Looper.loop(Looper.java:344) at android.os.HandlerThread.run(HandlerThread.java:67) Caused by: androidx.media3.exoplayer.video.MediaCodecVideoDecoderException: Decoder failed: c2.mtk.avc.decoder.secure at androidx.media3.exoplayer.video.MediaCodecVideoRenderer.createDecoderException(MediaCodecVideoRenderer.java:1642) at androidx.media3.exoplayer.mediacodec.MediaCodecRenderer.render(MediaCodecRenderer.java:805) at androidx.media3.exoplayer.ExoPlayerImplInternal.doSomeWork(ExoPlayerImplInternal.java:1015) at androidx.media3.exoplayer.ExoPlayerImplInternal.handleMessage(ExoPlayerImplInternal.java:510) at android.os.Handler.dispatchMessage(Handler.java:102)  at android.os.Looper.loopOnce(Looper.java:233)  at android.os.Looper.loop(Looper.java:344)  at android.os.HandlerThread.run(HandlerThread.java:67)  Caused by: android.media.MediaCodec$CodecException: Error 0x80000000 14:24:11.356 E SurfaceView[androidx.media3.demo.main/androidx.media3.demo.main.PlayerActivity]#5(BLAST Consumer)5 detachBuffer: BufferQueue has no connected producer 14:24:11.365 E Disable failed. java.lang.IllegalStateException at android.media.MediaCodec.native_flush(Native Method) at android.media.MediaCodec.flush(MediaCodec.java:2350) at androidx.media3.exoplayer.mediacodec.AsynchronousMediaCodecAdapter.flush(AsynchronousMediaCodecAdapter.java:230) at androidx.media3.exoplayer.mediacodec.MediaCodecRenderer.flushCodec(MediaCodecRenderer.java:869) at androidx.media3.exoplayer.mediacodec.MediaCodecRenderer.flushOrReleaseCodec(MediaCodecRenderer.java:862) at androidx.media3.exoplayer.mediacodec.MediaCodecRenderer.onDisabled(MediaCodecRenderer.java:702) at androidx.media3.exoplayer.video.MediaCodecVideoRenderer.onDisabled(MediaCodecVideoRenderer.java:612) at androidx.media3.exoplayer.BaseRenderer.disable(BaseRenderer.java:190) at androidx.media3.exoplayer.ExoPlayerImplInternal.disableRenderer(ExoPlayerImplInternal.java:1679) at androidx.media3.exoplayer.ExoPlayerImplInternal.resetInternal(ExoPlayerImplInternal.java:1420) at androidx.media3.exoplayer.ExoPlayerImplInternal.stopInternal(ExoPlayerImplInternal.java:1381) at androidx.media3.exoplayer.ExoPlayerImplInternal.handleMessage(ExoPlayerImplInternal.java:602) at android.os.Handler.dispatchMessage(Handler.java:102) at android.os.Looper.loopOnce(Looper.java:233) at android.os.Looper.loop(Looper.java:344) at android.os.HandlerThread.run(HandlerThread.java:67) 14:24:11.365 D pause(35): prior state:STATE_ACTIVE output 21 stream 3 session 313 14:24:11.407 D flush(35): 0x79842f8200, prior state:STATE_PAUSED 14:24:11.407 I [signalFlush:L713] 14:24:11.407 D stop(35): prior state:STATE_FLUSHED output 21 stream 3 session 313 14:24:11.409 D ~AudioTrack(35): 0x79842f8200 14:24:11.409 D ~AudioTrack, releasing mStatus =0 session id 313 from 12032 on behalf of 12032 14:24:11.409 D stop(35): prior state:STATE_FLUSHED output 21 stream 3 session 313 14:24:11.409 D stopAndJoinCallbacks(35) done 14:24:11.410 I query failed after returning 19 values (BAD_INDEX) 14:24:11.411 W query -- param skipped: index = 1342179345. 14:24:11.411 W query -- param skipped: index = 2415921170. 14:24:11.411 W query -- param skipped: index = 1610614798. 14:24:11.416 I [sendFeedbackInfo:L303] send:MM_FB_EventID#21001#ClientPid#12032#ReportLevel#0#timeUsage#21#videoComponent#c2.mtk.avc.decoder.secure#videoHeight#1080#videoMime#video/avc#videoWidth#1920 14:24:11.423 I onLastStrongRef automatically unlinking death recipients 14:24:11.448 E playerFailed [eventTime=20.91, mediaPos=1507.52, window=0, period=0, errorCode=ERROR_CODE_DECODING_FAILED androidx.media3.exoplayer.ExoPlaybackException: MediaCodecVideoRenderer error, index=0, format=Format(1, null, null, video/avc, avc1.4D4029, 7481447, null, [1920, 1080, -1.0], [-1, -1]), format_supported=YES at androidx.media3.exoplayer.ExoPlayerImplInternal.handleMessage(ExoPlayerImplInternal.java:586) at android.os.Handler.dispatchMessage(Handler.java:102) at android.os.Looper.loopOnce(Looper.java:233) at android.os.Looper.loop(Looper.java:344) at android.os.HandlerThread.run(HandlerThread.java:67) Caused by: androidx.media3.exoplayer.video.MediaCodecVideoDecoderException: Decoder failed: c2.mtk.avc.decoder.secure at androidx.media3.exoplayer.video.MediaCodecVideoRenderer.createDecoderException(MediaCodecVideoRenderer.java:1642) at androidx.media3.exoplayer.mediacodec.MediaCodecRenderer.render(MediaCodecRenderer.java:805) at androidx.media3.exoplayer.ExoPlayerImplInternal.doSomeWork(ExoPlayerImplInternal.java:1015) at androidx.media3.exoplayer.ExoPlayerImplInternal.handleMessage(ExoPlayerImplInternal.java:510) at android.os.Handler.dispatchMessage(Handler.java:102)  at android.os.Looper.loopOnce(Looper.java:233)  at android.os.Looper.loop(Looper.java:344)  at android.os.HandlerThread.run(HandlerThread.java:67)  Caused by: android.media.MediaCodec$CodecException: Error 0x80000000 ] 14:32:58.205 I onLastStrongRef automatically unlinking death recipients 14:32:58.209 I onLastStrongRef automatically unlinking death recipients 14:33:03.377 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; CpuFgTime = 0 ms; CpuBgTime = 0 ms. 14:33:03.377 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; CpuKernelFgTime = 0 ms; CpuKernelBgTime = 0 ms. 14:33:03.377 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; CpuUserFgTime = 0 ms ; CpuUserBgTime = 0 ms. 14:33:03.378 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; CpuFgPower = 0.0 mAh.; CpuBgPower = 0.0 mAh. 14:33:03.378 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; AlarmFgWakeupCount = 0; AlarmBgWakeupCount = 0 14:33:03.378 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; JobFgCount = 0; JobBgCount = 0; JobFgTime = 0; JobBgTime = 0 14:33:03.378 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; SyncFgCount = 0; SyncBgCount = 0; SyncFgTime = 0; SyncBgTime = 0 14:33:03.378 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; WakeLockFgTime = 0 ms; WakeLockFgPower = 0.0 mAh.; WakeLockBgTime = 0 ms; WakeLockBgPower = 0.0 mAh. 14:33:03.378 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; ScreenFgTime = 871049 ms; ScreenFgPower = 21.776225 mAh. 14:33:03.378 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; FgWifiRxBytes = 30790936 Bytes; BgWifiRxBytes = 0 Bytes; FgWifiTxBytes = 433406 Bytes; BgWifiTxBytes = 0 Bytes; FgWifiRxPackets = 20786 ; BgWifiRxPackets = 0 ; FgWifiTxPackets = 10578 ; BgWifiTxPackets = 0 ; FgWiFiScanTimeMs = 0 ; BgWiFiScanTimeMs = 0 ; FgWiFiBatchScanTimeMs = 0 ; BgWiFiBatchScanTimeMs = 0 14:33:03.378 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; FgWifiRxPacketsPower = 0.20569480955600739 mAh; BgWifiRxPacketsPower = 0.0 mAh; FgWifiTxPacketsPower = 0.10467813909053802 mAh; BgWifiTxPacketsPower = 0.0 mAh; FgWifiScanPower = 0.0 mAh; BgWifiScanPower = 0.0 mAh; FgWifiBatchScanPower = 0.0 mAh; BgWifiBatchScanPower = 0.0 mAh; FgWiFiTotalPower = 0.3103729486465454 mAh; BgWiFiTotalPower = 0.0 mAh. 14:33:03.378 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; FgGpsTime = 0 ms; BgGpsTime = 0 ms. 14:33:03.378 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; FgGpsPower = 0.0 mAh; BgGpsPower = 0.0 mAh. 14:33:03.378 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; FgSensorTime = 0 ms; BgSensorTime = 0 ms. 14:33:03.378 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; FgSensorPower = 0.0 mAh; BgSensorPower = 0.0 mAh. 14:33:03.378 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; FgCameraTime = 0 ms; BgCameraTime = 0 ms 14:33:03.378 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; FgCameraPower = 0.0 mAh; BgCameraPower = 0.0 mAh 14:33:03.378 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; FgFlashLightTime = 0 ms; BgFlashLightTime = 0 ms 14:33:03.378 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; FgFlashLightPower = 0.0 mAh; BgFlashLightPower = 0.0 mAh 14:33:05.179 E Could not remove dir '/data/data/androidx.media3.demo.main/code_cache/.ll/': No such file or directory 14:33:07.382 E makeSurface ActivityRecord{d732e15 u0 androidx.media3.demo.main/.SampleChooserActivity t125} windowType 2 14:33:07.393 I SetHeapTaggingLevel: tag level set to 0 14:33:07.397 I Late-enabling -Xcheck:jni 14:33:07.416 E Unknown bits set in runtime_flags: 0x40000000 14:33:07.416 I Using CollectorTypeCC GC. 14:33:07.424 I Mark active for pid=12375? true 14:33:07.436 W type=1400 audit(0.0:131): avc: granted { execute } for path="/data/user/0/androidx.media3.demo.main/code_cache/startup_agents/4615cd08-agent.so" dev="dm-21" ino=25993 scontext=u:r:untrusted_app_29:s0:c7,c257,c512,c768 tcontext=u:object_r:app_data_file:s0:c7,c257,c512,c768 tclass=file app=androidx.media3.demo.main 14:33:07.444 W DexFile /data/data/androidx.media3.demo.main/code_cache/.studio/instruments-68a2b4fe.jar is in boot class path but is not in a known location 14:33:07.450 W Redefining intrinsic method java.lang.Thread java.lang.Thread.currentThread(). This may cause the unexpected use of the original definition of java.lang.Thread java.lang.Thread.currentThread()in methods that have already been compiled. 14:33:07.450 W Redefining intrinsic method boolean java.lang.Thread.interrupted(). This may cause the unexpected use of the original definition of boolean java.lang.Thread.interrupted()in methods that have already been compiled. 14:33:07.453 I get feature:IOplusAutoResolutionFeature 14:33:07.453 I getOplusAutoResolutionFeature 14:33:07.464 W Application androidx.media3.demo.main is waiting for the debugger on port 8100... 14:33:07.465 I Sending WAIT chunk 14:33:07.939 E CheckNotificationCanBeSetSecond: check androidx.media3.demo.main , Notification master switch on, unable to pop dialog 14:33:08.665 I Debugger has connected 14:33:08.666 I waiting for debugger to settle... 14:33:08.866 I waiting for debugger to settle... 14:33:09.066 I waiting for debugger to settle... 14:33:09.266 I waiting for debugger to settle... 14:33:09.468 I waiting for debugger to settle... 14:33:09.669 I waiting for debugger to settle... 14:33:09.871 I waiting for debugger to settle... 14:33:10.073 I waiting for debugger to settle... 14:33:10.273 I waiting for debugger to settle... 14:33:10.476 I debugger has settled (1411) 14:33:10.622 I Milliseconds spent on init(): 141 14:33:10.707 I VM with version 2.1.0 has multidex support 14:33:10.707 I Installing application 14:33:10.707 I VM has multidex support, MultiDex support library is disabled. 14:33:10.718 E >>> msg's executing time is too long 14:33:10.718 E Blocked msg = { when=-3s276ms what=110 target=android.app.ActivityThread$H obj=AppBindData{appInfo=ApplicationInfo{c7415b9 androidx.media3.demo.main}} } , cost = 3266 ms 14:33:10.718 E >>>Current msg List is: 14:33:10.718 E Current msg <1> = { when=-3s274ms what=159 target=android.app.ActivityThread$H @.*** } 14:33:10.718 E Current msg <2> = { when=-3s274ms what=159 target=android.app.ActivityThread$H @.*** } 14:33:10.719 E Current msg <3> = { when=-2s866ms what=149 target=android.app.ActivityThread$H @.*** } 14:33:10.719 E Current msg <4> = { when=-2s274ms what=0 target=android.app.ActivityThread$H callback=android.app.ActivityThread$$ExternalSyntheticLambda1 } 14:33:10.719 E Current msg <5> = { when=-3ms what=164 target=android.app.ActivityThread$H obj=androidx.media3.demo.main } 14:33:10.719 E >>>CURRENT MSG DUMP OVER<<< 14:33:10.720 I ActivityThread: bindApplication delay 3268 androidx.media3.demo.main 12375 14:33:10.721 I Blocked msg = Package name: androidx.media3.demo.main [ schedGroup: 5 schedPolicy: 0 ] process the message: { when=-3s278ms what=110 target=android.app.ActivityThread$H obj=AppBindData{appInfo=ApplicationInfo{c7415b9 androidx.media3.demo.main}} } took 3268 ms 14:33:10.737 E Loaded layer handle (12515137711863904991) for layer /my_product/lib64/libcolorx-loader.so 14:33:10.737 E Looking for entrypoint ColorX_Check 14:33:10.737 E Found ColorX_Check for /my_product/lib64/libcolorx-loader.so 14:33:10.738 E Check failed 14:33:10.739 W qt_process_init() called 14:33:10.739 E [QT]file does not exist 14:33:10.739 W Support!! 14:33:10.740 E [QT]file does not exist 14:33:10.768 I Unknow feature:IOplusTextViewRTLUtilForUG 14:33:10.788 I get feature:IOplusDynamicVsyncFeature 14:33:10.788 I get feature:IOplusDynamicVsyncFeature 14:33:10.788 I get feature:IOplusDynamicVsyncFeature 14:33:10.803 W Accessing hidden method Landroid/view/View;->computeFitSystemWindows(Landroid/graphics/Rect;Landroid/graphics/Rect;)Z (unsupported, reflection, allowed) 14:33:10.803 W Accessing hidden method Landroid/view/ViewGroup;->makeOptionalFitsSystemWindows()V (unsupported, reflection, allowed) 14:33:10.805 E sInstance is null, start a new sInstance 14:33:10.850 I Considering local module com.google.android.gms.cronet_dynamite:0 and remote module com.google.android.gms.cronet_dynamite:2611 14:33:10.850 I Selected remote version of com.google.android.gms.cronet_dynamite, version >= 2611 14:33:10.861 W ClassLoader referenced unknown path: 14:33:10.891 E OplusMultiAppImpl 14:33:10.904 I Cronet version: 119.0.6045.31, arch: aarch64 14:33:10.926 I ActivityThread: activityStart delay 205 androidx.media3.demo.main 12375 14:33:10.933 I [static] sSurfaceFactory = @.*** 14:33:10.935 I [static] sMsyncFactory = @.*** 14:33:10.954 E ofbOpen failed with error=No such file or directory 14:33:10.954 E sysOpen failed with error=No such file or directory 14:33:10.968 I PowerHalWrapper.getInstance 14:33:10.979 I Skipped: false 1 cost 19.699408 refreshRate 11111111 processName androidx.media3.demo.main 14:33:11.003 I connect: controlledByApp=false 14:33:11.003 I [ViewRootImpl[SampleChooserActivity]#0] constructor() 14:33:11.027 I ViewRootImpl[SampleChooserActivity]#0(BLAST Consumer)0 connect: api=1 producerControlledByApp=true 14:33:11.040 E fbcNotifyFrameComplete error: undefined symbol: fbcNotifyFrameComplete 14:33:11.040 E fbcNotifyNoRender error: undefined symbol: fbcNotifyNoRender 14:33:11.045 I JankWin:androidx.media3.demo.main/androidx.media3.demo.main.SampleChooserActivity type:1 action:9 cost:82 FV:16 16-82-16-4-0-0-46-0-9-2-0-0-0-0-7-1-1-0-1-0-0-0-0; SF:no-need cannot access task track path JV:cannot access jank version 14:33:11.148 E ViewRootImpl[SampleChooserActivity]#0 isEGL=1, mPendingRelease.size()=1, mMaxAcquiredBuffers=4, currentMaxAcquiredBufferCount=2 14:33:12.488 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; CpuFgTime = 0 ms; CpuBgTime = 0 ms. 14:33:12.489 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; CpuKernelFgTime = 0 ms; CpuKernelBgTime = 0 ms. 14:33:12.489 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; CpuUserFgTime = 0 ms ; CpuUserBgTime = 0 ms. 14:33:12.489 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; CpuFgPower = 0.0 mAh.; CpuBgPower = 0.0 mAh. 14:33:12.489 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; AlarmFgWakeupCount = 0; AlarmBgWakeupCount = 0 14:33:12.489 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; JobFgCount = 0; JobBgCount = 0; JobFgTime = 0; JobBgTime = 0 14:33:12.489 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; SyncFgCount = 0; SyncBgCount = 0; SyncFgTime = 0; SyncBgTime = 0 14:33:12.489 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; WakeLockFgTime = 0 ms; WakeLockFgPower = 0.0 mAh.; WakeLockBgTime = 0 ms; WakeLockBgPower = 0.0 mAh. 14:33:12.489 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; ScreenFgTime = 871049 ms; ScreenFgPower = 21.776225 mAh. 14:33:12.489 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; FgWifiRxBytes = 30790936 Bytes; BgWifiRxBytes = 141413 Bytes; FgWifiTxBytes = 433406 Bytes; BgWifiTxBytes = 1990 Bytes; FgWifiRxPackets = 20786 ; BgWifiRxPackets = 96 ; FgWifiTxPackets = 10578 ; BgWifiTxPackets = 48 ; FgWiFiScanTimeMs = 0 ; BgWiFiScanTimeMs = 0 ; FgWiFiBatchScanTimeMs = 0 ; BgWiFiBatchScanTimeMs = 0 14:33:12.489 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; FgWifiRxPacketsPower = 0.20569480955600739 mAh; BgWifiRxPacketsPower = 5.066666635684669E-4 mAh; FgWifiTxPacketsPower = 0.10467813909053802 mAh; BgWifiTxPacketsPower = 2.5333333178423345E-4 mAh; FgWifiScanPower = 0.0 mAh; BgWifiScanPower = 0.0 mAh; FgWifiBatchScanPower = 0.0 mAh; BgWifiBatchScanPower = 0.0 mAh; FgWiFiTotalPower = 0.3103729486465454 mAh; BgWiFiTotalPower = 7.599999953527004E-4 mAh. 14:33:12.489 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; FgGpsTime = 0 ms; BgGpsTime = 0 ms. 14:33:12.489 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; FgGpsPower = 0.0 mAh; BgGpsPower = 0.0 mAh. 14:33:12.489 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; FgSensorTime = 0 ms; BgSensorTime = 0 ms. 14:33:12.489 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; FgSensorPower = 0.0 mAh; BgSensorPower = 0.0 mAh. 14:33:12.489 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; FgCameraTime = 0 ms; BgCameraTime = 0 ms 14:33:12.489 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; FgCameraPower = 0.0 mAh; BgCameraPower = 0.0 mAh 14:33:12.489 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; FgFlashLightTime = 0 ms; BgFlashLightTime = 0 ms 14:33:12.489 E AppStats: Uid = 10263; pkgName = androidx.media3.demo.main; FgFlashLightPower = 0.0 mAh; BgFlashLightPower = 0.0 mAh 14:33:44.190 W A resource failed to call close. 14:33:44.188 W type=1400 audit(0.0:132): avc: denied { getopt } for path="/dev/socket/usap_pool_primary" scontext=u:r:untrusted_app_29:s0:c7,c257,c512,c768 tcontext=u:r:zygote:s0 tclass=unix_stream_socket permissive=0 app=androidx.media3.demo.main 14:34:05.637 W Accessing hidden method Landroid/media/AudioTrack;->getLatency()I (unsupported, reflection, allowed) 14:34:05.642 I Skipped: true 1 cost 16.864525 refreshRate 11111111 processName androidx.media3.demo.main 14:34:06.526 W Unsupported mime audio/x-adpcm-dvi-ima 14:34:06.526 W Unsupported mime audio/x-adpcm-dvi-ima 14:34:06.526 W Unsupported mime audio/x-adpcm-ms 14:34:06.527 W Unsupported mime audio/x-adpcm-ms 14:34:06.527 W Unsupported mime audio/alac 14:34:06.528 W Unsupported mime audio/alac 14:34:06.528 W Unsupported mime audio/ape 14:34:06.528 W Unsupported mime audio/ape 14:34:06.529 W Unsupported mime audio/x-ms-wma 14:34:06.529 W Unsupported mime audio/x-ms-wma 14:34:06.544 W Unsupported mime audio/ffmpeg 14:34:06.544 W Unsupported mime video/ffmpeg 14:34:06.557 W Unsupported mime image/vnd.android.heic 14:34:06.558 W Unsupported mime image/vnd.android.heic 14:34:06.559 W Unsupported mime image/vnd.android.heic 14:34:06.560 W Unsupported mime image/vnd.android.heic 14:34:06.571 W Unsupported mime video/x-ms-wmv 14:34:06.572 W Unsupported mime video/x-ms-wmv 14:34:06.601 I Skipped: false 7 cost 87.87931 refreshRate 11111111 processName androidx.media3.demo.main 14:34:06.630 I get feature:IOplusDynamicVsyncFeature 14:34:06.631 I get feature:IOplusDynamicVsyncFeature 14:34:06.631 I get feature:IOplusDynamicVsyncFeature 14:34:06.693 I Skipped: false 6 cost 68.64172 refreshRate 11111111 processName androidx.media3.demo.main 14:34:06.714 I get feature:IOplusDynamicVsyncFeature 14:34:06.714 I get feature:IOplusDynamicVsyncFeature 14:34:06.714 I get feature:IOplusDynamicVsyncFeature 14:34:06.793 I get feature:IOplusDynamicVsyncFeature 14:34:06.793 I get feature:IOplusDynamicVsyncFeature 14:34:06.793 I get feature:IOplusDynamicVsyncFeature 14:34:06.941 I Background young concurrent copying GC freed 44634(1714KB) AllocSpace objects, 1(20KB) LOS objects, 27% free, 4627KB/6393KB, paused 785us,721us total 106.437ms 14:34:06.951 I connect: controlledByApp=false 14:34:06.952 I [ViewRootImpl[Download]#1] constructor() 14:34:06.957 I ViewRootImpl[Download]#1(BLAST Consumer)1 connect: api=1 producerControlledByApp=true 14:34:07.075 I Skipped 33 frames! The application may be doing too much work on its main thread. 14:34:07.076 I Skipped: false 33 cost 373.86536 refreshRate 11111111 processName androidx.media3.demo.main 14:34:07.106 I JankWin:androidx.media3.demo.main/androidx.media3.demo.main.SampleChooserActivity type:1 action:6 cost:382 FV:11 11-385-366-7-0-1-0-0-3-3-0-0-0-0-0-0-0-0-1-0-0-1-0; SF:no-need cannot access task track path JV:cannot access jank version 14:34:07.239 I Skipped: true 2 cost 30.06942 refreshRate 11111111 processName androidx.media3.demo.main 14:34:21.269 I Skipped: true 3 cost 37.093147 refreshRate 11111111 processName androidx.media3.demo.main 14:34:21.280 I ViewRootImpl[Download]#1(BLAST Consumer)1 disconnect: api 1 14:34:21.281 E BLASTBufferItemConsumer::onDisconnect() 14:34:21.291 I [ViewRootImpl[Download]#1] destructor() 14:34:21.291 I ViewRootImpl[Download]#1(BLAST Consumer)1 disconnect 14:34:21.301 I Skipped: true 2 cost 23.30678 refreshRate 11111111 processName androidx.media3.demo.main 14:34:21.464 I releaseBufferCallbackThunk bufferId:53150220288007 framenumber:17 blastBufferQueue is dead 14:34:31.134 E makeSurface ActivityRecord{2103d44 u0 androidx.media3.demo.main/.PlayerActivity t125} windowType 2 14:34:31.228 I get feature:IOplusDynamicVsyncFeature 14:34:31.229 I get feature:IOplusDynamicVsyncFeature 14:34:31.229 I get feature:IOplusDynamicVsyncFeature 14:34:31.256 I get feature:IOplusDynamicVsyncFeature 14:34:31.256 I get feature:IOplusDynamicVsyncFeature 14:34:31.256 I get feature:IOplusDynamicVsyncFeature 14:34:31.279 I ActivityThread: activityStart delay 121 androidx.media3.demo.main 12375 14:34:31.288 I found instance=clearkey @.::IDrmFactory 14:34:31.288 I found instance=widevine @.::IDrmFactory 14:34:31.291 I Init 8ce9c8d [AndroidXMedia3/1.0.1] [OP5339L1, CPH2359, OPPO, 31] 14:34:31.346 I Skipped: false 16 cost 184.91832 refreshRate 11111111 processName androidx.media3.demo.main 14:34:31.353 W DefaultDrmSessionManager accessed before setPlayer(), possibly on the wrong thread. java.lang.IllegalStateException at androidx.media3.exoplayer.drm.DefaultDrmSessionManager.verifyPlaybackThread(DefaultDrmSessionManager.java:827) at androidx.media3.exoplayer.drm.DefaultDrmSessionManager.prepare(DefaultDrmSessionManager.java:474) at androidx.media3.exoplayer.dash.DashMediaSource.prepareSourceInternal(DashMediaSource.java:452) at androidx.media3.exoplayer.source.BaseMediaSource.prepareSource(BaseMediaSource.java:223) at androidx.media3.exoplayer.source.CompositeMediaSource.prepareChildSource(CompositeMediaSource.java:122) at androidx.media3.exoplayer.source.WrappingMediaSource.prepareChildSource(WrappingMediaSource.java:214) at androidx.media3.exoplayer.source.MaskingMediaSource.createPeriod(MaskingMediaSource.java:110) at androidx.media3.exoplayer.MediaSourceList.createPeriod(MediaSourceList.java:316) at androidx.media3.exoplayer.MediaPeriodHolder.createMediaPeriod(MediaPeriodHolder.java:436) at androidx.media3.exoplayer.MediaPeriodHolder.(MediaPeriodHolder.java:111) at androidx.media3.exoplayer.MediaPeriodQueue.enqueueNextMediaPeriodHolder(MediaPeriodQueue.java:192) at androidx.media3.exoplayer.ExoPlayerImplInternal.maybeUpdateLoadingPeriod(ExoPlayerImplInternal.java:2007) at androidx.media3.exoplayer.ExoPlayerImplInternal.updatePeriods(ExoPlayerImplInternal.java:1994) at androidx.media3.exoplayer.ExoPlayerImplInternal.doSomeWork(ExoPlayerImplInternal.java:982) at androidx.media3.exoplayer.ExoPlayerImplInternal.handleMessage(ExoPlayerImplInternal.java:510) at android.os.Handler.dispatchMessage(Handler.java:102) at android.os.Looper.loopOnce(Looper.java:233) at android.os.Looper.loop(Looper.java:344) at android.os.HandlerThread.run(HandlerThread.java:67) 14:34:31.372 I connect: controlledByApp=false 14:34:31.372 I [ViewRootImpl[PlayerActivity]#2] constructor() 14:34:31.375 I ViewRootImpl[PlayerActivity]#2(BLAST Consumer)2 connect: api=1 producerControlledByApp=true 14:34:31.384 I connect: controlledByApp=false 14:34:31.384 I [SurfaceView[androidx.media3.demo.main/androidx.media3.demo.main.PlayerActivity]#3] constructor() 14:34:31.401 E makeSurface ActivityRecord{2103d44 u0 androidx.media3.demo.main/.PlayerActivity t125} windowType 2 14:34:31.402 E makeSurface ActivityRecord{2103d44 u0 androidx.media3.demo.main/.PlayerActivity t125} windowType 2 14:34:31.402 I Skipped: false 4 cost 52.956528 refreshRate 11111111 processName androidx.media3.demo.main 14:34:31.404 E makeSurface ActivityRecord{d732e15 u0 androidx.media3.demo.main/.SampleChooserActivity t125} windowType 2 14:34:31.404 I JankWin:androidx.media3.demo.main/androidx.media3.demo.main.PlayerActivity type:1 action:9 cost:230 FV:11 11-231-177-7-0-0-39-0-2-1-0-0-0-0-1-1-1-0-1-0-0-0-0; SF:no-need cannot access task track path JV:cannot access jank version 14:34:31.404 E makeSurface ActivityRecord{d732e15 u0 androidx.media3.demo.main/.SampleChooserActivity t125} windowType 2 14:34:31.506 W Cleared Reference was only reachable from finalizer (only reported once) 14:34:31.772 I ViewRootImpl[SampleChooserActivity]#0(BLAST Consumer)0 disconnect: api 1 14:34:31.773 E BLASTBufferItemConsumer::onDisconnect() 14:34:31.777 I [ViewRootImpl[SampleChooserActivity]#0] destructor() 14:34:31.777 I ViewRootImpl[SampleChooserActivity]#0(BLAST Consumer)0 disconnect 14:34:31.823 I releaseBufferCallbackThunk bufferId:53150220288000 framenumber:97 blastBufferQueue is dead 14:34:31.948 E Reading a NULL string not supported here. 14:34:32.049 I Creating an asynchronous MediaCodec adapter for track type video 14:34:32.052 W Access denied finding property "vendor.oplus.media.vpp.stutter" 14:34:32.055 I Available Codec2 services: "default" "software" 14:34:32.058 I setting up 'default' as default (vendor) store 14:34:32.070 I Created component [c2.mtk.avc.decoder.secure] 14:34:32.078 I query failed after returning 18 values (BAD_INDEX) 14:34:32.078 W expected specified color aspects (2:0:0:0) 14:34:32.090 I MediaCodec will operate in async mode 14:34:32.092 I [c2.mtk.avc.decoder.secure] configure, format : AMessage(what = 0x00000000) = { Buffer csd-1 = { 00000000: 00 00 00 01 68 c9 23 52 ....h.#R } int32_t max-height = 1080 string log-session-id = "BjvBdBGDIaK8iBy8" int32_t max-width = 1920 string mime = "video/avc" int32_t width = 854 int32_t priority = 0 int32_t rotation-degrees = 0 int32_t max-input-size = 1566720 int32_t height = 480 Buffer csd-0 = { 00000000: 00 00 00 01 67 42 80 1f 96 52 81 b0 7b cd e0 2d ....gB...R..{..- 00000010: 10 00 00 3e 90 00 0e a6 0e 08 00 05 b8 d0 00 19 ...>............ 00000020: a2 9f c6 38 3b 44 24 b0 ...8;D$. } } 14:34:32.092 I SurfaceView[androidx.media3.demo.main/androidx.media3.demo.main.PlayerActivity]#3(BLAST Consumer)3 connect: api=3 producerControlledByApp=true 14:34:32.092 I [c2.mtk.avc.decoder.secure] setting surface generation to 12672001 14:34:32.092 I SurfaceView[androidx.media3.demo.main/androidx.media3.demo.main.PlayerActivity]#3(BLAST Consumer)3 disconnect: api 3 14:34:32.092 E BLASTBufferItemConsumer::onDisconnect() 14:34:32.092 I SurfaceView[androidx.media3.demo.main/androidx.media3.demo.main.PlayerActivity]#3(BLAST Consumer)3 connect: api=3 producerControlledByApp=true 14:34:32.098 W query -- param skipped: index = 1107298332. 14:34:32.098 W This behavior is subject to change. It is recommended that app developers double check whether the requested max input size is in reasonable range. 14:34:32.098 I query failed after returning 18 values (BAD_INDEX) 14:34:32.104 W query -- param skipped: index = 1342179345. 14:34:32.104 W query -- param skipped: index = 2415921170. 14:34:32.104 W query -- param skipped: index = 1610614798. 14:34:32.107 E ioctl c0044901 failed with code -1: Invalid argument 14:34:32.159 I [c2.mtk.avc.decoder.secure#312] Created output block pool with allocatorID 18 => poolID 19 - OK 14:34:32.201 I Creating an asynchronous MediaCodec adapter for track type audio 14:34:32.203 W Access denied finding property "vendor.oplus.media.vpp.stutter" 14:34:32.207 I setting up 'default' as default (vendor) store 14:34:32.215 I Created component [c2.android.aac.decoder] 14:34:32.223 I query failed after returning 19 values (BAD_INDEX) 14:34:32.227 I MediaCodec will operate in async mode 14:34:32.227 I [c2.android.aac.decoder] configure, format : AMessage(what = 0x00000000) = { string log-session-id = "BjvBdBGDIaK8iBy8" int32_t sample-rate = 44100 string mime = "audio/mp4a-latm" int32_t channel-count = 2 int32_t priority = 0 Buffer csd-0 = { 00000000: 12 10 00 00 .... } } 14:34:32.230 W query -- param skipped: index = 1107298332. 14:34:32.230 I query failed after returning 19 values (BAD_INDEX) 14:34:32.232 W query -- param skipped: index = 1342179345. 14:34:32.232 W query -- param skipped: index = 2415921170. 14:34:32.232 W query -- param skipped: index = 1610614798. 14:34:32.234 I [c2.android.aac.decoder#905] Created output block pool with allocatorID 16 => poolID 37 - OK 14:34:32.423 E ViewRootImpl[PlayerActivity]#2 isEGL=1, mPendingRelease.size()=1, mMaxAcquiredBuffers=4, currentMaxAcquiredBufferCount=2 14:34:32.439 E ViewRootImpl[PlayerActivity]#2 isEGL=1, mPendingRelease.size()=2, mMaxAcquiredBuffers=4, currentMaxAcquiredBufferCount=2 14:34:33.165 I 0x7989306000 c2.mtk.avc.decoder.secure 854x480 inputFps=2 outputFps=4 renderFps=0, discardFps=0 14:34:33.222 D INSP: type=audio,case=1,set=0x795f88c600,stream_type=-1,sample_rate=44100,format=0x1,channel_mask=0x3,frame_count=11025,flags=0x0,notification_frames=0,session_id=321,transfer_type=3,uid=-1,pid=-1 14:34:33.222 D INSP: type=audio,case=1,attributes=1,content_type=0,usage=1,source=-1,flags=0xa00 14:34:33.222 D set() streamType -1, sampleRate 44100, format 0x1, channelMask 0x3, frameCount 11025, flags #0, notificationFrames 0, sessionId 321, transferType 3, uid -1, pid -1 cbf 1 14:34:33.222 D set(): Building AudioTrack with attributes: usage=1 content=0 flags=0xa00 tags=[] 14:34:33.222 D set(): 0x795f88c600, Create AudioTrackThread, tid = 15099 14:34:33.229 D createTrack_l(36): 0x795f88c600, mCblk = 0x7aa560d000, mLatency = 378, mAfLatency = 128, frameCount = 11025, mSampleRate = 44100, mFlags = 0x8, mReqFrameCount = 11025, mNotificationFramesAct = 5512 14:34:33.234 D setVolume(36): 0x795f88c600, left = 1.000000, right = 1.000000 14:34:33.234 D setVolume left 1.000 right 1.000 , callingPid 12375 14:34:33.250 W mapFormat: no mediaType information 14:34:33.259 D start(36): prior state:STATE_STOPPED output 21 stream 3 session 321 14:34:33.259 D start(36): 0x795f88c600, prior state:STATE_STOPPED 14:34:33.365 D getTimestamp_l(36): stale timestamp time corrected, currentTimeNanos: 872052780974 < limitNs: 1490775226550 < mStartNs: 1490903226550 14:34:34.165 I 0x7989306000 c2.mtk.avc.decoder.secure 854x480 inputFps=52 outputFps=40 renderFps=25, discardFps=0 14:34:35.165 I 0x7989306000 c2.mtk.avc.decoder.secure 854x480 inputFps=30 outputFps=30 renderFps=30, discardFps=0 14:34:35.339 E ViewRootImpl[PlayerActivity]#2 isEGL=1, mPendingRelease.size()=1, mMaxAcquiredBuffers=4, currentMaxAcquiredBufferCount=2 14:34:36.165 I 0x7989306000 c2.mtk.avc.decoder.secure 854x480 inputFps=30 outputFps=30 renderFps=30, discardFps=0 14:34:36.332 E ViewRootImpl[PlayerActivity]#2 isEGL=1, mPendingRelease.size()=2, mMaxAcquiredBuffers=4, currentMaxAcquiredBufferCount=2 14:34:37.166 I 0x7989306000 c2.mtk.avc.decoder.secure 854x480 inputFps=29 outputFps=30 renderFps=30, discardFps=0 14:34:38.166 I 0x7989306000 c2.mtk.avc.decoder.secure 854x480 inputFps=30 outputFps=30 renderFps=30, discardFps=0 14:34:39.166 I 0x7989306000 c2.mtk.avc.decoder.secure 854x480 inputFps=30 outputFps=29 renderFps=30, discardFps=0 14:34:39.479 I Skipped: false 2 cost 25.763935 refreshRate 11111111 processName androidx.media3.demo.main 14:34:39.500 I Skipped: false 1 cost 14.310746 refreshRate 11111111 processName androidx.media3.demo.main 14:34:40.167 I 0x7989306000 c2.mtk.avc.decoder.secure 854x480 inputFps=30 outputFps=30 renderFps=30, discardFps=0 14:34:41.167 I 0x7989306000 c2.mtk.avc.decoder.secure 854x480 inputFps=31 outputFps=31 renderFps=30, discardFps=0 14:34:42.071 D pause(36): prior state:STATE_ACTIVE output 21 stream 3 session 321 14:34:42.084 I Skipped: false 1 cost 14.742961 refreshRate 11111111 processName androidx.media3.demo.main 14:34:42.121 I [signalFlush:L713] 14:34:42.153 I query failed after returning 18 values (BAD_INDEX) 14:34:42.156 W query -- param skipped: index = 1342179345. 14:34:42.156 W query -- param skipped: index = 2415921170. 14:34:42.156 W query -- param skipped: index = 1610614798. 14:34:42.159 I [signalFlush:L713] 14:34:42.162 I query failed after returning 19 values (BAD_INDEX) 14:34:42.162 W query -- param skipped: index = 1342179345. 14:34:42.162 W query -- param skipped: index = 2415921170. 14:34:42.162 W query -- param skipped: index = 1610614798. 14:34:42.168 I 0x7989306000 c2.mtk.avc.decoder.secure 854x480 inputFps=27 outputFps=27 renderFps=27, discardFps=15 14:34:42.169 D flush(36): 0x795f88c600, prior state:STATE_PAUSED 14:34:42.170 D stop(36): prior state:STATE_FLUSHED output 21 stream 3 session 321 14:34:42.171 D ~AudioTrack(36): 0x795f88c600 14:34:42.171 D ~AudioTrack, releasing mStatus =0 session id 321 from 12375 on behalf of 12375 14:34:42.171 D stop(36): prior state:STATE_FLUSHED output 21 stream 3 session 321 14:34:42.172 D stopAndJoinCallbacks(36) done 14:34:42.715 D INSP: type=audio,case=1,set=0x79f524d600,stream_type=-1,sample_rate=44100,format=0x1,channel_mask=0x3,frame_count=11025,flags=0x0,notification_frames=0,session_id=321,transfer_type=3,uid=-1,pid=-1 14:34:42.715 D INSP: type=audio,case=1,attributes=1,content_type=0,usage=1,source=-1,flags=0xa00 14:34:42.715 D set() streamType -1, sampleRate 44100, format 0x1, channelMask 0x3, frameCount 11025, flags #0, notificationFrames 0, sessionId 321, transferType 3, uid -1, pid -1 cbf 1 14:34:42.715 D set(): Building AudioTrack with attributes: usage=1 content=0 flags=0xa00 tags=[] 14:34:42.715 D set(): 0x79f524d600, Create AudioTrackThread, tid = 15124 14:34:42.724 D createTrack_l(37): 0x79f524d600, mCblk = 0x7aa560d000, mLatency = 356, mAfLatency = 106, frameCount = 11025, mSampleRate = 44100, mFlags = 0x8, mReqFrameCount = 11025, mNotificationFramesAct = 5512 14:34:42.726 D setVolume(37): 0x79f524d600, left = 1.000000, right = 1.000000 14:34:42.727 D setVolume left 1.000 right 1.000 , callingPid 12375 14:34:43.170 I 0x7989306000 c2.mtk.avc.decoder.secure 854x480 inputFps=18 outputFps=14 renderFps=0, discardFps=0 14:34:43.213 W mapFormat: no mediaType information 14:34:43.545 D start(37): prior state:STATE_STOPPED output 21 stream 3 session 321 14:34:43.545 D start(37): 0x79f524d600, prior state:STATE_STOPPED 14:34:43.565 D getTimestamp_l(37): device stall time corrected using current time 1501208686550 14:34:44.170 I 0x7989306000 c2.mtk.avc.decoder.secure 1920x1080 inputFps=5 outputFps=7 renderFps=5, discardFps=0 14:34:45.170 I 0x7989306000 c2.mtk.avc.decoder.secure 1920x1080 inputFps=0 outputFps=0 renderFps=2, discardFps=0 14:34:46.171 I 0x7989306000 c2.mtk.avc.decoder.secure 1920x1080 inputFps=0 outputFps=0 renderFps=0, discardFps=0 14:34:46.343 E ViewRootImpl[PlayerActivity]#2 isEGL=1, mPendingRelease.size()=1, mMaxAcquiredBuffers=4, currentMaxAcquiredBufferCount=2 14:34:46.609 E ViewRootImpl[PlayerActivity]#2 isEGL=1, mPendingRelease.size()=2, mMaxAcquiredBuffers=4, currentMaxAcquiredBufferCount=2 14:34:47.171 I 0x7989306000 c2.mtk.avc.decoder.secure 1920x1080 inputFps=0 outputFps=0 renderFps=0, discardFps=0 14:34:48.172 I 0x7989306000 c2.mtk.avc.decoder.secure 1920x1080 inputFps=0 outputFps=0 renderFps=0, discardFps=0 14:34:49.173 I 0x7989306000 c2.mtk.avc.decoder.secure 1920x1080 inputFps=0 outputFps=0 renderFps=0, discardFps=0 14:34:49.173 I send video stutter message MM_FB_EventID#11002#ClientPid#12375#Component#c2.mtk.avc.decoder.secure#VideoInputFps#0#VideoOutputFps#0#VideoRenderFps#0#VideoHeight#1080#VideoWidth#1920#IsMediaPlayer#0#LogLevel#3#FpsArray#5/0/0/0/0/0#ModifiedVersion#5 14:34:50.174 I 0x7989306000 c2.mtk.avc.decoder.secure 1920x1080 inputFps=0 outputFps=0 renderFps=0, discardFps=0 14:34:51.174 I 0x7989306000 c2.mtk.avc.decoder.secure 1920x1080 inputFps=0 outputFps=0 renderFps=0, discardFps=0 14:34:52.175 I 0x7989306000 c2.mtk.avc.decoder.secure 1920x1080 inputFps=0 outputFps=0 renderFps=0, discardFps=0 14:34:53.176 I 0x7989306000 c2.mtk.avc.decoder.secure 1920x1080 inputFps=0 outputFps=0 renderFps=0, discardFps=0 14:34:54.176 I 0x7989306000 c2.mtk.avc.decoder.secure 1920x1080 inputFps=0 outputFps=0 renderFps=0, discardFps=0 14:34:55.176 I [sendFPSInfo:L427] input/output/render 0fps over 10s, reduce print fps log 14:35:24.125 D pause(37): prior state:STATE_ACTIVE output 21 stream 3 session 321 14:35:24.134 I Skipped: false 1 cost 16.097824 refreshRate 11111111 processName androidx.media3.demo.main 14:35:24.146 I [signalFlush:L713] 14:35:24.168 I query failed after returning 18 values (BAD_INDEX) 14:35:24.171 W query -- param skipped: index = 1342179345. 14:35:24.171 W query -- param skipped: index = 2415921170. 14:35:24.171 W query -- param skipped: index = 1610614798. 14:35:24.174 I [signalFlush:L713] 14:35:24.177 I query failed after returning 19 values (BAD_INDEX) 14:35:24.177 W query -- param skipped: index = 1342179345. 14:35:24.177 W query -- param skipped: index = 2415921170. 14:35:24.177 W query -- param skipped: index = 1610614798. 14:35:24.182 D flush(37): 0x79f524d600, prior state:STATE_PAUSED 14:35:24.183 D stop(37): prior state:STATE_FLUSHED output 21 stream 3 session 321 14:35:24.184 D ~AudioTrack(37): 0x79f524d600 14:35:24.184 D ~AudioTrack, releasing mStatus =0 session id 321 from 12375 on behalf of 12375 14:35:24.184 D stop(37): prior state:STATE_FLUSHED output 21 stream 3 session 321 14:35:24.185 D stopAndJoinCallbacks(37) done 14:35:24.185 I 0x7989306000 c2.mtk.avc.decoder.secure 1920x1080 inputFps=0 outputFps=12 renderFps=0, discardFps=0 14:35:24.327 D INSP: type=audio,case=1,set=0x79f524d000,stream_type=-1,sample_rate=44100,format=0x1,channel_mask=0x3,frame_count=11025,flags=0x0,notification_frames=0,session_id=321,transfer_type=3,uid=-1,pid=-1 14:35:24.327 D INSP: type=audio,case=1,attributes=1,content_type=0,usage=1,source=-1,flags=0xa00 14:35:24.327 D set() streamType -1, sampleRate 44100, format 0x1, channelMask 0x3, frameCount 11025, flags #0, notificationFrames 0, sessionId 321, transferType 3, uid -1, pid -1 cbf 1 14:35:24.327 D set(): Building AudioTrack with attributes: usage=1 content=0 flags=0xa00 tags=[] 14:35:24.327 D set(): 0x79f524d000, Create AudioTrackThread, tid = 15209 14:35:24.336 D createTrack_l(38): 0x79f524d000, mCblk = 0x7aa560d100, mLatency = 356, mAfLatency = 106, frameCount = 11025, mSampleRate = 44100, mFlags = 0x8, mReqFrameCount = 11025, mNotificationFramesAct = 5512 14:35:24.338 D setVolume(38): 0x79f524d000, left = 1.000000, right = 1.000000 14:35:24.338 D setVolume left 1.000 right 1.000 , callingPid 12375 14:35:25.108 D start(38): prior state:STATE_STOPPED output 21 stream 3 session 321 14:35:25.108 D start(38): 0x79f524d000, prior state:STATE_STOPPED 14:35:25.126 D getTimestamp_l(38): device stall time corrected using current time 1542770047476 14:35:25.185 I 0x7989306000 c2.mtk.avc.decoder.secure 1920x1080 inputFps

myvideotom avatar Jan 23 '24 03:01 myvideotom

@myvideotom, we are trying to reproduce this. Do we have your permission to share the logs you sent to [email protected] with MediaTek? (Internal ref: 324096412)

rrfrias avatar Feb 06 '24 19:02 rrfrias

@rrfrias , Absolutely, you have my permission to share the logs with MediaTek. Please keep me informed if the Widevine token expires, and I will promptly provide a new one.

myvideotom avatar Feb 16 '24 05:02 myvideotom

Thanks I have shared it with MediaTek. Will work with them to reproduce.

rrfrias avatar Feb 22 '24 04:02 rrfrias

Was the code above truncated by accident? Perhaps Github does this to text by default?

"uri": https://dache.myvideo.net.tw/Media/20210710/Smooth/VID01000000515H/....

In Exo Player, the expected code would look like: "uri": "https://storage.domain.com/media/testmedia/test.mpd",

There is a code option in Github to change the text if that's necessary.

The same "..." happens at the end of the "acquirelicenseassertion" string.

fcolley avatar Mar 20 '24 19:03 fcolley

@fcolley Some information about this video (such as the Widevine token, etc.) cannot be publicly shared on GitHub. Please provide me with your email, and I will send you the complete information about the video. PS: my e-mail: [email protected] or [email protected] If MTK needs to contact me, they can ask MTK's Taiwan unit to contact me (I am also in Taiwan).

myvideotom avatar Mar 21 '24 02:03 myvideotom

@myvideotom we received the information you sent here. Will work with @fcolley to reproduce and let you know if we need more information.

rrfrias avatar Mar 21 '24 18:03 rrfrias

@rrfrias @fcolley Got it. If the URL of the video or the Widevine token expires, please let me(my e-mail: [email protected] or [email protected]) know, and I'll assist in providing them as soon as possible. Thank you.

myvideotom avatar Mar 22 '24 02:03 myvideotom

Additional information

"Similar issues have been observed on other devices in Firebase Crashlytics. PS: My media player app uses the media3 (1.2.1) player

Non-fatal Exception: androidx.media3.exoplayer.ExoPlaybackException MediaCodecVideoRenderer error, index=0, format=Format(1, null, null, video/avc, avc1.42C01E, 2013647, null, [854, 480, -1.0, ColorInfo(Unset color space, Unset color range, Unset color transfer, false, 8bit Luma, 8bit Chroma)], [-1, -1]), format_supported=YES Caused by androidx.media3.exoplayer.mediacodec.MediaCodecRenderer$DecoderInitializationException: Decoder init failed: c2.mtk.avc.decoder.secure, Format(1, null, null, video/avc, avc1.42C01E, 2013647, null, [854, 480, -1.0, ColorInfo(Unset color space, Unset color range, Unset color transfer, false, 8bit Luma, 8bit Chroma)], [-1, -1]) at androidx.media3.exoplayer.mediacodec.MediaCodecRenderer.maybeInitCodecWithFallback(MediaCodecRenderer.java:1114) at androidx.media3.exoplayer.mediacodec.MediaCodecRenderer.maybeInitCodecOrBypass(MediaCodecRenderer.java:551) at androidx.media3.exoplayer.mediacodec.MediaCodecRenderer.onInputFormatChanged(MediaCodecRenderer.java:1560) https://console.firebase.google.com/u/0/project/myvideo-190d9/crashlytics/app/android:com.taiwanmobile.myVideo/issues/c6e010adba983b6514619f0488bae950?time=1710201600000:1711065599000&types=error&versions=5.0.1.33%20(865)&sessionEventKey=65F711CE02A400016A460FF5F78CD02F_1925828555319573778 image Log_ModelOppo_Reno8_5G.txt

Information about the 'widevine dash' video(on-the-fly): videoUrl:https://dache.myvideo.net.tw/Media/20190430/Smooth/MED01000006054H/MED01000006054HPC... widevineLicenseUrl: https://widevine... widevineToken:CIvx2lPc5hG/Js7AyTLOAW0d6oTy5Oj+x2wZIVo2jofIlY2o26cspM5apnCpIsZ/QV72Dzn+gRmW31TeuEN8Nbc1ZP71Fb0ndAQ42gkvfIJYq5sZuu4QQUGVC4nlJSYrl3TxkOyVM+Dm4qVnLs2QeEWUV959jlQlChOjzunF1i5cQQSxTgmguH1VgRopEitrgTluBYDGHu4umQ9bJ.....

PS: Some information about this video (such as the Widevine token, etc.) cannot be publicly shared on GitHub. Please provide me with your email, and I will send you the complete information about the video.

myvideotom avatar Mar 22 '24 10:03 myvideotom

Thanks for providing more info.

We tried building an Exo Player with the latest details provided, but video fails with 404 indicating the "server could not find what was requested". If you have any insight please message [email protected] with any details. Thanks.

fcolley avatar Mar 22 '24 17:03 fcolley

Thanks for providing more info.

We tried building an Exo Player with the latest details provided, but video fails with 404 indicating the "server could not find what was requested". If you have any insight please message [email protected] with any details. Thanks.

@fcolley The complete information of the video (videoUrl, widevineLicenseUrl, and widevineToken) has been emailed to [email protected]

myvideotom avatar Mar 25 '24 14:03 myvideotom

Thanks for that information. We were able to playback the video and are working with MediaTek to investigate furthur.

rrfrias avatar Mar 25 '24 18:03 rrfrias

Thanks for that information. We were able to playback the video and are working with MediaTek to investigate furthur.

@rrfrias If a long-term valid widevineToken is needed, please let me know, and I will provide it as soon as possible.

myvideotom avatar Mar 26 '24 02:03 myvideotom

It may take some time for this to be analyzed. It would be helpful if the links are long term.

fcolley avatar Mar 26 '24 17:03 fcolley

It may take some time for this to be analyzed. It would be helpful if the links are long term. @fcolley I will report to my supervisor and provide the video with a long-term valid widevineToken. Once ready, I will send it to you via email and notify you.

myvideotom avatar Mar 27 '24 05:03 myvideotom

It may take some time for this to be analyzed. It would be helpful if the links are long term. @fcolley I will report to my supervisor and provide the video with a long-term valid widevineToken. Once ready, I will send it to you via email and notify you.

@fcolley @rrfrias The complete information(videoDetalInfo_#997_0402.txt) of the video (videoUrl, widevineLicenseUrl, and widevineToken [long-lasting token, valid until 5/2]) has been emailed to [email protected].

myvideotom avatar Apr 02 '24 14:04 myvideotom

Forgot to update this. We are waiting on MediaTek to investigate.

rrfrias avatar Apr 30 '24 21:04 rrfrias

@rrfrias Thank you for informing me.

myvideotom avatar May 02 '24 12:05 myvideotom

@rrfrias Thank you for informing me.

myvideotom avatar May 02 '24 12:05 myvideotom

Can we get another link? It took a while to get Mediatek's attention. The link had expired by then.

rrfrias avatar May 20 '24 13:05 rrfrias

Can we get another link? It took a while to get Mediatek's attention. The link had expired by then.

@rrfrias I already understand the information you need. We are preparing the video URL and Widevine token data for the relevant video. Once ready, we will notify you. The video-related information will be emailed to [email protected].

myvideotom avatar May 21 '24 07:05 myvideotom

@rrfrias The complete information(videoDetalInfo_#997_0831.txt) of the video (videoUrl, widevineLicenseUrl, and widevineToken [long-lasting token, valid until 08/31]) has been emailed to [email protected].

myvideotom avatar May 22 '24 10:05 myvideotom

Thanks. We are configuring an apk to reproduce and will pass that to MediaTek

rrfrias avatar May 24 '24 11:05 rrfrias

@rrfrias The complete information(videoDetalInfo_#997_0831.txt) of the video (videoUrl, widevineLicenseUrl, and widevineToken [long-lasting token, valid until 08/31]) has been emailed to [email protected].

We tried playback, but the attempt resulted in response code 404 (content cannot be found). Did this video content get moved or removed? We cannot pass this to MediaTek without working content.

Feel free to send an update to [email protected] , thank you.

fcolley avatar May 24 '24 14:05 fcolley

@fcolley

  • I already understand the information you need. We are preparing the video URL and Widevine token data for the relevant video. Once ready, we will notify you. The video-related information will be emailed to [email protected].
  • The email also includes the relevant ExoPlayer code.

myvideotom avatar May 29 '24 06:05 myvideotom

Do let us know when you have something to share. We received some information on May 28th but it does not look like it is appreciably different from what you shared before.

rrfrias avatar Jun 03 '24 18:06 rrfrias