media
media copied to clipboard
androidx
No video played when `setVideoEffects` called on NVIDIA Shield
Version
Media3 1.4.0
More version details
Calling player.setVideoEffects() with any effects or even an empty list results in no video (just black screen).
I reviewed the requirements for this to work and they all seem met:
- Not passing a surface manually, using a
androidx. media3.ui.PlayerView -
media-effectlib is added - Checking
player.getRendererand the only video one isMediaCodecVideoRenderer - Effects do not edit timestamps
- No DRM
- Function is called before
prepare()
I guess it must be a hardware decoding issue, but I'm not sure how to validate. There's nothing obviously erroring in the logs.
Bare-bones code that causes the issue:
val player = ExoPlayer.Builder(this).build()
playerView.player = player
player.setVideoEffects(listOf()) // Commenting this out works
player.setMediaItem(MediaItem.fromUri("https://commondatastorage.googleapis.com/gtv-videos-bucket/sample/BigBuckBunny.mp4"))
player.prepare()
player.play()
Devices that reproduce the issue
- NVIDIA Shield (2017 Pro & 2019 Pro models)
Devices that do not reproduce the issue
- Emulator running Android TV API 34
- Fire TV Stick 4k Max 2nd Gen
Reproducible in the demo app?
Not tested
Reproduction steps
- Call
setVideoEffectson the ExoPlayer object (even with an empty list) - Play a video
Expected result
Video plays successfully with effects applied or if no effects added at least the video plays normally
Actual result
Video playback is a black screen.
Audio plays as expected.
Media
https://commondatastorage.googleapis.com/gtv-videos-bucket/sample/BigBuckBunny.mp4
Bug Report
- [X] You will email the zip file produced by
adb bugreportto [email protected] after filing this issue.
I didn't see an example in the demo apps that used setVideoEffects, so I made a test one https://github.com/damontecres/media3-issue if that helps. Thanks!
I was wondering what output is connected to this? Is it a PlayerView?
Can you help me double check a few things, adding logs to show the variables related to resolutions at
https://github.com/androidx/media/blob/b01c6ffcb3fca3d038476dab5d3bc9c9f2010781/libraries/exoplayer/src/main/java/androidx/media3/exoplayer/video/MediaCodecVideoRenderer.java#L863
and
https://github.com/androidx/media/blob/b01c6ffcb3fca3d038476dab5d3bc9c9f2010781/libraries/effect/src/main/java/androidx/media3/effect/FinalShaderProgramWrapper.java#L366
And log if this line is executed:
https://github.com/androidx/media/blob/b01c6ffcb3fca3d038476dab5d3bc9c9f2010781/libraries/effect/src/main/java/androidx/media3/effect/FinalShaderProgramWrapper.java#L409
Hi @claincly!
I was wondering what output is connected to this? Is it a PlayerView?
Yes, defined very simply:
<?xml version="1.0" encoding="utf-8"?>
<FrameLayout xmlns:android="http://schemas.android.com/apk/res/android"
android:layout_width="match_parent"
android:layout_height="match_parent">
<androidx.media3.ui.PlayerView
android:id="@+id/player_view"
android:layout_width="match_parent"
android:layout_height="match_parent"/>
</FrameLayout>
Can you help me double check a few things, adding logs to show the variables related to resolutions at
Here are the logs when executing with setVideoEffects called:
2024-08-14 11:53:55.657 2520-2563 issue1620 com...hub.damontecres.myapplication D MSG_SET_VIDEO_OUTPUT_RESOLUTION Size=0x0
2024-08-14 11:53:55.657 2520-2563 issue1620 com...hub.damontecres.myapplication D MSG_SET_VIDEO_OUTPUT_RESOLUTION videoSink!=null = false
2024-08-14 11:53:55.657 2520-2563 issue1620 com...hub.damontecres.myapplication D MSG_SET_VIDEO_OUTPUT_RESOLUTION displaySurface!=null = false
2024-08-14 11:53:55.771 2520-2563 issue1620 com...hub.damontecres.myapplication D MSG_SET_VIDEO_OUTPUT_RESOLUTION Size=1920x984
2024-08-14 11:53:55.771 2520-2563 issue1620 com...hub.damontecres.myapplication D MSG_SET_VIDEO_OUTPUT_RESOLUTION videoSink!=null = false
2024-08-14 11:53:55.771 2520-2563 issue1620 com...hub.damontecres.myapplication D MSG_SET_VIDEO_OUTPUT_RESOLUTION displaySurface!=null = true
2024-08-14 11:53:56.514 2520-2577 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrame inputTexture.width=1280
2024-08-14 11:53:56.514 2520-2577 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrame inputTexture.height=720
2024-08-14 11:53:56.567 2520-2577 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface EGL14.eglSwapBuffers was called
2024-08-14 11:53:56.592 2520-2577 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrame inputTexture.width=1280
2024-08-14 11:53:56.592 2520-2577 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrame inputTexture.height=720
2024-08-14 11:53:56.603 2520-2577 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface EGL14.eglSwapBuffers was called
2024-08-14 11:53:56.613 2520-2577 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrame inputTexture.width=1280
2024-08-14 11:53:56.613 2520-2577 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrame inputTexture.height=720
2024-08-14 11:53:56.639 2520-2577 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface EGL14.eglSwapBuffers was called
Here are the logs when executing without setVideoEffects called:
2024-08-14 11:54:51.660 2703-2743 issue1620 com...hub.damontecres.myapplication D MSG_SET_VIDEO_OUTPUT_RESOLUTION Size=0x0
2024-08-14 11:54:51.660 2703-2743 issue1620 com...hub.damontecres.myapplication D MSG_SET_VIDEO_OUTPUT_RESOLUTION videoSink!=null = false
2024-08-14 11:54:51.661 2703-2743 issue1620 com...hub.damontecres.myapplication D MSG_SET_VIDEO_OUTPUT_RESOLUTION displaySurface!=null = false
2024-08-14 11:54:51.772 2703-2743 issue1620 com...hub.damontecres.myapplication D MSG_SET_VIDEO_OUTPUT_RESOLUTION Size=1920x984
2024-08-14 11:54:51.772 2703-2743 issue1620 com...hub.damontecres.myapplication D MSG_SET_VIDEO_OUTPUT_RESOLUTION videoSink!=null = false
2024-08-14 11:54:51.772 2703-2743 issue1620 com...hub.damontecres.myapplication D MSG_SET_VIDEO_OUTPUT_RESOLUTION displaySurface!=null = true
2024-08-14 11:54:52.389 2703-2743 issue1620 com...hub.damontecres.myapplication D MSG_SET_VIDEO_OUTPUT_RESOLUTION Size=1749x984
2024-08-14 11:54:52.389 2703-2743 issue1620 com...hub.damontecres.myapplication D MSG_SET_VIDEO_OUTPUT_RESOLUTION videoSink!=null = false
2024-08-14 11:54:52.389 2703-2743 issue1620 com...hub.damontecres.myapplication D MSG_SET_VIDEO_OUTPUT_RESOLUTION displaySurface!=null = true
@claincly, is there any other information I can provide to help debug this?
Any ideas on if there is a work around? I tried sending the MSG_SET_VIDEO_OUTPUT_RESOLUTION message manually a few different ways without success.
I wonder if you could log on this line?
https://github.com/androidx/media/blob/b01c6ffcb3fca3d038476dab5d3bc9c9f2010781/libraries/effect/src/main/java/androidx/media3/effect/FinalShaderProgramWrapper.java#L409
The resolution logs look correct. You might noticed some discrepancies between with/without video effects, but that's expected.
Hi @claincly, here are some logs from in that method. Thoughts?
2024-08-27 19:56:19.353 23576-23612 issue1620 com...hub.damontecres.myapplication D MSG_SET_VIDEO_OUTPUT_RESOLUTION Size=0x0
2024-08-27 19:56:19.353 23576-23612 issue1620 com...hub.damontecres.myapplication D MSG_SET_VIDEO_OUTPUT_RESOLUTION videoSink!=null = false
2024-08-27 19:56:19.353 23576-23612 issue1620 com...hub.damontecres.myapplication D MSG_SET_VIDEO_OUTPUT_RESOLUTION displaySurface!=null = false
2024-08-27 19:56:19.452 23576-23612 issue1620 com...hub.damontecres.myapplication D MSG_SET_VIDEO_OUTPUT_RESOLUTION Size=1920x1080
2024-08-27 19:56:19.452 23576-23612 issue1620 com...hub.damontecres.myapplication D MSG_SET_VIDEO_OUTPUT_RESOLUTION videoSink!=null = false
2024-08-27 19:56:19.452 23576-23612 issue1620 com...hub.damontecres.myapplication D MSG_SET_VIDEO_OUTPUT_RESOLUTION displaySurface!=null = true
2024-08-27 19:56:20.181 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrame inputTexture.width=1280
2024-08-27 19:56:20.181 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrame inputTexture.height=720
2024-08-27 19:56:20.202 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface: inputTexture=androidx.media3.common.GlTextureInfo@51e72ea
2024-08-27 19:56:20.202 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface: presentationTimeUs=1000000000000
2024-08-27 19:56:20.202 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface: renderTimeNs=-1
2024-08-27 19:56:20.217 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface: eglDisplay=android.opengl.EGLDisplay@210
2024-08-27 19:56:20.217 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface: outputEglSurface=android.opengl.EGLSurface@4edfb0a5
2024-08-27 19:56:20.217 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface: outputSurfaceInfo.width=1920
2024-08-27 19:56:20.217 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface: outputSurfaceInfo.height=1080
2024-08-27 19:56:20.218 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface after EGL14.eglSwapBuffers was called
2024-08-27 19:56:20.244 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrame inputTexture.width=1280
2024-08-27 19:56:20.244 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrame inputTexture.height=720
2024-08-27 19:56:20.249 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface: inputTexture=androidx.media3.common.GlTextureInfo@51e72ea
2024-08-27 19:56:20.249 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface: presentationTimeUs=1000000041666
2024-08-27 19:56:20.249 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface: renderTimeNs=440391116378964
2024-08-27 19:56:20.253 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface: eglDisplay=android.opengl.EGLDisplay@210
2024-08-27 19:56:20.253 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface: outputEglSurface=android.opengl.EGLSurface@4edfb0a5
2024-08-27 19:56:20.253 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface: outputSurfaceInfo.width=1920
2024-08-27 19:56:20.253 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface: outputSurfaceInfo.height=1080
2024-08-27 19:56:20.254 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface after EGL14.eglSwapBuffers was called
2024-08-27 19:56:20.263 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrame inputTexture.width=1280
2024-08-27 19:56:20.263 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrame inputTexture.height=720
2024-08-27 19:56:20.265 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface: inputTexture=androidx.media3.common.GlTextureInfo@51e72ea
2024-08-27 19:56:20.265 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface: presentationTimeUs=1000000083333
2024-08-27 19:56:20.265 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface: renderTimeNs=440391166428963
2024-08-27 19:56:20.268 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface: eglDisplay=android.opengl.EGLDisplay@210
2024-08-27 19:56:20.268 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface: outputEglSurface=android.opengl.EGLSurface@4edfb0a5
2024-08-27 19:56:20.268 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface: outputSurfaceInfo.width=1920
2024-08-27 19:56:20.268 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface: outputSurfaceInfo.height=1080
2024-08-27 19:56:20.269 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface after EGL14.eglSwapBuffers was called
2024-08-27 19:56:20.294 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrame inputTexture.width=1280
2024-08-27 19:56:20.294 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrame inputTexture.height=720
2024-08-27 19:56:20.294 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface: inputTexture=androidx.media3.common.GlTextureInfo@51e72ea
2024-08-27 19:56:20.294 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface: presentationTimeUs=1000000125000
2024-08-27 19:56:20.294 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface: renderTimeNs=440391199795629
2024-08-27 19:56:20.296 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface: eglDisplay=android.opengl.EGLDisplay@210
2024-08-27 19:56:20.296 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface: outputEglSurface=android.opengl.EGLSurface@4edfb0a5
2024-08-27 19:56:20.296 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface: outputSurfaceInfo.width=1920
2024-08-27 19:56:20.296 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface: outputSurfaceInfo.height=1080
2024-08-27 19:56:20.297 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrameToOutputSurface after EGL14.eglSwapBuffers was called
2024-08-27 19:56:20.345 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrame inputTexture.width=1280
2024-08-27 19:56:20.345 23576-23620 issue1620 com...hub.damontecres.myapplication D FinalShaderProgramWrapper.renderFrame inputTexture.height=720
Hmmm that is indeed very strange - I mean if eglSwapBuffers() is called, then we have already send the frame to display on the screen.
I also noticed you mentioned you didn't try reproducing using the demo app, could you try using the demo app and see it reproduces? I just wanted to rule out any unrelated reasons.
The no video issue is reproducible in the basic demo app with a change to import the lib-effect module (it crashes when calling setVideoEffects otherwise).
I tried on another NVIDIA shield device (2017 model) and the issue is reproducible there as well.
I also tried the demo-transformer app on the same device and set some random RGB video effects. It produced a correctly transformed video including playback during export in the "debug preview". It looks like this debug preview is a Surface but not from/connected to a PlayerView if I'm reading the code correctly. Maybe that is a clue?
Here is the patch just in case:
diff --git a/demos/main/build.gradle b/demos/main/build.gradle
index 6a49adf169..78a43f180b 100644
--- a/demos/main/build.gradle
+++ b/demos/main/build.gradle
@@ -85,6 +85,7 @@ dependencies {
implementation project(modulePrefix + 'lib-ui')
implementation project(modulePrefix + 'lib-datasource-cronet')
implementation project(modulePrefix + 'lib-exoplayer-ima')
+ implementation project(modulePrefix + 'lib-effect')
withDecoderExtensionsImplementation project(modulePrefix + 'lib-decoder-av1')
withDecoderExtensionsImplementation project(modulePrefix + 'lib-decoder-ffmpeg')
withDecoderExtensionsImplementation project(modulePrefix + 'lib-decoder-flac')
diff --git a/demos/main/src/main/java/androidx/media3/demo/main/PlayerActivity.java b/demos/main/src/main/java/androidx/media3/demo/main/PlayerActivity.java
index 6be37323bd..2b3c11977a 100644
--- a/demos/main/src/main/java/androidx/media3/demo/main/PlayerActivity.java
+++ b/demos/main/src/main/java/androidx/media3/demo/main/PlayerActivity.java
@@ -258,7 +258,7 @@ public class PlayerActivity extends AppCompatActivity
/**
* @return Whether initialization was successful.
*/
- protected boolean initializePlayer() {
+ @OptIn(markerClass = UnstableApi.class) protected boolean initializePlayer() {
Intent intent = getIntent();
if (player == null) {
@@ -279,6 +279,7 @@ public class PlayerActivity extends AppCompatActivity
player.addAnalyticsListener(new EventLogger());
player.setAudioAttributes(AudioAttributes.DEFAULT, /* handleAudioFocus= */ true);
player.setPlayWhenReady(startAutoPlay);
+ player.setVideoEffects(new ArrayList<>());
playerView.setPlayer(player);
configurePlayerWithServerSideAdsLoader();
debugViewHelper = new DebugTextViewHelper(player, debugTextView);
It looks like this debug preview is a Surface but not from/connected to a PlayerView if I'm reading the code correctly. Maybe that is a clue?
Interesting find. You are correct the debug preview is not a PlayerView (I think it's just a SurfaceView). The only difference I can think of is we call eglPresentationTimeANDROID() when rendering to a PlayerView. Now that method is enabled by an OpenGL extension which might now work reliably on all devices.
Although I don't recommend it, could you try commenting that line out and see if the video plays?
https://github.com/androidx/media/blob/c35a9d62baec57118ea898e271ac66819399649b/libraries/effect/src/main/java/androidx/media3/effect/FinalShaderProgramWrapper.java#L437-L442
Although I don't recommend it, could you try commenting that line out and see if the video plays?
Commenting out the EGLExt.eglPresentationTimeANDROID method call does not resolve the issue, still just a black screen with audio.
I also tried updating to 1.4.1 just in case, but no joy.
One last thing to try - Nvidia might have very customized OpenGL stack),
Adding glFinish() after calling eglSwapBuffers(), and
change the color to something like red in clearFocusedBuffers(), here to something not black, maybe set red to 1
https://github.com/androidx/media/blob/c35a9d62baec57118ea898e271ac66819399649b/libraries/common/src/main/java/androidx/media3/common/util/GlUtil.java#L554-L555
Adding glFinish() after calling eglSwapBuffers(), and
No change
change the color to something like red in clearFocusedBuffers(), here to something not black, maybe set red to 1
Screen is still black!
Encountering the same behavior (black video, audio working) on the Nvidia Shield Pro 2019 model (P2897). Both with version 1.5.1 and the main branch using the demo-effect project. I've tried digging into what is causing the issue but I'm not familiar with the codebase. So far all I can find is the issue only occurs when setVideoEffects is used and the PlaybackVideoGraphWrapper class is used.
It would be nice if we can get this issue fixed as I want to use effects in my app (Jellyfin for Android TV) but with the shield being one of our most used devices this is now not possible.
It seems this reproduces a lot on nVidia shields, we'll take a look soon, stay tuned
@claincly is there any timeframe to share on this? The issue still exists on the 1.6.0 and 1.6.1 releases and we cannot use effects at all because the Nvidia Shield is our most popular device (according to Google Play statistics).
@nielsvanvelzen unfortunately we are not able to share any timeframe on this at the moment.
We will keep this issue updated when we start working on it
I am able to reproduce this problem with the version 1.8.0.
Also, in the logcat, when I use player.setVideoEffects(new ArrayList<>());, I get this log dequeueBuffer failed: WOULD_BLOCK(-11). I couldn't found why it happen, but it may gives you idea.
I just retried with the master branch (specifically this commit a8d2f5cb74d212424eae0246bc6b6e23256fb92b) + this patch and the issue persist.
This is the log I get with (package:mine):
2025-11-01 14:25:50.615 15108-15108 android_os_HwBinder androidx.media3.demo.main I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-01 14:25:50.693 15108-15108 ExoPlayerImpl androidx.media3.demo.main I Init 556299e [AndroidXMedia3/1.9.0-alpha01] [mdarcy, SHIELD Android TV, NVIDIA, 30]
2025-11-01 14:25:50.699 15108-15108 EventLogger androidx.media3.demo.main D playWhenReady [eventTime=0.00, mediaPos=0.00, window=0, true, USER_REQUEST]
2025-11-01 14:25:50.703 15108-15108 EventLogger androidx.media3.demo.main D surfaceSize [eventTime=0.01, mediaPos=0.00, window=0, w=0, h=0]
2025-11-01 14:25:50.708 15108-15108 EventLogger androidx.media3.demo.main D timeline [eventTime=0.01, mediaPos=0.00, window=0, periodCount=1, windowCount=1, reason=PLAYLIST_CHANGED
2025-11-01 14:25:50.708 15108-15108 EventLogger androidx.media3.demo.main D period [?]
2025-11-01 14:25:50.708 15108-15108 EventLogger androidx.media3.demo.main D window [?, seekable=false, dynamic=true]
2025-11-01 14:25:50.708 15108-15108 EventLogger androidx.media3.demo.main D ]
2025-11-01 14:25:50.709 15108-15108 EventLogger androidx.media3.demo.main D mediaItem [eventTime=0.01, mediaPos=0.00, window=0, reason=PLAYLIST_CHANGED]
2025-11-01 14:25:50.711 15108-15108 EventLogger androidx.media3.demo.main D state [eventTime=0.01, mediaPos=0.00, window=0, BUFFERING]
2025-11-01 14:25:50.717 15108-15108 android_os_HwBinder androidx.media3.demo.main I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-01 14:25:50.721 15108-15108 android_os_HwBinder androidx.media3.demo.main I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-01 14:25:50.759 15108-15137 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_ASYNC"
2025-11-01 14:25:50.759 15108-15137 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_LOGFPS"
2025-11-01 14:25:50.759 15108-15137 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_PROCESS_NAME"
2025-11-01 14:25:50.759 15108-15137 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_FPSDYNAMIC"
2025-11-01 14:25:50.763 15108-15108 EventLogger androidx.media3.demo.main D surfaceSize [eventTime=0.06, mediaPos=0.00, window=0, w=1920, h=1080]
2025-11-01 14:25:50.774 15108-15108 EventLogger androidx.media3.demo.main D audioSessionId [eventTime=0.08, mediaPos=0.00, window=0, 137]
2025-11-01 14:25:50.777 15108-15108 EventLogger androidx.media3.demo.main D loading [eventTime=0.08, mediaPos=0.00, window=0, period=0, true]
2025-11-01 14:25:50.780 15108-15108 EventLogger androidx.media3.demo.main D timeline [eventTime=0.08, mediaPos=0.00, window=0, period=0, periodCount=1, windowCount=1, reason=SOURCE_UPDATE
2025-11-01 14:25:50.780 15108-15108 EventLogger androidx.media3.demo.main D period [?]
2025-11-01 14:25:50.780 15108-15108 EventLogger androidx.media3.demo.main D window [?, seekable=false, dynamic=false]
2025-11-01 14:25:50.780 15108-15108 EventLogger androidx.media3.demo.main D ]
2025-11-01 14:25:50.906 15108-15336 MetadataUtil androidx.media3.demo.main D Skipped unknown metadata entry: gsst
2025-11-01 14:25:50.906 15108-15336 MetadataUtil androidx.media3.demo.main D Skipped unknown metadata entry: gstd
2025-11-01 14:25:50.906 15108-15336 MetadataUtil androidx.media3.demo.main D Skipped unknown metadata entry: gssd
2025-11-01 14:25:50.906 15108-15336 MetadataUtil androidx.media3.demo.main D Skipped unknown metadata entry: gspu
2025-11-01 14:25:50.906 15108-15336 MetadataUtil androidx.media3.demo.main D Skipped unknown metadata entry: gspm
2025-11-01 14:25:50.934 15108-15108 EventLogger androidx.media3.demo.main D timeline [eventTime=0.24, mediaPos=0.00, window=0, period=0, periodCount=1, windowCount=1, reason=SOURCE_UPDATE
2025-11-01 14:25:50.935 15108-15108 EventLogger androidx.media3.demo.main D period [25.00]
2025-11-01 14:25:50.935 15108-15108 EventLogger androidx.media3.demo.main D window [25.00, seekable=true, dynamic=false]
2025-11-01 14:25:50.935 15108-15108 EventLogger androidx.media3.demo.main D ]
2025-11-01 14:25:50.943 15108-15338 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_ASYNC"
2025-11-01 14:25:50.943 15108-15338 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_LOGFPS"
2025-11-01 14:25:50.944 15108-15338 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_PROCESS_NAME"
2025-11-01 14:25:50.944 15108-15338 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_FPSDYNAMIC"
2025-11-01 14:25:50.951 15108-15108 EventLogger androidx.media3.demo.main D videoEnabled [eventTime=0.25, mediaPos=0.00, window=0, period=0]
2025-11-01 14:25:50.952 15108-15108 EventLogger androidx.media3.demo.main D audioEnabled [eventTime=0.25, mediaPos=0.00, window=0, period=0]
2025-11-01 14:25:50.955 15108-15108 EventLogger androidx.media3.demo.main D tracks [eventTime=0.26, mediaPos=0.00, window=0, period=0
2025-11-01 14:25:50.955 15108-15108 EventLogger androidx.media3.demo.main D group [ id=0:1
2025-11-01 14:25:50.955 15108-15108 EventLogger androidx.media3.demo.main D [X] Track:0, id=0:2, mimeType=video/avc, container=video/mp4, bitrate=494792, codecs=avc1.42C01E, res=480x360, color=NA/NA/NA/8/8, fps=30.0, language=und, supported=YES
2025-11-01 14:25:50.955 15108-15108 EventLogger androidx.media3.demo.main D ]
2025-11-01 14:25:50.955 15108-15108 EventLogger androidx.media3.demo.main D group [ id=0:0
2025-11-01 14:25:50.955 15108-15108 EventLogger androidx.media3.demo.main D [X] Track:0, id=0:1, mimeType=audio/mp4a-latm, container=video/mp4, bitrate=102352, codecs=mp4a.40.2, channels=2, sample_rate=44100, language=und, supported=YES
2025-11-01 14:25:50.955 15108-15108 EventLogger androidx.media3.demo.main D ]
2025-11-01 14:25:50.955 15108-15108 EventLogger androidx.media3.demo.main D group [ id=1:0
2025-11-01 14:25:50.955 15108-15108 EventLogger androidx.media3.demo.main D [X] Track:0, id=1:, mimeType=application/x-media3-cues, codecs=text/vtt, language=en, selectionFlags=[default], supported=YES
2025-11-01 14:25:50.955 15108-15108 EventLogger androidx.media3.demo.main D ]
2025-11-01 14:25:50.955 15108-15108 EventLogger androidx.media3.demo.main D Metadata [
2025-11-01 14:25:50.956 15108-15108 EventLogger androidx.media3.demo.main D Mp4Timestamp: creation time=3339325113, modification time=3339325113, timescale=600
2025-11-01 14:25:50.956 15108-15108 EventLogger androidx.media3.demo.main D ThumbnailMetadata: presentationTimeUs=6800000
2025-11-01 14:25:50.956 15108-15108 EventLogger androidx.media3.demo.main D ]
2025-11-01 14:25:50.956 15108-15108 EventLogger androidx.media3.demo.main D ]
2025-11-01 14:25:50.965 15108-15335 android_os_HwBinder androidx.media3.demo.main I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-01 14:25:50.968 15108-15108 EventLogger androidx.media3.demo.main D downstreamFormat [eventTime=0.27, mediaPos=0.00, window=0, period=0, id=2, mimeType=video/avc, container=video/mp4, bitrate=494792, codecs=avc1.42C01E, res=480x360, color=NA/NA/NA/8/8, fps=30.0, language=und]
2025-11-01 14:25:50.969 15108-15340 OMXClient androidx.media3.demo.main I IOmx service obtained
2025-11-01 14:25:50.974 15108-15335 android_os_HwBinder androidx.media3.demo.main I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-01 14:25:50.977 15108-15335 chatty androidx.media3.demo.main I uid=10133(androidx.media3.demo.main) ExoPlayer:Playb identical 1 line
2025-11-01 14:25:50.979 15108-15335 android_os_HwBinder androidx.media3.demo.main I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-01 14:25:50.980 15108-15335 MediaCodec androidx.media3.demo.main I mContentRefreshRate = 30.0
2025-11-01 14:25:50.981 15108-15339 MediaCodec androidx.media3.demo.main D flushMediametrics
2025-11-01 14:25:50.981 15108-15339 SurfaceUtils androidx.media3.demo.main D connecting to surface 0x287c42e7b0, reason connectToSurface
2025-11-01 14:25:50.981 15108-15339 MediaCodec androidx.media3.demo.main I [OMX.Nvidia.h264.decode] setting surface generation to 15470594
2025-11-01 14:25:50.981 15108-15339 SurfaceUtils androidx.media3.demo.main D disconnecting from surface 0x287c42e7b0, reason connectToSurface(reconnect)
2025-11-01 14:25:50.981 15108-15339 SurfaceUtils androidx.media3.demo.main D connecting to surface 0x287c42e7b0, reason connectToSurface(reconnect)
2025-11-01 14:25:50.981 15108-15339 SurfaceUtils androidx.media3.demo.main D legacy buffer drop disabled: target sdk (34)
2025-11-01 14:25:50.988 15108-15340 ACodec androidx.media3.demo.main I Enable timestamp filtering for Video Decoder
2025-11-01 14:25:50.989 15108-15340 ACodec androidx.media3.demo.main I Get OMX_IndexConfigScale: 0x10000/0x10000
2025-11-01 14:25:50.989 15108-15340 ACodec androidx.media3.demo.main I Scaled Width is 480, height is 360
2025-11-01 14:25:50.994 15108-15340 SurfaceUtils androidx.media3.demo.main D set up nativeWindow 0x287c42e7b0 for 480x360, color 0x106, rotation 0, usage 0x2900
2025-11-01 14:25:51.000 15108-15108 EventLogger androidx.media3.demo.main D videoDecoderInitialized [eventTime=0.30, mediaPos=0.00, window=0, period=0, OMX.Nvidia.h264.decode]
2025-11-01 14:25:51.002 15108-15108 EventLogger androidx.media3.demo.main D videoInputFormat [eventTime=0.30, mediaPos=0.00, window=0, period=0, id=2, mimeType=video/avc, container=video/mp4, bitrate=494792, codecs=avc1.42C01E, res=480x360, color=NA/NA/NA/8/8, fps=30.0, language=und]
2025-11-01 14:25:51.007 15108-15340 ACodec androidx.media3.demo.main I Get OMX_IndexConfigScale: 0x10000/0x10000
2025-11-01 14:25:51.008 15108-15340 ACodec androidx.media3.demo.main I Scaled Width is 480, height is 360
2025-11-01 14:25:51.009 15108-15340 SurfaceUtils androidx.media3.demo.main D set up nativeWindow 0x287c42e7b0 for 480x360, color 0x106, rotation 0, usage 0x2900
2025-11-01 14:25:51.014 15108-15335 android_os_HwBinder androidx.media3.demo.main I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-01 14:25:51.018 15108-15340 ACodec androidx.media3.demo.main I Get OMX_IndexConfigScale: 0x10000/0x10000
2025-11-01 14:25:51.018 15108-15340 ACodec androidx.media3.demo.main I Scaled Width is 480, height is 360
2025-11-01 14:25:51.019 15108-15108 EventLogger androidx.media3.demo.main D downstreamFormat [eventTime=0.32, mediaPos=0.00, window=0, period=0, id=1, mimeType=audio/mp4a-latm, container=video/mp4, bitrate=102352, codecs=mp4a.40.2, channels=2, sample_rate=44100, language=und]
2025-11-01 14:25:51.020 15108-15346 OMXClient androidx.media3.demo.main I IOmx service obtained
2025-11-01 14:25:51.025 15108-15335 android_os_HwBinder androidx.media3.demo.main I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-01 14:25:51.027 15108-15335 chatty androidx.media3.demo.main I uid=10133(androidx.media3.demo.main) ExoPlayer:Playb identical 1 line
2025-11-01 14:25:51.031 15108-15335 android_os_HwBinder androidx.media3.demo.main I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-01 14:25:51.033 15108-15346 MediaCodec androidx.media3.demo.main D flushMediametrics
2025-11-01 14:25:51.034 15108-15346 ACodec androidx.media3.demo.main I codec does not support config priority (err -2147483648)
2025-11-01 14:25:51.052 15108-15108 EventLogger androidx.media3.demo.main D audioDecoderInitialized [eventTime=0.35, mediaPos=0.00, window=0, period=0, OMX.google.aac.decoder]
2025-11-01 14:25:51.053 15108-15108 EventLogger androidx.media3.demo.main D audioInputFormat [eventTime=0.36, mediaPos=0.00, window=0, period=0, id=1, mimeType=audio/mp4a-latm, container=video/mp4, bitrate=102352, codecs=mp4a.40.2, channels=2, sample_rate=44100, language=und]
2025-11-01 14:25:51.055 15108-15108 EventLogger androidx.media3.demo.main D downstreamFormat [eventTime=0.36, mediaPos=0.00, window=0, period=0, id=null, mimeType=application/x-media3-cues, codecs=text/vtt, language=en, selectionFlags=[default]]
2025-11-01 14:25:51.056 15108-15108 EventLogger androidx.media3.demo.main D rendererReady [eventTime=0.36, mediaPos=0.00, window=0, period=0, rendererIndex=2, text, true]
2025-11-01 14:25:51.072 15108-15108 EventLogger androidx.media3.demo.main D audioTrackInit [eventTime=0.37, mediaPos=0.00, window=0, period=0, enc=pcm-16, channelConf=stereo, sampleRate=44100, bufferSize=44100]
2025-11-01 14:25:51.079 15108-15108 EventLogger androidx.media3.demo.main D rendererReady [eventTime=0.38, mediaPos=0.00, window=0, period=0, rendererIndex=1, audio, true]
2025-11-01 14:25:51.087 15108-15340 ACodec androidx.media3.demo.main E dequeueBuffer failed: WOULD_BLOCK(-11).
2025-11-01 14:25:51.088 15108-15340 chatty androidx.media3.demo.main I uid=10133(androidx.media3.demo.main) JNISurfaceTextu identical 1 line
2025-11-01 14:25:51.088 15108-15340 ACodec androidx.media3.demo.main E dequeueBuffer failed: WOULD_BLOCK(-11).
2025-11-01 14:25:51.101 15108-15108 EventLogger androidx.media3.demo.main D rendererReady [eventTime=0.40, mediaPos=0.00, window=0, period=0, rendererIndex=0, video, true]
2025-11-01 14:25:51.106 15108-15338 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_ASYNC"
2025-11-01 14:25:51.106 15108-15338 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_LOGFPS"
2025-11-01 14:25:51.106 15108-15338 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_PROCESS_NAME"
2025-11-01 14:25:51.106 15108-15338 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_FPSDYNAMIC"
2025-11-01 14:25:51.107 15108-15108 EventLogger androidx.media3.demo.main D state [eventTime=0.41, mediaPos=0.00, window=0, period=0, READY]
2025-11-01 14:25:51.113 15108-15108 EventLogger androidx.media3.demo.main D isPlaying [eventTime=0.41, mediaPos=0.00, window=0, period=0, true]
2025-11-01 14:25:51.116 15108-15108 EventLogger androidx.media3.demo.main D renderedFirstFrame [eventTime=0.42, mediaPos=0.00, window=0, period=0, Surface(name=null)/@0xc580362]
2025-11-01 14:25:51.121 15108-15335 AudioTrack androidx.media3.demo.main D getTimestamp_l(91): device stall time corrected using current time 14897422644942
2025-11-01 14:25:51.200 15108-15137 Surface androidx.media3.demo.main E freeAllBuffers: 1 buffers were freed while being dequeued!
2025-11-01 14:25:51.212 15108-15108 EventLogger androidx.media3.demo.main D audioPositionAdvancing [eventTime=0.51, mediaPos=0.06, window=0, period=0, since 0.47]
2025-11-01 14:25:51.490 15108-15108 EventLogger androidx.media3.demo.main D loading [eventTime=0.79, mediaPos=0.31, window=0, period=0, false]
2025-11-01 14:25:55.969 15108-15108 EventLogger androidx.media3.demo.main D playWhenReady [eventTime=5.27, mediaPos=4.79, window=0, period=0, false, USER_REQUEST]
2025-11-01 14:25:56.004 15108-15108 EventLogger androidx.media3.demo.main D isPlaying [eventTime=5.31, mediaPos=4.79, window=0, period=0, false]
With no filter, I get this:
2025-11-01 14:25:40.169 3390-3521 NvOsDebugPrintf [email protected] D NvRmStreamFree: WARN: pStream is NULL
2025-11-01 14:25:40.623 13323-13323 ThreadPoolForeg com.google.android.gms W type=1400 audit(0.0:1180): avc: denied { write } for name="traced_producer" dev="tmpfs" ino=22014 scontext=u:r:gmscore_app:s0:c512,c768 tcontext=u:object_r:traced_producer_socket:s0 tclass=sock_file permissive=0 app=com.google.android.gms
2025-11-01 14:25:40.781 13323-13323 BoundBrokerSvc com.google.android.gms D onUnbind: Intent { act=com.google.android.gms.common.BIND_SHARED_PREFS pkg=com.google.android.gms }
2025-11-01 14:25:41.446 3893-4363 nv_bt_stats com.android.bluetooth I btif_nv_stats_update_host_stats() addr: ff:ff:ff:ff:ff:ff , json string: cmd_timeout_rsp_after_to
2025-11-01 14:25:45.172 3390-3521 hdcp up [email protected] V tsec_version: 1
2025-11-01 14:25:45.448 3893-4363 nv_bt_stats com.android.bluetooth I btif_nv_stats_update_host_stats() addr: ff:ff:ff:ff:ff:ff , json string: cmd_timeout_rsp_after_to
2025-11-01 14:25:45.831 3390-3521 NvOsDebugPrintf [email protected] D NvRmStreamFree: WARN: pStream is NULL
2025-11-01 14:25:47.092 3370-8614 hdcp up [email protected] V SecureHdcp_GetCurrentLevel: cur: 0x01, max: 0x04
2025-11-01 14:25:49.452 3893-4363 nv_bt_stats com.android.bluetooth I btif_nv_stats_update_host_stats() addr: ff:ff:ff:ff:ff:ff , json string: cmd_timeout_rsp_after_to
2025-11-01 14:25:49.852 3714-3790 WindowManager system_server D handleComboKeys keyCode: 20, keyAction: 0
2025-11-01 14:25:49.867 3714-4527 system_server system_server I oneway function results will be dropped but finished with status OK and parcel size 4
2025-11-01 14:25:49.868 3714-4527 system_server system_server I oneway function results will be dropped but finished with status OK and parcel size 4
2025-11-01 14:25:49.927 3714-3790 WindowManager system_server D handleComboKeys keyCode: 20, keyAction: 1
2025-11-01 14:25:50.012 3714-4527 system_server system_server I oneway function results will be dropped but finished with status OK and parcel size 4
2025-11-01 14:25:50.027 3714-3790 WindowManager system_server D handleComboKeys keyCode: 20, keyAction: 0
2025-11-01 14:25:50.039 3714-10446 system_server system_server I oneway function results will be dropped but finished with status OK and parcel size 4
2025-11-01 14:25:50.127 3714-3790 WindowManager system_server D handleComboKeys keyCode: 20, keyAction: 1
2025-11-01 14:25:50.172 3714-10446 system_server system_server I oneway function results will be dropped but finished with status OK and parcel size 4
2025-11-01 14:25:50.177 3714-3790 WindowManager system_server D handleComboKeys keyCode: 20, keyAction: 0
2025-11-01 14:25:50.186 3714-10446 system_server system_server I oneway function results will be dropped but finished with status OK and parcel size 4
2025-11-01 14:25:50.225 5612-8374 cast_shell com.google.android.apps.mediashell I [1101/142550.224992:INFO:ssdp_device.c(101)] SSDP packets sent for 35 seconds = 11
2025-11-01 14:25:50.302 3714-3790 WindowManager system_server D handleComboKeys keyCode: 20, keyAction: 1
2025-11-01 14:25:50.332 3714-10446 system_server system_server I oneway function results will be dropped but finished with status OK and parcel size 4
2025-11-01 14:25:50.453 3714-3790 WindowManager system_server D handleComboKeys keyCode: 23, keyAction: 0
2025-11-01 14:25:50.552 3714-3790 WindowManager system_server D handleComboKeys keyCode: 23, keyAction: 1
2025-11-01 14:25:50.560 3714-3736 EventSequenceValidator system_server D inc AccIntentStartedEvents to 2
2025-11-01 14:25:50.560 3714-10446 ActivityTaskManager system_server I START u0 {act=androidx.media3.demo.main.action.VIEW dat=https://html5demos.com/... cmp=androidx.media3.demo.main/.PlayerActivity (has extras)} from uid 10133
2025-11-01 14:25:50.562 3397-3397 powerHAL::common [email protected] W Power hint:0x8 is not supported in PowerHAL
2025-11-01 14:25:50.566 3714-4527 system_server system_server I oneway function results will be dropped but finished with status OK and parcel size 4
2025-11-01 14:25:50.567 3714-3736 EventSequenceValidator system_server D dec AccIntentStartedEvents to 1
2025-11-01 14:25:50.570 3714-10446 ActivityTaskManager system_server W Tried to set launchTime (0) < mLastActivityLaunchTime (14839404)
2025-11-01 14:25:50.576 3714-15199 NvAppProfileService system_server W App Profiles: Enabled
2025-11-01 14:25:50.578 4637-4662 android_os_HwBinder com.nvidia.NvCPLSvc I HwBinder: Starting thread pool for getting: [email protected]::IPower/default
2025-11-01 14:25:50.588 3714-15334 android_os_HwBinder system_server I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-01 14:25:50.615 15108-15108 android_os_HwBinder androidx.media3.demo.main I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-01 14:25:50.693 15108-15108 ExoPlayerImpl androidx.media3.demo.main I Init 556299e [AndroidXMedia3/1.9.0-alpha01] [mdarcy, SHIELD Android TV, NVIDIA, 30]
2025-11-01 14:25:50.699 15108-15108 EventLogger androidx.media3.demo.main D playWhenReady [eventTime=0.00, mediaPos=0.00, window=0, true, USER_REQUEST]
2025-11-01 14:25:50.699 3714-10446 system_server system_server I oneway function results will be dropped but finished with status OK and parcel size 4
2025-11-01 14:25:50.703 15108-15108 EventLogger androidx.media3.demo.main D surfaceSize [eventTime=0.01, mediaPos=0.00, window=0, w=0, h=0]
2025-11-01 14:25:50.708 15108-15108 EventLogger androidx.media3.demo.main D timeline [eventTime=0.01, mediaPos=0.00, window=0, periodCount=1, windowCount=1, reason=PLAYLIST_CHANGED
2025-11-01 14:25:50.708 15108-15108 EventLogger androidx.media3.demo.main D period [?]
2025-11-01 14:25:50.708 15108-15108 EventLogger androidx.media3.demo.main D window [?, seekable=false, dynamic=true]
2025-11-01 14:25:50.708 15108-15108 EventLogger androidx.media3.demo.main D ]
2025-11-01 14:25:50.709 15108-15108 EventLogger androidx.media3.demo.main D mediaItem [eventTime=0.01, mediaPos=0.00, window=0, reason=PLAYLIST_CHANGED]
2025-11-01 14:25:50.711 3714-4527 MediaFocusControl system_server I requestAudioFocus() from uid/pid 10133/15108 clientId=android.media.AudioManager@b37e223androidx.media3.common.audio.AudioFocusManager$$ExternalSyntheticLambda0@5a59c95 callingPack=androidx.media3.demo.main req=1 flags=0x0 sdk=34
2025-11-01 14:25:50.711 15108-15108 EventLogger androidx.media3.demo.main D state [eventTime=0.01, mediaPos=0.00, window=0, BUFFERING]
2025-11-01 14:25:50.717 15108-15108 android_os_HwBinder androidx.media3.demo.main I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-01 14:25:50.721 15108-15108 android_os_HwBinder androidx.media3.demo.main I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-01 14:25:50.726 3714-4527 NvUtils system_server I onActivityResumed AM packageName = androidx.media3.demo.main
2025-11-01 14:25:50.727 3714-4527 AudioSystem-JNI system_server E Command failed for android_media_AudioSystem_setParameters: -22
2025-11-01 14:25:50.728 3714-4527 chatty system_server I uid=1000(system) Binder:3714_A identical 2 lines
2025-11-01 14:25:50.728 3714-4527 AudioSystem-JNI system_server E Command failed for android_media_AudioSystem_setParameters: -22
2025-11-01 14:25:50.759 15108-15137 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_ASYNC"
2025-11-01 14:25:50.759 15108-15137 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_LOGFPS"
2025-11-01 14:25:50.759 15108-15137 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_PROCESS_NAME"
2025-11-01 14:25:50.759 15108-15137 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_FPSDYNAMIC"
2025-11-01 14:25:50.763 15108-15108 EventLogger androidx.media3.demo.main D surfaceSize [eventTime=0.06, mediaPos=0.00, window=0, w=1920, h=1080]
2025-11-01 14:25:50.774 15108-15108 EventLogger androidx.media3.demo.main D audioSessionId [eventTime=0.08, mediaPos=0.00, window=0, 137]
2025-11-01 14:25:50.776 3714-3741 ActivityTaskManager system_server I Displayed androidx.media3.demo.main/.PlayerActivity: +216ms
2025-11-01 14:25:50.777 15108-15108 EventLogger androidx.media3.demo.main D loading [eventTime=0.08, mediaPos=0.00, window=0, period=0, true]
2025-11-01 14:25:50.780 15108-15108 EventLogger androidx.media3.demo.main D timeline [eventTime=0.08, mediaPos=0.00, window=0, period=0, periodCount=1, windowCount=1, reason=SOURCE_UPDATE
2025-11-01 14:25:50.780 15108-15108 EventLogger androidx.media3.demo.main D period [?]
2025-11-01 14:25:50.780 15108-15108 EventLogger androidx.media3.demo.main D window [?, seekable=false, dynamic=false]
2025-11-01 14:25:50.780 15108-15108 EventLogger androidx.media3.demo.main D ]
2025-11-01 14:25:50.800 4386-4386 GoogleInpu...hodService com...gle.android.inputmethod.latin I GoogleInputMethodService.onFinishInput():2084
2025-11-01 14:25:50.800 4386-4386 Module com...gle.android.inputmethod.latin I DeviceLockedStatusModuleProvider$Module.updateDeviceLockedStatus():101 repeatCheckTimes = 0, locked = false
2025-11-01 14:25:50.801 4386-4386 GoogleInpu...hodService com...gle.android.inputmethod.latin I GoogleInputMethodService.onStartInput():1345 onStartInput(EditorInfo{EditorInfo{packageName=androidx.media3.demo.main, inputType=0, inputTypeString=NULL, enableLearning=false, autoCorrection=false, autoComplete=false, imeOptions=0, privateImeOptions=null, actionName=UNSPECIFIED, actionLabel=null, initialSelStart=-1, initialSelEnd=-1, initialCapsMode=0, label=null, fieldId=2131362160, fieldName=null, extras=null, hintText=null, hintLocales=[]}}, false)
2025-11-01 14:25:50.802 4386-4386 Module com...gle.android.inputmethod.latin I DeviceLockedStatusModuleProvider$Module.updateDeviceLockedStatus():101 repeatCheckTimes = 1, locked = false
2025-11-01 14:25:50.835 3390-3521 hdcp up [email protected] V tsec_version: 1
2025-11-01 14:25:50.906 15108-15336 MetadataUtil androidx.media3.demo.main D Skipped unknown metadata entry: gsst
2025-11-01 14:25:50.906 15108-15336 MetadataUtil androidx.media3.demo.main D Skipped unknown metadata entry: gstd
2025-11-01 14:25:50.906 15108-15336 MetadataUtil androidx.media3.demo.main D Skipped unknown metadata entry: gssd
2025-11-01 14:25:50.906 15108-15336 MetadataUtil androidx.media3.demo.main D Skipped unknown metadata entry: gspu
2025-11-01 14:25:50.906 15108-15336 MetadataUtil androidx.media3.demo.main D Skipped unknown metadata entry: gspm
2025-11-01 14:25:50.922 3410-3605 APM_AudioPolicyManager audioserver I getProfileForOutput: Current module is msd ignore this profile
2025-11-01 14:25:50.929 3410-3605 chatty audioserver I uid=1041(audioserver) Binder:3410_1 identical 11 lines
2025-11-01 14:25:50.932 3410-3605 APM_AudioPolicyManager audioserver I getProfileForOutput: Current module is msd ignore this profile
2025-11-01 14:25:50.934 15108-15108 EventLogger androidx.media3.demo.main D timeline [eventTime=0.24, mediaPos=0.00, window=0, period=0, periodCount=1, windowCount=1, reason=SOURCE_UPDATE
2025-11-01 14:25:50.935 15108-15108 EventLogger androidx.media3.demo.main D period [25.00]
2025-11-01 14:25:50.935 15108-15108 EventLogger androidx.media3.demo.main D window [25.00, seekable=true, dynamic=false]
2025-11-01 14:25:50.935 15108-15108 EventLogger androidx.media3.demo.main D ]
2025-11-01 14:25:50.943 15108-15338 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_ASYNC"
2025-11-01 14:25:50.943 15108-15338 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_LOGFPS"
2025-11-01 14:25:50.944 15108-15338 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_PROCESS_NAME"
2025-11-01 14:25:50.944 15108-15338 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_FPSDYNAMIC"
2025-11-01 14:25:50.951 15108-15108 EventLogger androidx.media3.demo.main D videoEnabled [eventTime=0.25, mediaPos=0.00, window=0, period=0]
2025-11-01 14:25:50.952 15108-15108 EventLogger androidx.media3.demo.main D audioEnabled [eventTime=0.25, mediaPos=0.00, window=0, period=0]
2025-11-01 14:25:50.955 15108-15108 EventLogger androidx.media3.demo.main D tracks [eventTime=0.26, mediaPos=0.00, window=0, period=0
2025-11-01 14:25:50.955 15108-15108 EventLogger androidx.media3.demo.main D group [ id=0:1
2025-11-01 14:25:50.955 15108-15108 EventLogger androidx.media3.demo.main D [X] Track:0, id=0:2, mimeType=video/avc, container=video/mp4, bitrate=494792, codecs=avc1.42C01E, res=480x360, color=NA/NA/NA/8/8, fps=30.0, language=und, supported=YES
2025-11-01 14:25:50.955 15108-15108 EventLogger androidx.media3.demo.main D ]
2025-11-01 14:25:50.955 15108-15108 EventLogger androidx.media3.demo.main D group [ id=0:0
2025-11-01 14:25:50.955 15108-15108 EventLogger androidx.media3.demo.main D [X] Track:0, id=0:1, mimeType=audio/mp4a-latm, container=video/mp4, bitrate=102352, codecs=mp4a.40.2, channels=2, sample_rate=44100, language=und, supported=YES
2025-11-01 14:25:50.955 15108-15108 EventLogger androidx.media3.demo.main D ]
2025-11-01 14:25:50.955 15108-15108 EventLogger androidx.media3.demo.main D group [ id=1:0
2025-11-01 14:25:50.955 15108-15108 EventLogger androidx.media3.demo.main D [X] Track:0, id=1:, mimeType=application/x-media3-cues, codecs=text/vtt, language=en, selectionFlags=[default], supported=YES
2025-11-01 14:25:50.955 15108-15108 EventLogger androidx.media3.demo.main D ]
2025-11-01 14:25:50.955 15108-15108 EventLogger androidx.media3.demo.main D Metadata [
2025-11-01 14:25:50.956 15108-15108 EventLogger androidx.media3.demo.main D Mp4Timestamp: creation time=3339325113, modification time=3339325113, timescale=600
2025-11-01 14:25:50.956 15108-15108 EventLogger androidx.media3.demo.main D ThumbnailMetadata: presentationTimeUs=6800000
2025-11-01 14:25:50.956 15108-15108 EventLogger androidx.media3.demo.main D ]
2025-11-01 14:25:50.956 15108-15108 EventLogger androidx.media3.demo.main D ]
2025-11-01 14:25:50.965 15108-15335 android_os_HwBinder androidx.media3.demo.main I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-01 14:25:50.968 15108-15108 EventLogger androidx.media3.demo.main D downstreamFormat [eventTime=0.27, mediaPos=0.00, window=0, period=0, id=2, mimeType=video/avc, container=video/mp4, bitrate=494792, codecs=avc1.42C01E, res=480x360, color=NA/NA/NA/8/8, fps=30.0, language=und]
2025-11-01 14:25:50.969 15108-15340 OMXClient androidx.media3.demo.main I IOmx service obtained
2025-11-01 14:25:50.970 3579-3964 OMXMaster media.codec I makeComponentInstance(OMX.Nvidia.h264.decode) in android.hardwar process
2025-11-01 14:25:50.974 15108-15335 android_os_HwBinder androidx.media3.demo.main I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-01 14:25:50.977 15108-15335 chatty androidx.media3.demo.main I uid=10133(androidx.media3.demo.main) ExoPlayer:Playb identical 1 line
2025-11-01 14:25:50.979 15108-15335 android_os_HwBinder androidx.media3.demo.main I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-01 14:25:50.980 15108-15335 MediaCodec androidx.media3.demo.main I mContentRefreshRate = 30.0
2025-11-01 14:25:50.981 15108-15339 MediaCodec androidx.media3.demo.main D flushMediametrics
2025-11-01 14:25:50.981 15108-15339 SurfaceUtils androidx.media3.demo.main D connecting to surface 0x287c42e7b0, reason connectToSurface
2025-11-01 14:25:50.981 15108-15339 MediaCodec androidx.media3.demo.main I [OMX.Nvidia.h264.decode] setting surface generation to 15470594
2025-11-01 14:25:50.981 15108-15339 SurfaceUtils androidx.media3.demo.main D disconnecting from surface 0x287c42e7b0, reason connectToSurface(reconnect)
2025-11-01 14:25:50.981 15108-15339 SurfaceUtils androidx.media3.demo.main D connecting to surface 0x287c42e7b0, reason connectToSurface(reconnect)
2025-11-01 14:25:50.981 15108-15339 SurfaceUtils androidx.media3.demo.main D legacy buffer drop disabled: target sdk (34)
2025-11-01 14:25:50.982 3579-3964 NvOsDebugPrintf media.codec D NVX_IndexParamAllocateNativeHandle : Using Native Handles
2025-11-01 14:25:50.982 3579-3964 OMXNodeInstance media.codec E getParameter(0x4bac0a20:Nvidia.h264.decode, ??(0x6f600011)) ERROR: NotImplemented(0x80001006)
2025-11-01 14:25:50.984 3579-15341 NvOsDebugPrintf media.codec D NvMMLiteOpen : Block : BlockType = 261
2025-11-01 14:25:50.984 3579-15341 NvOsDebugPrintf media.codec D NVMEDIA: NvMMLiteTVMRDecBlockOpen: 3532: NvMMLiteBlockOpen
2025-11-01 14:25:50.986 3579-15341 NvOsDebugPrintf media.codec D NVMEDIA: RefreshRate = 59.940000
2025-11-01 14:25:50.986 3579-15341 NvOsDebugPrintf media.codec D NVMEDIA: Reading vendor.tegra.display-size : status: 0
2025-11-01 14:25:50.986 3579-15341 NvOsDebugPrintf media.codec D NVMEDIA: DisplayWidth = 3840, DisplayHeight = 2160
2025-11-01 14:25:50.987 3579-15341 NvOsDebugPrintf media.codec D NVMEDIA: RefreshRate = 59.940000
2025-11-01 14:25:50.987 3579-15341 NvOsDebugPrintf media.codec D NvMMLiteBlockCreate : Block : BlockType = 261
2025-11-01 14:25:50.988 3579-15341 NvOsDebugPrintf media.codec D WARNING! HDRStatic info being set, even for non SMPTE 2084 case
2025-11-01 14:25:50.988 3579-15341 NvOsDebugPrintf media.codec D WARNING! This media may play as non HDR
2025-11-01 14:25:50.988 15108-15340 ACodec androidx.media3.demo.main I Enable timestamp filtering for Video Decoder
2025-11-01 14:25:50.989 3579-3964 OMXNodeInstance media.codec E getConfig(0x4bac0a20:Nvidia.h264.decode, ConfigAndroidVendorExtension(0x6f100004)) ERROR: UnsupportedIndex(0x8000101a)
2025-11-01 14:25:50.989 15108-15340 ACodec androidx.media3.demo.main I Get OMX_IndexConfigScale: 0x10000/0x10000
2025-11-01 14:25:50.989 15108-15340 ACodec androidx.media3.demo.main I Scaled Width is 480, height is 360
2025-11-01 14:25:50.994 15108-15340 SurfaceUtils androidx.media3.demo.main D set up nativeWindow 0x287c42e7b0 for 480x360, color 0x106, rotation 0, usage 0x2900
2025-11-01 14:25:50.994 3575-15174 ResourceManagerService mediaserver W Ignoring request to add new resource entry with value <= 0
2025-11-01 14:25:51.000 15108-15108 EventLogger androidx.media3.demo.main D videoDecoderInitialized [eventTime=0.30, mediaPos=0.00, window=0, period=0, OMX.Nvidia.h264.decode]
2025-11-01 14:25:51.002 15108-15108 EventLogger androidx.media3.demo.main D videoInputFormat [eventTime=0.30, mediaPos=0.00, window=0, period=0, id=2, mimeType=video/avc, container=video/mp4, bitrate=494792, codecs=avc1.42C01E, res=480x360, color=NA/NA/NA/8/8, fps=30.0, language=und]
2025-11-01 14:25:51.004 3579-15343 NvOsDebugPrintf media.codec D NVMEDIA: RefreshRate = 59.940000
2025-11-01 14:25:51.004 3579-15343 NvOsDebugPrintf media.codec D NVMEDIA: bEnableVideoSmootheningbEnable = 0
2025-11-01 14:25:51.004 3579-15343 NvOsDebugPrintf media.codec D NVMEDIA: cbBeginSequence: 175: BeginSequence 480x368, bVPR = 0
2025-11-01 14:25:51.004 3579-15343 NvOsDebugPrintf media.codec D NVMEDIA: cbBeginSequence: 551: DecodeBuffers = 2, pnvsi->eCodec = 0
2025-11-01 14:25:51.006 3579-15343 NvOsDebugPrintf media.codec D NVMEDIA: cbBeginSequence: 630: Display Resolution : (480x360)
2025-11-01 14:25:51.006 3579-15343 NvOsDebugPrintf media.codec D NVMEDIA: cbBeginSequence: 631: Display Aspect Ratio : (480x360)
2025-11-01 14:25:51.006 3579-15343 NvOsDebugPrintf media.codec D NVMEDIA: cbBeginSequence: 727: ColorFormat : 5
2025-11-01 14:25:51.006 3579-15343 NvOsDebugPrintf media.codec D NVMEDIA: cbBeginSequence:741 ColorSpace = NvColorSpace_YCbCr601
2025-11-01 14:25:51.006 3579-15343 NvOsDebugPrintf media.codec D NVMEDIA: cbBeginSequence: 857: SurfaceLayout = 3
2025-11-01 14:25:51.006 3579-15343 NvOsDebugPrintf media.codec D NVMEDIA: cbBeginSequence: 991: NumOfSurfaces = 6, InteraceStream = 0, InterlaceEnabled = 0, bSecure = 0, MVC = 0 Semiplanar = 1, bReinit = 1, BitDepthForSurface = 8 LumaBitDepth = 8, ChromaBitDepth = 8, ChromaFormat = 5
2025-11-01 14:25:51.006 3579-15343 NvOsDebugPrintf media.codec D NVMEDIA: cbBeginSequence: 993: BeginSequence ColorPrimaries = 2, TransferCharacteristics = 2, MatrixCoefficients = 2
2025-11-01 14:25:51.006 3579-15343 NvOsDebugPrintf media.codec D Allocating new output: 480x368 (x 6), ThumbnailMode = 0
2025-11-01 14:25:51.006 3579-15343 NvOsDebugPrintf media.codec D WARNING Bad Color Primaries in stream!
2025-11-01 14:25:51.006 3579-15343 NvOsDebugPrintf media.codec D WARNING Bad TransferCharacterstics in stream!
2025-11-01 14:25:51.006 3579-15343 NvOsDebugPrintf media.codec D WARNING Bad MatrixCoefficients in stream!
2025-11-01 14:25:51.006 3579-15343 NvOsDebugPrintf media.codec D
bitDepth ========================================> 8
NewStreamInfo->bValidMasteringDispData ==========> 0
bAllowClientValues ==============================> 0
NewStreamInfo->bValidContentLightLevelInfo ======> 0
2025-11-01 14:25:51.006 3579-15343 NvOsDebugPrintf media.codec D OPENMAX: HandleNewStreamFormat: 3609: Send OMX_EventPortSettingsChanged: nFrameWidth = 480, nFrameHeight = 360
2025-11-01 14:25:51.007 15108-15340 ACodec androidx.media3.demo.main I Get OMX_IndexConfigScale: 0x10000/0x10000
2025-11-01 14:25:51.008 15108-15340 ACodec androidx.media3.demo.main I Scaled Width is 480, height is 360
2025-11-01 14:25:51.008 3410-3605 APM_AudioPolicyManager audioserver I getProfileForOutput: Current module is msd ignore this profile
2025-11-01 14:25:51.009 15108-15340 SurfaceUtils androidx.media3.demo.main D set up nativeWindow 0x287c42e7b0 for 480x360, color 0x106, rotation 0, usage 0x2900
2025-11-01 14:25:51.009 3410-3605 APM_AudioPolicyManager audioserver I getProfileForOutput: Current module is msd ignore this profile
2025-11-01 14:25:51.014 15108-15335 android_os_HwBinder androidx.media3.demo.main I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-01 14:25:51.016 3579-15343 NvOsDebugPrintf media.codec D NVMEDIA: FrameRate = 30
2025-11-01 14:25:51.018 15108-15340 ACodec androidx.media3.demo.main I Get OMX_IndexConfigScale: 0x10000/0x10000
2025-11-01 14:25:51.018 15108-15340 ACodec androidx.media3.demo.main I Scaled Width is 480, height is 360
2025-11-01 14:25:51.019 15108-15108 EventLogger androidx.media3.demo.main D downstreamFormat [eventTime=0.32, mediaPos=0.00, window=0, period=0, id=1, mimeType=audio/mp4a-latm, container=video/mp4, bitrate=102352, codecs=mp4a.40.2, channels=2, sample_rate=44100, language=und]
2025-11-01 14:25:51.020 15108-15346 OMXClient androidx.media3.demo.main I IOmx service obtained
2025-11-01 14:25:51.020 3579-3964 OMXMaster media.codec I makeComponentInstance(OMX.google.aac.decoder) in android.hardwar process
2025-11-01 14:25:51.025 15108-15335 android_os_HwBinder androidx.media3.demo.main I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-01 14:25:51.027 15108-15335 chatty androidx.media3.demo.main I uid=10133(androidx.media3.demo.main) ExoPlayer:Playb identical 1 line
2025-11-01 14:25:51.031 15108-15335 android_os_HwBinder androidx.media3.demo.main I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-01 14:25:51.033 15108-15346 MediaCodec androidx.media3.demo.main D flushMediametrics
2025-11-01 14:25:51.034 3579-3997 OMXNodeInstance media.codec E setConfig(0x4bac0400:google.aac.decoder, ConfigPriority(0x6f800002)) ERROR: Undefined(0x80001001)
2025-11-01 14:25:51.034 15108-15346 ACodec androidx.media3.demo.main I codec does not support config priority (err -2147483648)
2025-11-01 14:25:51.034 3579-3997 OMXNodeInstance media.codec E getConfig(0x4bac0400:google.aac.decoder, ConfigAndroidVendorExtension(0x6f100004)) ERROR: Undefined(0x80001001)
2025-11-01 14:25:51.035 3579-3997 SoftAAC2 media.codec D get OMX_IndexParamAudioAndroidAacDrcPresentation
2025-11-01 14:25:51.035 3579-15347 SimpleSoftOMXComponent media.codec I state: 2, mState: 1 mTargetState: 1
2025-11-01 14:25:51.038 3579-15347 SimpleSoftOMXComponent media.codec I state: 3, mState: 2 mTargetState: 2
2025-11-01 14:25:51.040 3579-15347 SoftAAC2 media.codec I Reconfiguring decoder: 0->44100 Hz, 0->2 channels
2025-11-01 14:25:51.041 3579-3964 SoftAAC2 media.codec D get OMX_IndexParamAudioAndroidAacDrcPresentation
2025-11-01 14:25:51.045 3410-3605 APM_AudioPolicyManager audioserver I getProfileForOutput: Current module is msd ignore this profile
2025-11-01 14:25:51.047 3410-3605 chatty audioserver I uid=1041(audioserver) Binder:3410_1 identical 5 lines
2025-11-01 14:25:51.048 3410-3605 APM_AudioPolicyManager audioserver I getProfileForOutput: Current module is msd ignore this profile
2025-11-01 14:25:51.052 15108-15108 EventLogger androidx.media3.demo.main D audioDecoderInitialized [eventTime=0.35, mediaPos=0.00, window=0, period=0, OMX.google.aac.decoder]
2025-11-01 14:25:51.053 15108-15108 EventLogger androidx.media3.demo.main D audioInputFormat [eventTime=0.36, mediaPos=0.00, window=0, period=0, id=1, mimeType=audio/mp4a-latm, container=video/mp4, bitrate=102352, codecs=mp4a.40.2, channels=2, sample_rate=44100, language=und]
2025-11-01 14:25:51.055 15108-15108 EventLogger androidx.media3.demo.main D downstreamFormat [eventTime=0.36, mediaPos=0.00, window=0, period=0, id=null, mimeType=application/x-media3-cues, codecs=text/vtt, language=en, selectionFlags=[default]]
2025-11-01 14:25:51.056 15108-15108 EventLogger androidx.media3.demo.main D rendererReady [eventTime=0.36, mediaPos=0.00, window=0, period=0, rendererIndex=2, text, true]
2025-11-01 14:25:51.057 3397-3397 powerHAL::common [email protected] W Power hint:0x8 is not supported in PowerHAL
2025-11-01 14:25:51.060 3410-3605 APM_AudioPolicyManager audioserver I getOutputForAttrInt() device {type:0x400,@:}, sampling rate 44100, format 0x1, channel mask 0x3, flags 0x8 stream AUDIO_STREAM_MUSIC
2025-11-01 14:25:51.061 3364-4471 nvaudio_hw android.hardware.audio.service I prop_get:hw_dev->is_native_sample_rate:0
2025-11-01 14:25:51.062 3410-3605 APM_AudioPolicyManager audioserver I getOutputForAttrInt returns output 13 selectedDeviceId 81 for device {type:0x400,@:}, sampling rate 44100, format 0x1, channel mask 0x3, flags 0x8 stream AUDIO_STREAM_MUSIC
2025-11-01 14:25:51.062 3410-3605 AudioFlinger audioserver W createTrack_l(): mismatch between requested flags (00000008) and output flags (00000006)
2025-11-01 14:25:51.063 3410-3605 AudioFlinger audioserver D Client defaulted notificationFrames to 5512 for frameCount 11025
2025-11-01 14:25:51.066 3410-3605 AF::TrackHandle audioserver D OpPlayAudio: track:70 usage:1 not muted
2025-11-01 14:25:51.072 15108-15108 EventLogger androidx.media3.demo.main D audioTrackInit [eventTime=0.37, mediaPos=0.00, window=0, period=0, enc=pcm-16, channelConf=stereo, sampleRate=44100, bufferSize=44100]
2025-11-01 14:25:51.072 3714-4527 system_server system_server I oneway function results will be dropped but finished with status OK and parcel size 4
2025-11-01 14:25:51.073 3714-4527 system_server system_server I oneway function results will be dropped but finished with status OK and parcel size 4
2025-11-01 14:25:51.073 3714-10446 system_server system_server I oneway function results will be dropped but finished with status OK and parcel size 4
2025-11-01 14:25:51.073 3714-4527 system_server system_server I oneway function results will be dropped but finished with status OK and parcel size 4
2025-11-01 14:25:51.079 15108-15108 EventLogger androidx.media3.demo.main D rendererReady [eventTime=0.38, mediaPos=0.00, window=0, period=0, rendererIndex=1, audio, true]
2025-11-01 14:25:51.087 15108-15340 ACodec androidx.media3.demo.main E dequeueBuffer failed: WOULD_BLOCK(-11).
2025-11-01 14:25:51.088 15108-15340 chatty androidx.media3.demo.main I uid=10133(androidx.media3.demo.main) JNISurfaceTextu identical 1 line
2025-11-01 14:25:51.088 15108-15340 ACodec androidx.media3.demo.main E dequeueBuffer failed: WOULD_BLOCK(-11).
2025-11-01 14:25:51.101 15108-15108 EventLogger androidx.media3.demo.main D rendererReady [eventTime=0.40, mediaPos=0.00, window=0, period=0, rendererIndex=0, video, true]
2025-11-01 14:25:51.104 4062-4062 MainFragment com.android.tv.settings W playback is not active, set prefers disabled
2025-11-01 14:25:51.106 15108-15338 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_ASYNC"
2025-11-01 14:25:51.106 15108-15338 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_LOGFPS"
2025-11-01 14:25:51.106 15108-15338 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_PROCESS_NAME"
2025-11-01 14:25:51.106 15108-15338 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_FPSDYNAMIC"
2025-11-01 14:25:51.107 15108-15108 EventLogger androidx.media3.demo.main D state [eventTime=0.41, mediaPos=0.00, window=0, period=0, READY]
2025-11-01 14:25:51.112 3714-4527 system_server system_server I oneway function results will be dropped but finished with status OK and parcel size 4
2025-11-01 14:25:51.113 15108-15108 EventLogger androidx.media3.demo.main D isPlaying [eventTime=0.41, mediaPos=0.00, window=0, period=0, true]
2025-11-01 14:25:51.116 15108-15108 EventLogger androidx.media3.demo.main D renderedFirstFrame [eventTime=0.42, mediaPos=0.00, window=0, period=0, Surface(name=null)/@0xc580362]
2025-11-01 14:25:51.118 3410-4382 APM_AudioPolicyManager audioserver I getProfileForOutput: Current module is msd ignore this profile
2025-11-01 14:25:51.120 3410-4382 chatty audioserver I uid=1041(audioserver) Binder:3410_6 identical 5 lines
2025-11-01 14:25:51.120 3410-4382 APM_AudioPolicyManager audioserver I getProfileForOutput: Current module is msd ignore this profile
2025-11-01 14:25:51.121 15108-15335 AudioTrack androidx.media3.demo.main D getTimestamp_l(91): device stall time corrected using current time 14897422644942
2025-11-01 14:25:51.200 15108-15137 Surface androidx.media3.demo.main E freeAllBuffers: 1 buffers were freed while being dequeued!
2025-11-01 14:25:51.212 15108-15108 EventLogger androidx.media3.demo.main D audioPositionAdvancing [eventTime=0.51, mediaPos=0.06, window=0, period=0, since 0.47]
2025-11-01 14:25:51.222 3714-4527 NvUtils system_server I onActivityStopped AM packageName = androidx.media3.demo.main
2025-11-01 14:25:51.223 3714-4527 AudioSystem-JNI system_server E Command failed for android_media_AudioSystem_setParameters: -22
2025-11-01 14:25:51.224 3714-4527 AudioSystem-JNI system_server E Command failed for android_media_AudioSystem_setParameters: -22
2025-11-01 14:25:51.225 3714-4527 android_os_HwBinder system_server I HwBinder: Starting thread pool for getting: [email protected]::INvDisplay/default
2025-11-01 14:25:51.490 15108-15108 EventLogger androidx.media3.demo.main D loading [eventTime=0.79, mediaPos=0.31, window=0, period=0, false]
2025-11-01 14:25:51.494 3390-3521 NvOsDebugPrintf [email protected] D NvRmStreamFree: WARN: pStream is NULL
2025-11-01 14:25:53.455 3893-4363 nv_bt_stats com.android.bluetooth I btif_nv_stats_update_host_stats() addr: ff:ff:ff:ff:ff:ff , json string: cmd_timeout_rsp_after_to
2025-11-01 14:25:53.603 3714-4491 TaskPersister system_server E File error accessing recents directory (directory doesn't exist?).
2025-11-01 14:25:54.080 3714-10446 system_server system_server I oneway function results will be dropped but finished with status OK and parcel size 4
2025-11-01 14:25:54.081 3714-4527 system_server system_server I oneway function results will be dropped but finished with status OK and parcel size 4
2025-11-01 14:25:54.855 3579-15343 NvOsDebugPrintf media.codec D NVMEDIA: FrameRate(for last 120 frames) = 30.000030, AvgFrameRate = 30.000030
2025-11-01 14:25:55.947 3714-3790 WindowManager system_server D handleComboKeys keyCode: 85, keyAction: 0
2025-11-01 14:25:55.969 15108-15108 EventLogger androidx.media3.demo.main D playWhenReady [eventTime=5.27, mediaPos=4.79, window=0, period=0, false, USER_REQUEST]
2025-11-01 14:25:55.970 4062-4062 MainFragment com.android.tv.settings W playback is not active, set prefers disabled
2025-11-01 14:25:56.004 15108-15108 EventLogger androidx.media3.demo.main D isPlaying [eventTime=5.31, mediaPos=4.79, window=0, period=0, false]
2025-11-01 14:25:56.032 4386-4386 GoogleInpu...hodService com...gle.android.inputmethod.latin I GoogleInputMethodService.onFinishInput():2084
2025-11-01 14:25:56.033 4386-4386 Module com...gle.android.inputmethod.latin I DeviceLockedStatusModuleProvider$Module.updateDeviceLockedStatus():101 repeatCheckTimes = 0, locked = false
2025-11-01 14:25:56.033 4386-4386 GoogleInpu...hodService com...gle.android.inputmethod.latin I GoogleInputMethodService.onStartInput():1345 onStartInput(EditorInfo{EditorInfo{packageName=androidx.media3.demo.main, inputType=0, inputTypeString=NULL, enableLearning=false, autoCorrection=false, autoComplete=false, imeOptions=0, privateImeOptions=null, actionName=UNSPECIFIED, actionLabel=null, initialSelStart=-1, initialSelEnd=-1, initialCapsMode=0, label=null, fieldId=2131361989, fieldName=null, extras=null, hintText=null, hintLocales=[]}}, false)
2025-11-01 14:25:56.034 4386-4386 Module com...gle.android.inputmethod.latin I DeviceLockedStatusModuleProvider$Module.updateDeviceLockedStatus():101 repeatCheckTimes = 1, locked = false
2025-11-01 14:25:56.053 3714-4527 system_server system_server I oneway function results will be dropped but finished with status OK and parcel size 4
2025-11-01 14:25:56.078 3714-3790 WindowManager system_server D handleComboKeys keyCode: 85, keyAction: 1
2025-11-01 14:25:56.497 3390-3521 hdcp up [email protected] V tsec_version: 1
Thanks for updating the log! We did run in to problems with WOULD_BLOCK in the past (internal ref: 230097284).
This is a problem with the OMX decoders shipped on older Android devices and it's likely the OEM didn't configure the hardware in the correct way.
We don't have a "fix" for the issue, but there's something to try to alleviate the issue: force the method to return 1 always:
https://github.com/androidx/media/blob/bfe5930f7f29c6492d60e3d01a90abd3c138b615/libraries/common/src/main/java/androidx/media3/common/util/Util.java#L3619
Please try it out and check if it fixed the issue.
@claincly I tried your patch. I indeed don't have the WOULD_BLOCK error anymore, but I still see a black screen.
This is the log I get with package:mine:
---------------------------- PROCESS STARTED (20785) for package androidx.media3.demo.main ----------------------------
2025-11-04 09:11:27.038 20785-20785 edia3.demo.mai androidx.media3.demo.main W ClassLoaderContext classpath size mismatch. expected=0, found=1 (PCL[] | PCL[/data/data/androidx.media3.demo.main/code_cache/.overlay/base.apk/classes13.dex*534262585])
2025-11-04 09:11:27.040 20785-20785 edia3.demo.mai androidx.media3.demo.main W Found duplicate classes, falling back to extracting from APK : /data/app/~~sQPK4IiDQqkslbO391Q0vQ==/androidx.media3.demo.main-BosUy2j9Kx1tLJ9qKP6a4A==/base.apk
2025-11-04 09:11:27.040 20785-20785 edia3.demo.mai androidx.media3.demo.main W NOTE: This wastes RAM and hurts startup performance.
2025-11-04 09:11:27.040 20785-20785 edia3.demo.mai androidx.media3.demo.main W Found duplicated class when checking oat files: 'Landroidx/media3/common/util/Assertions;' in /data/app/~~sQPK4IiDQqkslbO391Q0vQ==/androidx.media3.demo.main-BosUy2j9Kx1tLJ9qKP6a4A==/base.apk!classes13.dex and /data/data/androidx.media3.demo.main/code_cache/.overlay/base.apk/classes13.dex
2025-11-04 09:11:27.728 20785-20785 NetworkSecurityConfig androidx.media3.demo.main D No Network Security Config specified, using platform default
2025-11-04 09:11:27.729 20785-20785 NetworkSecurityConfig androidx.media3.demo.main D No Network Security Config specified, using platform default
2025-11-04 09:11:27.825 20785-20785 AppCompatDelegate androidx.media3.demo.main D Checking for metadata for AppLocalesMetadataHolderService : Service not found
2025-11-04 09:11:27.927 20785-20785 edia3.demo.mai androidx.media3.demo.main W Accessing hidden method Landroid/view/View;->computeFitSystemWindows(Landroid/graphics/Rect;Landroid/graphics/Rect;)Z (greylist, reflection, allowed)
2025-11-04 09:11:27.928 20785-20785 edia3.demo.mai androidx.media3.demo.main W Accessing hidden method Landroid/view/ViewGroup;->makeOptionalFitsSystemWindows()V (greylist, reflection, allowed)
2025-11-04 09:11:27.933 20785-20785 android_os_HwBinder androidx.media3.demo.main I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-04 09:11:28.020 20785-20785 edia3.demo.mai androidx.media3.demo.main W ClassLoaderContext classpath size mismatch. expected=8, found=0 (DLC[];PCL[base.apk*409890633:base.apk!classes2.dex*1209932438:base.apk!classes3.dex*2746078529:base.apk!classes4.dex*24094834:base.apk!classes5.dex*3484699982:base.apk!classes6.dex*3695327762:base.apk!classes7.dex*3257680763:base.apk!classes8.dex*4243784554]{PCL[/system/framework/org.apache.http.legacy.jar*181645345]#PCL[/system/framework/com.android.location.provider.jar*2383074192]} | DLC[];PCL[])
2025-11-04 09:11:28.023 20785-20785 DynamiteModule androidx.media3.demo.main I Considering local module com.google.android.gms.cronet_dynamite:0 and remote module com.google.android.gms.cronet_dynamite:4011
2025-11-04 09:11:28.023 20785-20785 DynamiteModule androidx.media3.demo.main I Selected remote version of com.google.android.gms.cronet_dynamite, version >= 4011
2025-11-04 09:11:28.023 20785-20785 DynamiteModule androidx.media3.demo.main V Dynamite loader version >= 2, using loadModule2NoCrashUtils
2025-11-04 09:11:28.036 20785-20785 System androidx.media3.demo.main W ClassLoader referenced unknown path:
2025-11-04 09:11:28.050 20785-20785 edia3.demo.mai androidx.media3.demo.main W ClassLoaderContext classpath size mismatch. expected=8, found=21 (DLC[];PCL[base.apk*409890633:base.apk!classes2.dex*1209932438:base.apk!classes3.dex*2746078529:base.apk!classes4.dex*24094834:base.apk!classes5.dex*3484699982:base.apk!classes6.dex*3695327762:base.apk!classes7.dex*3257680763:base.apk!classes8.dex*4243784554]{PCL[/system/framework/org.apache.http.legacy.jar*181645345]#PCL[/system/framework/com.android.location.provider.jar*2383074192]} | DLC[];PCL[/data/data/androidx.media3.demo.main/code_cache/.overlay/base.apk/classes13.dex*534262585:/data/app/~~sQPK4IiDQqkslbO391Q0vQ==/androidx.media3.demo.main-BosUy2j9Kx1tLJ9qKP6a4A==/base.apk*1907916503:/data/app/~~sQPK4IiDQqkslbO391Q0vQ==/androidx.media3.demo.main-BosUy2j9Kx1tLJ9qKP6a4A==/base.apk!classes2.dex*3451262090:/data/app/~~sQPK4IiDQqkslbO391Q0vQ==/androidx.media3.demo.main-BosUy2j9Kx1tLJ9qKP6a4A==/base.apk!classes3.dex*2788754055:/data/app/~~sQPK4IiDQqkslbO391Q0vQ==/androidx.media3.demo.main-BosUy2j9Kx1tLJ9qKP6a4A==/base.apk!classes4.dex*2766395343:/data/app/~~sQPK4IiDQqkslbO391Q0vQ==/androidx.media3.demo.main-BosUy2j9Kx1tLJ9qKP6a4A==/base.apk!classes5.dex*1502727098:/data/app/~~sQPK4IiDQqkslbO391Q0vQ==/androidx.media3.demo.main-BosUy2j9Kx1tLJ9qKP6a4A==/base.apk!classes6.dex*3507779077:/data/app/~~sQPK4IiDQqkslbO391Q0vQ==/androidx.media3.demo.main-BosUy2j9Kx1tLJ9qKP6a4A==/base.apk!classes7.dex*2704630952:/data/app/~~sQPK4IiDQqkslbO391Q0vQ==/androidx.media3.demo.main-BosUy2j9Kx1tLJ9qKP6a4A==/base.apk!classes8.dex*3740988137:/data/app/~~sQPK4IiDQqkslbO391Q0vQ==/androidx.media3.demo.main-BosUy2j9Kx1tLJ9qKP6a4A==/base.apk!classes9.dex*3064202947:/data/app/~~sQPK4IiDQqkslbO391Q0vQ==/androidx.media3.demo.main-BosUy2j9Kx1tLJ9qKP6a4A==/base.apk!classes10.dex*2471404001:/data/app/~~sQPK4IiDQqkslbO391Q0vQ==/androidx.media3.demo.main-BosUy2j9Kx1tLJ9qKP6a4A==/base.apk!classes11.dex*237040233:/data/app/~~sQPK4IiDQqkslbO391Q0vQ==/androidx.media3.demo.main-BosUy2j9Kx1tLJ9qKP6a4A==/base.apk!classes12.dex*2177093479:/data/app/~~sQPK4IiDQqkslbO391Q0vQ==/androidx.media3.demo.main-BosUy2j9Kx1tLJ9qKP6a4A==/base.apk!classes13.dex*644409300:/data/app/~~sQPK4IiDQqkslbO391Q0vQ==/androidx.media3.demo.main-BosUy2j9Kx1tLJ9qKP6a4A==/base.apk!classes14.dex*94693642:/data/app/~~sQPK4IiDQqkslbO391Q0vQ==/androidx.media3.demo.main-BosUy2j9Kx1tLJ9qKP6a4A==/base.apk!classes15.dex*4152919932:/data/app/~~sQPK4IiDQqkslbO391Q0vQ==/androidx.media3.demo.main-BosUy2j9Kx1tLJ9qKP6a4A==/base.apk!classes16.dex*4059427018:/data/app/~~sQPK4IiDQqkslbO391Q0vQ==/androidx.media3.demo.main-BosUy2j9Kx1tLJ9qKP6a4A==/base.apk!classes17.dex*2964442362:/data/app/~~sQPK4IiDQqkslbO391Q0vQ==/androidx.media3.demo.main-BosUy2j9Kx1tLJ9qKP6a4A==/base.apk!classes18.dex*2884874195:/data/app/~~sQPK4IiDQqkslbO391Q0vQ==/androidx.media3.demo.main-BosUy2j9Kx1tLJ9qKP6a4A==/base.apk!classes19.dex*2362195841:/data/app/~~sQPK4IiDQqkslbO391Q0vQ==/androidx.media3.demo.main-BosUy2j9Kx1tLJ9qKP6a4A==/base.apk!classes20.dex*1665986961])
2025-11-04 09:11:28.051 20785-20785 edia3.demo.mai androidx.media3.demo.main W Found duplicate classes, falling back to extracting from APK : /data/app/~~1bhKhU7SDW9XnpdttdWXXw==/com.google.android.gms-YEsC-j9W6i52w8EX378i8Q==/split_CronetDynamite_installtime.apk
2025-11-04 09:11:28.051 20785-20785 edia3.demo.mai androidx.media3.demo.main W NOTE: This wastes RAM and hurts startup performance.
2025-11-04 09:11:28.051 20785-20785 edia3.demo.mai androidx.media3.demo.main W Found duplicated class when checking oat files: 'Landroid/support/v4/graphics/drawable/IconCompatParcelizer;' in /data/app/~~1bhKhU7SDW9XnpdttdWXXw==/com.google.android.gms-YEsC-j9W6i52w8EX378i8Q==/split_CronetDynamite_installtime.apk and /data/app/~~sQPK4IiDQqkslbO391Q0vQ==/androidx.media3.demo.main-BosUy2j9Kx1tLJ9qKP6a4A==/base.apk
2025-11-04 09:11:28.121 20785-20785 linker androidx.media3.demo.main W Warning: "/data/app/~~1bhKhU7SDW9XnpdttdWXXw==/com.google.android.gms-YEsC-j9W6i52w8EX378i8Q==/split_CronetDynamite_installtime.apk!/lib/arm64-v8a/libcronet.142.0.7432.0.so" unused DT entry: unknown processor-specific (type 0x70000001 arg 0x0) (ignoring)
2025-11-04 09:11:28.149 20785-20815 HttpFlagsLoader androidx.media3.demo.main I HTTP flags log line (Impl): Using live production flag values from Google
2025-11-04 09:11:28.153 20785-20785 cn_CronetLibraryLoader androidx.media3.demo.main I Cronet version: 142.0.7432.0, arch: aarch64
2025-11-04 09:11:28.154 20785-20815 TetheringManager androidx.media3.demo.main I registerTetheringEventCallback:androidx.media3.demo.main
2025-11-04 09:11:28.159 20785-20785 CronetUtil androidx.media3.demo.main D CronetEngine built using Google-Play-Services-Cronet-Provider
2025-11-04 09:11:28.232 20785-20785 android_os_HwBinder androidx.media3.demo.main I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-04 09:11:28.242 20785-20785 android_os_HwBinder androidx.media3.demo.main I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-04 09:11:28.294 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_DEFAULT_DEVICE_INDEX"
2025-11-04 09:11:28.294 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_UZfVtuHi"
2025-11-04 09:11:28.294 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_DUMMY"
2025-11-04 09:11:28.294 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_V5tZ4s00"
2025-11-04 09:11:28.294 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_CHANNEL_INTERLEAVE"
2025-11-04 09:11:28.294 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_vGEEY2dW"
2025-11-04 09:11:28.294 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_CHANNEL_PRIORITY"
2025-11-04 09:11:28.294 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_zugIRozd"
2025-11-04 09:11:28.294 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_CHANNEL_TIMESLICE"
2025-11-04 09:11:28.295 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_maIaPSXj"
2025-11-04 09:11:28.295 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_DISABLE_COMPRESSION"
2025-11-04 09:11:28.295 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_LiY1nc6l"
2025-11-04 09:11:28.295 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_NOUVEAU_DISABLE"
2025-11-04 09:11:28.295 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_T29RkpyE"
2025-11-04 09:11:28.295 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_NVGPU_DISABLE"
2025-11-04 09:11:28.295 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_EEPyR4lp"
2025-11-04 09:11:28.295 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_NVGPU_DO_NFA_PARTIAL_MAP"
2025-11-04 09:11:28.295 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_36lvAjow"
2025-11-04 09:11:28.295 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_NVGPU_ECC_OVERRIDES"
2025-11-04 09:11:28.295 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_oSawIPsF"
2025-11-04 09:11:28.295 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_NVGPU_FORCE_SCG"
2025-11-04 09:11:28.295 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_6aQElJ8Y"
2025-11-04 09:11:28.295 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_NVGPU_FORCE_SM_DIVERSITY"
2025-11-04 09:11:28.295 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_UALTpoyI"
2025-11-04 09:11:28.295 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_NVGPU_NO_ALWAYS_IO_COHERENCY"
2025-11-04 09:11:28.295 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_kJo45YxE"
2025-11-04 09:11:28.295 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_NVGPU_NO_ASYNC_CE"
2025-11-04 09:11:28.295 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_gmxia7dI"
2025-11-04 09:11:28.295 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_NVGPU_NO_BIG_PAGES"
2025-11-04 09:11:28.296 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_vl2Vovpk"
2025-11-04 09:11:28.296 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_NVGPU_NO_CHANNEL_SETPRIORITY_EMUL"
2025-11-04 09:11:28.296 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_CqEssDmc"
2025-11-04 09:11:28.296 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_NVGPU_NO_IO_COHERENCY"
2025-11-04 09:11:28.296 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_x90Ja8kG"
2025-11-04 09:11:28.296 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_NVGPU_NO_IOCTL_MUTEX"
2025-11-04 09:11:28.296 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_CI4Wetrn"
2025-11-04 09:11:28.296 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_NVGPU_NO_L3"
2025-11-04 09:11:28.296 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_OmETcz6b"
2025-11-04 09:11:28.296 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_NVGPU_NO_MAP_BUFFER_BATCH"
2025-11-04 09:11:28.296 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_8sKrPMLm"
2025-11-04 09:11:28.296 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_NVGPU_NO_PROBE_DGPU"
2025-11-04 09:11:28.296 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_gT1cB8Jb"
2025-11-04 09:11:28.296 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_NVGPU_NO_PROBE_IGPU"
2025-11-04 09:11:28.296 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_dm7XlLKA"
2025-11-04 09:11:28.296 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_NVGPU_NO_SPARSE"
2025-11-04 09:11:28.296 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_XzoEBCw9"
2025-11-04 09:11:28.296 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_NVGPU_NO_SYNCFD"
2025-11-04 09:11:28.296 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_EHaGX5Xe"
2025-11-04 09:11:28.296 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_NVGPU_NO_SYNCPOINTS"
2025-11-04 09:11:28.296 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_59ct6EdS"
2025-11-04 09:11:28.297 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_NVGPU_NO_TTU"
2025-11-04 09:11:28.297 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_915h8OIS"
2025-11-04 09:11:28.297 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_NVGPU_NO_USER_MANAGED_SYNCPOINT"
2025-11-04 09:11:28.297 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_XylwwSvx"
2025-11-04 09:11:28.297 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_NVGPU_NO_VPR"
2025-11-04 09:11:28.297 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_Yb92x8rk"
2025-11-04 09:11:28.297 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_NVGPU_NO_ZBC"
2025-11-04 09:11:28.297 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_oMdTiRBY"
2025-11-04 09:11:28.297 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_NVGPU_NO_ZCULL"
2025-11-04 09:11:28.297 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_6tRS9rwi"
2025-11-04 09:11:28.297 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_NVGPU_USE_SYSMEM_FOR_VIDMEM"
2025-11-04 09:11:28.297 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_TkDtPGzC"
2025-11-04 09:11:28.300 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_NVGPU_DISABLE_WATCHDOG"
2025-11-04 09:11:28.300 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_EQ5Qmveh"
2025-11-04 09:11:28.301 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_PREVENT_USE"
2025-11-04 09:11:28.301 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRM_GPU_yrGhr5G6"
2025-11-04 09:11:28.301 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRMT_EMULATE_IO_COHERENCY"
2025-11-04 09:11:28.301 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRMT_fY86mLY0"
2025-11-04 09:11:28.301 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRMT_MEMORY_TYPE_SANITIZER"
2025-11-04 09:11:28.301 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRMT_kzdhcuAG"
2025-11-04 09:11:28.302 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRMT_FORCE_L3_ALLOC"
2025-11-04 09:11:28.302 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRMT_ZV2Frkax"
2025-11-04 09:11:28.302 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRMT_FORCE_SNOOPING"
2025-11-04 09:11:28.302 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRMT_VzUDgaZ2"
2025-11-04 09:11:28.302 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRMT_FORCE_SYNC_KICKOFFS"
2025-11-04 09:11:28.302 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRMT_kdisTKXJ"
2025-11-04 09:11:28.302 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRMT_EMULATE_BIG_PAGES"
2025-11-04 09:11:28.302 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRMT_KcOcZFPL"
2025-11-04 09:11:28.302 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRMT_FORCE_TSG"
2025-11-04 09:11:28.303 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRMT_64gDxjIe"
2025-11-04 09:11:28.303 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRMT_DISABLE_VASPACE"
2025-11-04 09:11:28.303 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRMT_JMAa8wgC"
2025-11-04 09:11:28.304 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVWSI_DUMP"
2025-11-04 09:11:28.304 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVWSI_FILL"
2025-11-04 09:11:28.308 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.nvblit.twod"
2025-11-04 09:11:28.308 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.nvblit.vic"
2025-11-04 09:11:28.308 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.nvblit.dump"
2025-11-04 09:11:28.313 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_EGL_FORCE_MSAA"
2025-11-04 09:11:28.313 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_EGL_FORCE_DEPTH"
2025-11-04 09:11:28.345 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_ASYNC"
2025-11-04 09:11:28.345 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_LOGFPS"
2025-11-04 09:11:28.345 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_PROCESS_NAME"
2025-11-04 09:11:28.345 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_FPSDYNAMIC"
2025-11-04 09:11:28.346 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRMT_DISABLE_COMPRESSION"
2025-11-04 09:11:28.346 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRMT_h6spQ4yh"
2025-11-04 09:11:28.346 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRMT_FORCE_ALIGN_128K"
2025-11-04 09:11:28.346 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRMT_UnLSxhuN"
2025-11-04 09:11:28.375 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRMT_FORCE_SCG"
2025-11-04 09:11:28.375 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRMT_WVRg2RZb"
2025-11-04 09:11:28.376 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRMT_EXPECTED_GPFIFO_ENTRIES_PER_SUBMIT"
2025-11-04 09:11:28.376 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NVRMT_0ktEXYzd"
2025-11-04 09:11:28.405 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_ASYNC"
2025-11-04 09:11:28.405 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_LOGFPS"
2025-11-04 09:11:28.405 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_PROCESS_NAME"
2025-11-04 09:11:28.405 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_FPSDYNAMIC"
2025-11-04 09:11:28.410 20785-20808 Gralloc4 androidx.media3.demo.main I mapper 4.x is not supported
2025-11-04 09:11:28.410 20785-20808 Gralloc3 androidx.media3.demo.main W mapper 3.x is not supported
2025-11-04 09:11:28.414 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_DECOMPRESSION"
2025-11-04 09:11:28.486 20785-20785 android_os_HwBinder androidx.media3.demo.main I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-04 09:11:28.952 20785-20801 System androidx.media3.demo.main W A resource failed to call close.
2025-11-04 09:11:45.204 20785-20785 android_os_HwBinder androidx.media3.demo.main I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-04 09:11:45.463 20785-20785 ExoPlayerImpl androidx.media3.demo.main I Init 1e84f69 [AndroidXMedia3/1.9.0-alpha01] [mdarcy, SHIELD Android TV, NVIDIA, 30]
2025-11-04 09:11:45.571 20785-20785 EventLogger androidx.media3.demo.main D playWhenReady [eventTime=0.00, mediaPos=0.00, window=0, true, USER_REQUEST]
2025-11-04 09:11:45.576 20785-20785 EventLogger androidx.media3.demo.main D surfaceSize [eventTime=0.01, mediaPos=0.00, window=0, w=0, h=0]
2025-11-04 09:11:45.601 20785-20785 EventLogger androidx.media3.demo.main D timeline [eventTime=0.03, mediaPos=0.00, window=0, periodCount=1, windowCount=1, reason=PLAYLIST_CHANGED
2025-11-04 09:11:45.601 20785-20785 EventLogger androidx.media3.demo.main D period [?]
2025-11-04 09:11:45.601 20785-20785 EventLogger androidx.media3.demo.main D window [?, seekable=false, dynamic=true]
2025-11-04 09:11:45.601 20785-20785 EventLogger androidx.media3.demo.main D ]
2025-11-04 09:11:45.602 20785-20785 EventLogger androidx.media3.demo.main D mediaItem [eventTime=0.03, mediaPos=0.00, window=0, reason=PLAYLIST_CHANGED]
2025-11-04 09:11:45.604 20785-20785 EventLogger androidx.media3.demo.main D state [eventTime=0.04, mediaPos=0.00, window=0, BUFFERING]
2025-11-04 09:11:45.619 20785-20785 android_os_HwBinder androidx.media3.demo.main I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-04 09:11:45.621 20785-20785 android_os_HwBinder androidx.media3.demo.main I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-04 09:11:45.635 20785-20785 Choreographer androidx.media3.demo.main I Skipped 30 frames! The application may be doing too much work on its main thread.
2025-11-04 09:11:45.661 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_ASYNC"
2025-11-04 09:11:45.661 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_LOGFPS"
2025-11-04 09:11:45.662 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_PROCESS_NAME"
2025-11-04 09:11:45.662 20785-20808 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_FPSDYNAMIC"
2025-11-04 09:11:45.664 20785-20785 EventLogger androidx.media3.demo.main D surfaceSize [eventTime=0.09, mediaPos=0.00, window=0, w=1920, h=1080]
2025-11-04 09:11:45.675 20785-20785 EventLogger androidx.media3.demo.main D audioSessionId [eventTime=0.11, mediaPos=0.00, window=0, 929]
2025-11-04 09:11:45.680 20785-20785 EventLogger androidx.media3.demo.main D loading [eventTime=0.11, mediaPos=0.00, window=0, period=0, true]
2025-11-04 09:11:45.682 20785-20785 EventLogger androidx.media3.demo.main D timeline [eventTime=0.11, mediaPos=0.00, window=0, period=0, periodCount=1, windowCount=1, reason=SOURCE_UPDATE
2025-11-04 09:11:45.682 20785-20785 EventLogger androidx.media3.demo.main D period [?]
2025-11-04 09:11:45.683 20785-20785 EventLogger androidx.media3.demo.main D window [?, seekable=false, dynamic=false]
2025-11-04 09:11:45.683 20785-20785 EventLogger androidx.media3.demo.main D ]
2025-11-04 09:11:46.029 20785-20850 MetadataUtil androidx.media3.demo.main D Skipped unknown metadata entry: gsst
2025-11-04 09:11:46.029 20785-20850 MetadataUtil androidx.media3.demo.main D Skipped unknown metadata entry: gstd
2025-11-04 09:11:46.029 20785-20850 MetadataUtil androidx.media3.demo.main D Skipped unknown metadata entry: gssd
2025-11-04 09:11:46.029 20785-20850 MetadataUtil androidx.media3.demo.main D Skipped unknown metadata entry: gspu
2025-11-04 09:11:46.029 20785-20850 MetadataUtil androidx.media3.demo.main D Skipped unknown metadata entry: gspm
2025-11-04 09:11:46.062 20785-20785 EventLogger androidx.media3.demo.main D timeline [eventTime=0.49, mediaPos=0.00, window=0, period=0, periodCount=1, windowCount=1, reason=SOURCE_UPDATE
2025-11-04 09:11:46.062 20785-20785 EventLogger androidx.media3.demo.main D period [25.00]
2025-11-04 09:11:46.063 20785-20785 EventLogger androidx.media3.demo.main D window [25.00, seekable=true, dynamic=false]
2025-11-04 09:11:46.063 20785-20785 EventLogger androidx.media3.demo.main D ]
2025-11-04 09:11:46.098 20785-20849 AudioCapabilities androidx.media3.demo.main W Unsupported mime audio/mpeg-L2
2025-11-04 09:11:46.099 20785-20849 AudioCapabilities androidx.media3.demo.main W Unsupported mime audio/x-ms-wma
2025-11-04 09:11:46.100 20785-20849 VideoCapabilities androidx.media3.demo.main W Unsupported mime image/vnd.android.heic
2025-11-04 09:11:46.101 20785-20808 Surface androidx.media3.demo.main E freeAllBuffers: 1 buffers were freed while being dequeued!
2025-11-04 09:11:46.107 20785-20849 VideoCapabilities androidx.media3.demo.main W Unsupported mime video/dolby-vision
2025-11-04 09:11:46.108 20785-20849 VideoCapabilities androidx.media3.demo.main W Unsupported mime video/dolby-vision
2025-11-04 09:11:46.113 20785-20849 VideoCapabilities androidx.media3.demo.main W Unsupported mime video/x-motion-jpeg
2025-11-04 09:11:46.117 20785-20849 VideoCapabilities androidx.media3.demo.main W Unsupported mime video/wvc1
2025-11-04 09:11:46.118 20785-20849 VideoCapabilities androidx.media3.demo.main W Unsupported mime video/x-ms-wmv
2025-11-04 09:11:46.133 20785-20849 VideoCapabilities androidx.media3.demo.main I Unsupported profile 4 for video/mp4v-es
2025-11-04 09:11:46.160 20785-20785 EventLogger androidx.media3.demo.main D videoEnabled [eventTime=0.59, mediaPos=0.00, window=0, period=0]
2025-11-04 09:11:46.173 20785-20785 EventLogger androidx.media3.demo.main D audioEnabled [eventTime=0.60, mediaPos=0.00, window=0, period=0]
2025-11-04 09:11:46.178 20785-20785 EventLogger androidx.media3.demo.main D tracks [eventTime=0.61, mediaPos=0.00, window=0, period=0
2025-11-04 09:11:46.179 20785-20785 EventLogger androidx.media3.demo.main D group [ id=0:1
2025-11-04 09:11:46.181 20785-20785 EventLogger androidx.media3.demo.main D [X] Track:0, id=0:2, mimeType=video/avc, container=video/mp4, bitrate=494792, codecs=avc1.42C01E, res=480x360, color=NA/NA/NA/8/8, fps=30.0, language=und, supported=YES
2025-11-04 09:11:46.181 20785-20785 EventLogger androidx.media3.demo.main D ]
2025-11-04 09:11:46.181 20785-20785 EventLogger androidx.media3.demo.main D group [ id=0:0
2025-11-04 09:11:46.181 20785-20785 EventLogger androidx.media3.demo.main D [X] Track:0, id=0:1, mimeType=audio/mp4a-latm, container=video/mp4, bitrate=102352, codecs=mp4a.40.2, channels=2, sample_rate=44100, language=und, supported=YES
2025-11-04 09:11:46.181 20785-20785 EventLogger androidx.media3.demo.main D ]
2025-11-04 09:11:46.181 20785-20785 EventLogger androidx.media3.demo.main D group [ id=1:0
2025-11-04 09:11:46.181 20785-20785 EventLogger androidx.media3.demo.main D [X] Track:0, id=1:, mimeType=application/x-media3-cues, codecs=text/vtt, language=en, selectionFlags=[default], supported=YES
2025-11-04 09:11:46.181 20785-20785 EventLogger androidx.media3.demo.main D ]
2025-11-04 09:11:46.181 20785-20785 EventLogger androidx.media3.demo.main D Metadata [
2025-11-04 09:11:46.182 20785-20785 EventLogger androidx.media3.demo.main D Mp4Timestamp: creation time=3339325113, modification time=3339325113, timescale=600
2025-11-04 09:11:46.182 20785-20785 EventLogger androidx.media3.demo.main D ThumbnailMetadata: presentationTimeUs=6800000
2025-11-04 09:11:46.182 20785-20785 EventLogger androidx.media3.demo.main D ]
2025-11-04 09:11:46.182 20785-20785 EventLogger androidx.media3.demo.main D ]
2025-11-04 09:11:46.192 20785-20785 EventLogger androidx.media3.demo.main D downstreamFormat [eventTime=0.62, mediaPos=0.00, window=0, period=0, id=2, mimeType=video/avc, container=video/mp4, bitrate=494792, codecs=avc1.42C01E, res=480x360, color=NA/NA/NA/8/8, fps=30.0, language=und]
2025-11-04 09:11:46.193 20785-20859 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_ASYNC"
2025-11-04 09:11:46.193 20785-20859 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_LOGFPS"
2025-11-04 09:11:46.193 20785-20859 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_PROCESS_NAME"
2025-11-04 09:11:46.193 20785-20859 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_FPSDYNAMIC"
2025-11-04 09:11:46.225 20785-20849 android_os_HwBinder androidx.media3.demo.main I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-04 09:11:46.228 20785-20862 OMXClient androidx.media3.demo.main I IOmx service obtained
2025-11-04 09:11:46.233 20785-20849 android_os_HwBinder androidx.media3.demo.main I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-04 09:11:46.236 20785-20849 chatty androidx.media3.demo.main I uid=10133(androidx.media3.demo.main) ExoPlayer:Playb identical 1 line
2025-11-04 09:11:46.238 20785-20849 android_os_HwBinder androidx.media3.demo.main I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-04 09:11:46.239 20785-20849 MediaCodec androidx.media3.demo.main I mContentRefreshRate = 30.0
2025-11-04 09:11:46.240 20785-20861 MediaCodec androidx.media3.demo.main D flushMediametrics
2025-11-04 09:11:46.240 20785-20861 SurfaceUtils androidx.media3.demo.main D connecting to surface 0x287c3c4090, reason connectToSurface
2025-11-04 09:11:46.240 20785-20861 MediaCodec androidx.media3.demo.main I [OMX.Nvidia.h264.decode] setting surface generation to 21283841
2025-11-04 09:11:46.240 20785-20861 SurfaceUtils androidx.media3.demo.main D disconnecting from surface 0x287c3c4090, reason connectToSurface(reconnect)
2025-11-04 09:11:46.240 20785-20861 SurfaceUtils androidx.media3.demo.main D connecting to surface 0x287c3c4090, reason connectToSurface(reconnect)
2025-11-04 09:11:46.240 20785-20861 SurfaceUtils androidx.media3.demo.main D legacy buffer drop disabled: target sdk (34)
2025-11-04 09:11:46.246 20785-20862 ACodec androidx.media3.demo.main I Enable timestamp filtering for Video Decoder
2025-11-04 09:11:46.247 20785-20862 ACodec androidx.media3.demo.main I Get OMX_IndexConfigScale: 0x10000/0x10000
2025-11-04 09:11:46.247 20785-20862 ACodec androidx.media3.demo.main I Scaled Width is 480, height is 360
2025-11-04 09:11:46.254 20785-20862 SurfaceUtils androidx.media3.demo.main D set up nativeWindow 0x287c3c4090 for 480x360, color 0x106, rotation 0, usage 0x2900
2025-11-04 09:11:46.256 20785-20862 Gralloc4 androidx.media3.demo.main W allocator 3.x is not supported
2025-11-04 09:11:46.256 20785-20862 Gralloc3 androidx.media3.demo.main W allocator 3.x is not supported
2025-11-04 09:11:46.259 20785-20785 EventLogger androidx.media3.demo.main D videoDecoderInitialized [eventTime=0.69, mediaPos=0.00, window=0, period=0, OMX.Nvidia.h264.decode]
2025-11-04 09:11:46.261 20785-20785 EventLogger androidx.media3.demo.main D videoInputFormat [eventTime=0.69, mediaPos=0.00, window=0, period=0, id=2, mimeType=video/avc, container=video/mp4, bitrate=494792, codecs=avc1.42C01E, res=480x360, color=NA/NA/NA/8/8, fps=30.0, language=und]
2025-11-04 09:11:46.265 20785-20862 ACodec androidx.media3.demo.main I Get OMX_IndexConfigScale: 0x10000/0x10000
2025-11-04 09:11:46.265 20785-20862 ACodec androidx.media3.demo.main I Scaled Width is 480, height is 360
2025-11-04 09:11:46.269 20785-20862 SurfaceUtils androidx.media3.demo.main D set up nativeWindow 0x287c3c4090 for 480x360, color 0x106, rotation 0, usage 0x2900
2025-11-04 09:11:46.271 20785-20785 EventLogger androidx.media3.demo.main D downstreamFormat [eventTime=0.70, mediaPos=0.00, window=0, period=0, id=1, mimeType=audio/mp4a-latm, container=video/mp4, bitrate=102352, codecs=mp4a.40.2, channels=2, sample_rate=44100, language=und]
2025-11-04 09:11:46.276 20785-20849 android_os_HwBinder androidx.media3.demo.main I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-04 09:11:46.276 20785-20862 ACodec androidx.media3.demo.main I Get OMX_IndexConfigScale: 0x10000/0x10000
2025-11-04 09:11:46.276 20785-20862 ACodec androidx.media3.demo.main I Scaled Width is 480, height is 360
2025-11-04 09:11:46.279 20785-20868 OMXClient androidx.media3.demo.main I IOmx service obtained
2025-11-04 09:11:46.284 20785-20849 android_os_HwBinder androidx.media3.demo.main I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-04 09:11:46.287 20785-20849 chatty androidx.media3.demo.main I uid=10133(androidx.media3.demo.main) ExoPlayer:Playb identical 1 line
2025-11-04 09:11:46.289 20785-20849 android_os_HwBinder androidx.media3.demo.main I HwBinder: Starting thread pool for getting: [email protected]::INvCplHalCommonService/default
2025-11-04 09:11:46.290 20785-20868 MediaCodec androidx.media3.demo.main D flushMediametrics
2025-11-04 09:11:46.292 20785-20868 ACodec androidx.media3.demo.main I codec does not support config priority (err -2147483648)
2025-11-04 09:11:46.299 20785-20785 EventLogger androidx.media3.demo.main D audioDecoderInitialized [eventTime=0.73, mediaPos=0.00, window=0, period=0, OMX.google.aac.decoder]
2025-11-04 09:11:46.300 20785-20785 EventLogger androidx.media3.demo.main D audioInputFormat [eventTime=0.73, mediaPos=0.00, window=0, period=0, id=1, mimeType=audio/mp4a-latm, container=video/mp4, bitrate=102352, codecs=mp4a.40.2, channels=2, sample_rate=44100, language=und]
2025-11-04 09:11:46.306 20785-20785 EventLogger androidx.media3.demo.main D downstreamFormat [eventTime=0.74, mediaPos=0.00, window=0, period=0, id=null, mimeType=application/x-media3-cues, codecs=text/vtt, language=en, selectionFlags=[default]]
2025-11-04 09:11:46.309 20785-20785 EventLogger androidx.media3.demo.main D rendererReady [eventTime=0.74, mediaPos=0.00, window=0, period=0, rendererIndex=2, text, true]
2025-11-04 09:11:46.335 20785-20849 edia3.demo.mai androidx.media3.demo.main W Accessing hidden method Landroid/media/AudioTrack;->getLatency()I (greylist, reflection, allowed)
2025-11-04 09:11:46.338 20785-20785 EventLogger androidx.media3.demo.main D audioTrackInit [eventTime=0.77, mediaPos=0.00, window=0, period=0, enc=pcm-16, channelConf=stereo, sampleRate=44100, bufferSize=44100]
2025-11-04 09:11:46.354 20785-20785 EventLogger androidx.media3.demo.main D rendererReady [eventTime=0.78, mediaPos=0.00, window=0, period=0, rendererIndex=1, audio, true]
2025-11-04 09:11:46.369 20785-20785 EventLogger androidx.media3.demo.main D rendererReady [eventTime=0.80, mediaPos=0.00, window=0, period=0, rendererIndex=0, video, true]
2025-11-04 09:11:46.377 20785-20859 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_ASYNC"
2025-11-04 09:11:46.377 20785-20859 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_LOGFPS"
2025-11-04 09:11:46.377 20785-20859 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_PROCESS_NAME"
2025-11-04 09:11:46.377 20785-20859 libc androidx.media3.demo.main E Access denied finding property "ro.vendor.sys.NV_FPSDYNAMIC"
2025-11-04 09:11:46.377 20785-20785 EventLogger androidx.media3.demo.main D state [eventTime=0.81, mediaPos=0.00, window=0, period=0, READY]
2025-11-04 09:11:46.379 20785-20849 AudioTrack androidx.media3.demo.main D getTimestamp_l(319): device stall time corrected using current time 258844917766048
2025-11-04 09:11:46.386 20785-20785 EventLogger androidx.media3.demo.main D isPlaying [eventTime=0.82, mediaPos=0.00, window=0, period=0, true]
2025-11-04 09:11:46.389 20785-20785 EventLogger androidx.media3.demo.main D renderedFirstFrame [eventTime=0.82, mediaPos=0.00, window=0, period=0, Surface(name=null)/@0x9668d6a]
2025-11-04 09:11:46.467 20785-20785 EventLogger androidx.media3.demo.main D loading [eventTime=0.90, mediaPos=0.05, window=0, period=0, false]
2025-11-04 09:11:46.471 20785-20785 EventLogger androidx.media3.demo.main D audioPositionAdvancing [eventTime=0.90, mediaPos=0.05, window=0, period=0, since 0.87]
Thanks for verifying - I don't want to jump to a conclusion that this is a device specific issue before I try it myself, but all evidences point to it. Are you able to reproduce this with other devices, like on a phone, or with TV emulator?
I am not able to reproduce it on any other devices. Would be appreciated if you could get a nvidia shield to diagnostic the issue.