[Android] Playing then pausing in the same frame does not pause video

Open Auroriax opened this issue 3 months ago • 1 comments
Unity version

2022.3.62f1
Unity editor platform

Windows
AVPro Video edition

Core
AVPro Video version

3.2.9
Device hardware

Pico G3
Which Android OS version are you using?

Unity Graphics API

Vulkan
Video API

media3/ExoPlayer
Texture format

BGRA
Audio output

Unity
Any other Media Player component configuration required to reproduce the issue.

None
Which output component(s) are you using?

Display uGUI
Any other component configuration required to reproduce the issue.

N/A
The issue

When a video receives a Play and Pause requests in the same frame while a video was paused at the start of the frame, the video playback state at the end of the frame is playing at the end of the frame.
Reproduction steps:
Start a video, and pause it. Wait at least one frame.
Play the video, then immediately pause the video, within the same frame. Expected: Video is now paused. (e.g. Windows playback exhibits this behavior.) Actual: Video is now playing, pause command is seemingly ignored.
Media information

Can be reproduced with Cones-2D-1080p60-H264
Logcat output

logcat
10-01 16:23:37.079  1601  4890 I AVProVideo: 📜 (UnityPlugin.cpp:211) UnityPluginLoad - AVPro Video version 3.2.8-core build 2507211624
10-01 16:23:37.079  1601  4890 I AVProVideo: 💬️ (UnityPlugin.cpp:145) OnUnityGfxDeviceEventInitialize - Using null renderer
10-01 16:23:37.079  1601  4890 I Unity   : Company Name: DefaultCompany
10-01 16:23:37.079  1601  4890 I Unity   : Product Name: avpro-repro
10-01 16:23:37.080  1337  3105 I SystemExt: notifyResizeVirtualDisplay displayId : 5, reqOrientation : 0
10-01 16:23:37.081  1337  3105 W InputReader: Device has associated, but no associated display id.
10-01 16:23:37.081  1337  3105 W InputReader: Device has associated, but no associated display id.
10-01 16:23:37.081  1601  4890 I AVProVideo: 📜 (VulkanAPI.cpp:876) InterceptUnityVulkanInitialisation - Intercepting Vulkan API initialisation from Unity
10-01 16:23:37.081  1601  4890 E vulkan  : invalid vkGetInstanceProcAddr(VK_NULL_HANDLE, "vkGetInstanceProcAddr") call
10-01 16:23:37.086  3013  3271 I Shortcut: DockManager,RecentAppDataManager , getRecentApps end, {}
10-01 16:23:37.086  3013  3271 I Shortcut: DockManager,RecentAppDataManager , loadRecentApp , appList , size=0 , List=[]
10-01 16:23:37.091  1601  4890 I AVProVideo: 💬️ (VulkanAPI.cpp:264) vk::CreateInstanceHook - vulkan version: 1.1.0
10-01 16:23:37.102  1601  4890 I AdrenoVK-0: ===== BEGIN DUMP OF OVERRIDDEN SETTINGS =====
10-01 16:23:37.102  1601  4890 I AdrenoVK-0: ===== END DUMP OF OVERRIDDEN SETTINGS =====
10-01 16:23:37.103  1601  4890 I AdrenoVK-0: QUALCOMM build          : 4e19010, I576589ecdb
10-01 16:23:37.103  1601  4890 I AdrenoVK-0: Build Date              : 03/16/22
10-01 16:23:37.103  1601  4890 I AdrenoVK-0: Shader Compiler Version : EV031.36.06.01
10-01 16:23:37.103  1601  4890 I AdrenoVK-0: Local Branch            : Promotion602
10-01 16:23:37.103  1601  4890 I AdrenoVK-0: Remote Branch           :
10-01 16:23:37.103  1601  4890 I AdrenoVK-0: Remote Branch           :
10-01 16:23:37.103  1601  4890 I AdrenoVK-0: Reconstruct Branch      :
10-01 16:23:37.103  1601  4890 I AdrenoVK-0: Build Config            : S P 8.0.12 AArch32
10-01 16:23:37.103  1601  4890 I AdrenoVK-0: Driver Path             : /vendor/lib/hw/vulkan.kona.so
10-01 16:23:37.103  1601  4890 I AdrenoVK-0: Driver Version          : 0607.0
10-01 16:23:37.103  1601  4890 I AdrenoVK-0: PFP                     : 0x016dd104
10-01 16:23:37.103  1601  4890 I AdrenoVK-0: ME                      : 0x00000000
10-01 16:23:37.103  1601  4890 I AdrenoVK-0: Application Name    : avpro-repro
10-01 16:23:37.103  1601  4890 I AdrenoVK-0: Application Version : 0x00000001
10-01 16:23:37.103  1601  4890 I AdrenoVK-0: Engine Name         : Unity
10-01 16:23:37.103  1601  4890 I AdrenoVK-0: Engine Version      : 0x00000001
10-01 16:23:37.103  1601  4890 I AdrenoVK-0: Api Version         : 0x00401000
10-01 16:23:37.105  1601  4890 I AVProVideo: 💬️ (VulkanAPI.cpp:264) vk::CreateInstanceHook - vulkan version: 1.1.0
10-01 16:23:37.107  1601  4890 I AdrenoVK-0: QUALCOMM build          : 4e19010, I576589ecdb
10-01 16:23:37.107  1601  4890 I AdrenoVK-0: Build Date              : 03/16/22
10-01 16:23:37.107  1601  4890 I AdrenoVK-0: Shader Compiler Version : EV031.36.06.01
10-01 16:23:37.107  1601  4890 I AdrenoVK-0: Local Branch            : Promotion602
10-01 16:23:37.107  1601  4890 I AdrenoVK-0: Remote Branch           :
10-01 16:23:37.107  1601  4890 I AdrenoVK-0: Remote Branch           :
10-01 16:23:37.107  1601  4890 I AdrenoVK-0: Reconstruct Branch      :
10-01 16:23:37.107  1601  4890 I AdrenoVK-0: Build Config            : S P 8.0.12 AArch32
10-01 16:23:37.107  1601  4890 I AdrenoVK-0: Driver Path             : /vendor/lib/hw/vulkan.kona.so
10-01 16:23:37.107  1601  4890 I AdrenoVK-0: Driver Version          : 0607.0
10-01 16:23:37.107  1601  4890 I AdrenoVK-0: PFP                     : 0x016dd104
10-01 16:23:37.107  1601  4890 I AdrenoVK-0: ME                      : 0x00000000
10-01 16:23:37.107  1601  4890 I AdrenoVK-0: Application Name    : avpro-repro
10-01 16:23:37.107  1601  4890 I AdrenoVK-0: Application Version : 0x00000001
10-01 16:23:37.107  1601  4890 I AdrenoVK-0: Engine Name         : Unity
10-01 16:23:37.107  1601  4890 I AdrenoVK-0: Engine Version      : 0x00000001
10-01 16:23:37.107  1601  4890 I AdrenoVK-0: Api Version         : 0x00401000
10-01 16:23:37.108  1601  4890 I AVProVideo: 💬️ (VulkanAPI.cpp:505) vk::CreateDeviceHook - physicalDevice: 0xeea4bb98, VkDevice: 0xc7159bf8
10-01 16:23:37.108  1601  4890 I AVProVideo: 💬️ (VulkanAPI.cpp:516) vk::CreateDeviceHook - vulkan device version: 1.1.128
10-01 16:23:37.159   914  1027 I pxrhmdservice : AutoIPDManager <notifyPSensorChanged> sendMessage com.pxr.psensor.status (0,1,near) psensor=1
10-01 16:23:37.159  1776  1776 I ProximityPowerPolicyController: 1:0.0
10-01 16:23:37.159   914  1027 I PxrNotificationClient: sendPxrNotification,action:com.pxr.psensor.status id:0,value1: value2:1,ext:near
10-01 16:23:37.160  1637  1790 I PxrNotificationService: sendPxrMessage callingPid:914 action com.pxr.psensor.status ,value1: ,value2:1 ,ext:near ,mPvrCallbacks.size():8
10-01 16:23:37.160   914  1027 E pxrhmdservice : Error <servicecore_status_cb> - g_eyetracking_support=[0].
10-01 16:23:37.172  1337  1413 E LightsService: Invalid flash parameter 15.
10-01 16:23:37.172  1337  1413 W LightsService: light native  setLight_native return for validate false, but not return
10-01 16:23:37.173  1588  2691 I PxrCompositor: notifyHmdBatteryChangeEvent,value 99
10-01 16:23:37.174  3013  3271 W Shortcut: DockManager,RightAppDataManager , capacity changed , level=99 , scale=100
10-01 16:23:37.174  2701  2701 I MtpServer: sendDevicePropertyChanged 20481
10-01 16:23:37.175  1337  1910 I PowerSaverController: [FEAT_POWER_DETECT][0] handleBatteryChanged level: 99, screenOn: true, doze: false
10-01 16:23:37.175  1337  1392 I SmartPowerMonitor: [FEAT_POWER_MONITOR_COMMON][0] battery:99 gpuInfo: [1, 588638, 1000328, 58%, 441MHZ]
10-01 16:23:37.180  2420  2827 I PxrPlugin: XR_TYPE_EVENT_HMD_BATTERY_CHANGE  99
10-01 16:23:37.180  2420  2827 I [PxrUnity]: PluginDisplaySystem MainThread_UpdateDisplayState PXR_TYPE_EVENT_HMD_BATTERY_CHANGED m_HmdBattery:99
10-01 16:23:37.219  1601  4937 I SwappyDisplayManager: Starting looper thread
10-01 16:23:37.219  1601  4890 I SwappyVk: Returning refresh duration of 16666667 nsec (approx 59.999999 Hz)
10-01 16:23:37.236  1601  4890 I libOpenSLES: Emulating old channel mask behavior (ignoring positional mask 0x3, using default mask 0x3 based on channel count of 2)
10-01 16:23:37.236  1601  4890 W AudioTrack: set(): notificationFrames=-21 clamped to the range -1 to -8
10-01 16:23:37.236  1601  4944 E libprocessgroup: AddTidToCgroup failed to write '4944'; fd=44: Permission denied
10-01 16:23:37.236  1601  4944 E libprocessgroup: Failed to add task into cgroup
10-01 16:23:37.236  1601  4944 W libprocessgroup: Failed to apply BlkIOForeground task profile: Permission denied
10-01 16:23:37.238   862  1017 I audio_hw_primary: getting vr mode to 0
10-01 16:23:37.238   906  1074 I hash_map_utils: key: 'vr_audio_mode_on' value: ''
10-01 16:23:37.239   906  1074 I AudioPolicyManagerCustom: VR mode is 0, switch to primary output if request is for fast|raw
10-01 16:23:37.239   906  1074 W AudioFlinger: createTrack_l(): mismatch between requested flags (00000104) and output flags (00000006)
10-01 16:23:37.241  1601  4890 I AudioTrack: createTrack_l(0): AUDIO_OUTPUT_FLAG_FAST successful; frameCount 0 -> 1536
10-01 16:23:37.242  1601  4890 I AudioTrack: createTrack_l() register mute listener
10-01 16:23:37.242  1601  4890 I         : register trackId = 9
10-01 16:23:37.242  1601  4890 I AudioTrack: onSoundCallback isMute 0, isInit 1
10-01 16:23:37.259   862  1017 E volume_listener: check_and_set_gain_dep_cal: Failed to set gain dep cal level
10-01 16:23:37.262   906  1494 I PxrNotificationClient: sendPxrNotification,action:teatracker_event_action id:0,value1:audio_output value2:0,ext:{"channel_mask":3,"content_type":0,"flags":260,"foreground_package_name_far":"","foreground_package_name_near":"","format":1,"output_type":"speaker","package_name":"com.DefaultCompany.avprorepro","sample_rate":48000,"stream_type":3,"usage":1,"volume_ui":0}
10-01 16:23:37.262  1637  1790 E PxrNotificationUtils: non system uid checkSendPxrMessagePermissionWithAction:teatracker_event_action permission granted.
10-01 16:23:37.263  1637  1790 I PxrNotificationService: sendPxrMessage callingPid:906 action teatracker_event_action ,value1:audio_output ,value2:0 ,ext:{"channel_mask":3,"content_type":0,"flags":260,"foreground_package_name_far":"","foreground_package_name_near":"","format":1,"output_type":"speaker","package_name":"com.DefaultCompany.avprorepro","sample_rate":48000,"stream_type":3,"usage":1,"volume_ui":0} ,mPvrCallbacks.size():8
10-01 16:23:37.267   862  4956 I msm8974_platform: platform_check_and_set_codec_backend_cfg:becf: afe: bitwidth 16, samplerate 48000 channels 2, backend_idx 0 usecase = 1 device (speaker)
10-01 16:23:37.267   862  4956 I msm8974_platform: platform_check_and_set_codec_backend_cfg: new_snd_devices[0] is 2
10-01 16:23:37.267   862  4956 I msm8974_platform: platform_check_codec_backend_cfg:becf: afe: bitwidth 16, samplerate 48000 channels 2, backend_idx 0 usecase = 1 device (speaker)
10-01 16:23:37.268   862  4956 I msm8974_platform: platform_check_codec_backend_cfg:becf: afe: Codec selected backend: 0 updated bit width: 16 and sample rate: 48000
10-01 16:23:37.268   862  4956 E msm8974_platform: platform_check_backends_match: Invalid snd_device =
10-01 16:23:37.277   862  4956 I audio_hw_utils: audio_extn_utils_update_stream_output_app_type_cfg Allowing 24 and above bits playback on speaker ONLY at default sampling rate
10-01 16:23:37.277   862  4956 I audio_hw_utils: send_app_type_cfg_for_device PLAYBACK app_type 69937, acdb_dev_id 15, sample_rate 48000, snd_device_be_idx 179
10-01 16:23:37.279   862  4956 I msm8974_platform: platform_get_custom_mtmx_params: no matching param with id 0 ip_ch 2 op_ch 2 uc_id 1 snd_dev 2
10-01 16:23:37.302  2757  3417 E ActivityThread: Failed to find provider info for com.google.android.gsf.gservices
10-01 16:23:37.314  3013  3271 I Shortcut: checkShowExitActivity , startExitActivity
10-01 16:23:37.316  3013  3271 W Shortcut: AmsUtils , getTopRunningTask ....taskInfo: TaskInfo{userId=0 stackId=0 taskId=61 displayId=0 isRunning=true baseIntent=Intent { act=android.intent.action.MAIN cat=[android.intent.category.HOME] flg=0x10800100 cmp=com.pvr.vrshell/.MainActivity } baseActivity=ComponentInfo{com.pvr.vrshell/com.pvr.vrshell.MainActivity} topActivity=ComponentInfo{com.pvr.vrshell/com.pvr.vrshell.MainActivity} origActivity=null realActivity=ComponentInfo{com.pvr.vrshell/com.pvr.vrshell.MainActivity} numActivities=1 lastActiveTime=10455 supportsSplitScreenMultiWindow=true resizeMode=0
10-01 16:23:37.317  3013  3271 W Shortcut: AmsUtils , getTopRunningTask ....taskInfo: TaskInfo{userId=0 stackId=0 taskId=61 displayId=0 isRunning=true baseIntent=Intent { act=android.intent.action.MAIN cat=[android.intent.category.HOME] flg=0x10800100 cmp=com.pvr.vrshell/.MainActivity } baseActivity=ComponentInfo{com.pvr.vrshell/com.pvr.vrshell.MainActivity} topActivity=ComponentInfo{com.pvr.vrshell/com.pvr.vrshell.MainActivity} origActivity=null realActivity=ComponentInfo{com.pvr.vrshell/com.pvr.vrshell.MainActivity} numActivities=1 lastActiveTime=10455 supportsSplitScreenMultiWindow=true resizeMode=0
10-01 16:23:37.317  3013  3271 W Shortcut: DockUtils , ignoreVrApp , packageName=com.pvr.vrshell
10-01 16:23:37.317  3013  3271 I Shortcut: DockMainView ifStartExitActivity, isRunning=true, ExitActivity.isRunning=false hasNearVisibleApp=false , isVrApp=false , topApp.component=ComponentInfo{com.pvr.vrshell/com.pvr.vrshell.MainActivity}
10-01 16:23:37.321  1337  1528 I InputReader: KeyboardInputMapper::process-->change displayId from 3 to 5, currentViewPort display:3, device: virtual_input_device
10-01 16:23:37.321  1337  1528 I InputReader: TouchInputMapper::process-->change displayId from 3 to 5, currentViewPort display:3, device: virtual_input_device
10-01 16:23:37.321  1337  1528 I InputReader: findViewport, displayId: 5, device: virtual_input_device
10-01 16:23:37.321  1337  1528 I InputReader: change raw size, rawWidth:100001, rawHeight:100001, surfaceW:1922, surfaceH:1082 for device:virtual_input_device, displayId: 5
10-01 16:23:37.321  1337  1528 I InputReader: Device reconfigured: id=6, name='virtual_input_device', size 1922x1082, orientation 0, mode 1, display id 5
10-01 16:23:37.323   888  1010 W ANDR-PERF-LM: FeatureState: readState() 149: warning: appName is NULL, returning default State
10-01 16:23:37.323   888  1010 E ANDR-PERF-LM: GamePerfEngine: runAsync() 420: Hint is for  = 0x1043 PID=1337
10-01 16:23:37.323   888  1010 E ANDR-PERF-LM: GamePerfEngine: runAsync() 511: Hint is = TAP
10-01 16:23:37.379  2420  3700 I PxrMetric: FPS=72/72,MTP=26.32ms,AppSW=off,FrmEarly=0,FrmLate=1,FrmCpu=1.46ms,FrmGpu=4.48ms,FrmTime=6.42ms,ATWGPU=2.64ms,VsyncDelay=1,GPU=58%/441Mhz,GPUTemp=52.1C,LayerCnt=3,Pkg=com.pvr.vrshell,FrmId=2524
10-01 16:23:37.424  1601  4890 I MONO    : Getting locale
10-01 16:23:37.424  1601  4890 I Mono    : Locale nl-NL
10-01 16:23:37.568  2420  2827 W APxrRuntime: PXRSDK_PM ENGINE FPS: 72, 48725274876
10-01 16:23:37.646  1601  4937 I SwappyDisplayManager: Terminating looper thread
10-01 16:23:37.653  1601  4973 I SwappyDisplayManager: Starting looper thread
10-01 16:23:37.653  1601  4890 I SwappyVk: Returning refresh duration of 16666667 nsec (approx 59.999999 Hz)
10-01 16:23:37.737  1337  1392 I ActivityTaskManager: Fully drawn com.DefaultCompany.avprorepro/com.unity3d.player.UnityPlayerActivity: +1s22ms
10-01 16:23:37.743   888  2611 E ANDR-PERF-LM: VmRssMeter:: start() 305: Could not find pid, can not collect vmrss data
10-01 16:23:37.743   888  2612 E ANDR-PERF-LM: VmRssMeter:: start() 305: Could not find pid, can not collect vmrss data
10-01 16:23:37.743   888  2612 E ANDR-PERF-LM: AdaptiveLaunch: writeToDataBase() 63: Meter aborted or could not get meter data for this run
10-01 16:23:38.051   910  2524 I JNI_PART: PC_Services: 20230404A _For_MerlinE
10-01 16:23:38.051   910  2524 I JNI_PART: SENSOR version:  SENSOR 0.0.0.0 082ecf78-dirty
10-01 16:23:38.051   910  2524 I JNI_PART: PCLog_IMU_Data_1: 52.631487,30.530000,-0.189847,0.001860,-0.161413,8.675652,4.442532,-1.198638,9.820375
10-01 16:23:38.061   914  2495 I pxrhmdservice : Debug <IsScreenOn> - brightness=[201].
10-01 16:23:38.090   914  2495 I pxrhmdservice : Call <getCommonDataThread> - boot_completed=[1] ------------------------
10-01 16:23:38.126  1337  2331 W ActivityManager: Unable to start service Intent { cmp=com.bytedance.pico.matrix/.server.MatrixServer } U=0: not found
10-01 16:23:38.163  1337  1398 W System.err: java.io.FileNotFoundException: /sys/devices/platform/soc/c440000.qcom,spmi/spmi-0/spmi0-05/c440000.qcom,spmi:qcom,pm8150l@5:camera_temp/cam_temp_value: open failed: ENOENT (No such file or directory)
10-01 16:23:38.163  1337  1398 W System.err:    at libcore.io.IoBridge.open(IoBridge.java:496)
10-01 16:23:38.163  1337  1398 W System.err:    at java.io.FileInputStream.<init>(FileInputStream.java:159)
10-01 16:23:38.163  1337  1398 W System.err:    at java.io.FileInputStream.<init>(FileInputStream.java:115)
10-01 16:23:38.163  1337  1398 W System.err:    at com.android.server.SysPerfMonitorService.readSystemInfo(SysPerfMonitorService.java:2931)
10-01 16:23:38.163  1337  1398 W System.err:    at com.android.server.SysPerfMonitorService.access$600(SysPerfMonitorService.java:106)
10-01 16:23:38.163  1337  1398 W System.err:    at com.android.server.SysPerfMonitorService$ThermalStatusTask.run(SysPerfMonitorService.java:1947)
10-01 16:23:38.163  1337  1398 W System.err:    at com.android.server.SysEventSyncUtils.trainRunning(SysEventSyncUtils.java:61)
10-01 16:23:38.163  1337  1398 W System.err:    at com.android.server.SysPerfMonitorService$MainHandler.handleMessage(SysPerfMonitorService.java:511)
10-01 16:23:38.163  1337  1398 W System.err:    at android.os.Handler.dispatchMessage(Handler.java:107)
10-01 16:23:38.163  1337  1398 W System.err:    at android.os.Looper.loop(Looper.java:220)
10-01 16:23:38.163  1337  1398 W System.err:    at com.android.server.SysPerfMonitorService$InstructionThread.run(SysPerfMonitorService.java:478)
10-01 16:23:38.163  1337  1398 W System.err: Caused by: android.system.ErrnoException: open failed: ENOENT (No such file or directory)
10-01 16:23:38.163  1337  1398 W System.err:    at libcore.io.Linux.open(Native Method)
10-01 16:23:38.163  1337  1398 W System.err:    at libcore.io.ForwardingOs.open(ForwardingOs.java:167)
10-01 16:23:38.163  1337  1398 W System.err:    at libcore.io.BlockGuardOs.open(BlockGuardOs.java:252)
10-01 16:23:38.163  1337  1398 W System.err:    at libcore.io.IoBridge.open(IoBridge.java:482)
10-01 16:23:38.163  1337  1398 W System.err:    ... 10 more
10-01 16:23:38.163  1337  1398 W System.err: java.lang.IndexOutOfBoundsException: Index: 0, Size: 0
10-01 16:23:38.163  1337  1398 W System.err:    at java.util.ArrayList.get(ArrayList.java:437)
10-01 16:23:38.163  1337  1398 W System.err:    at com.android.server.SysPerfMonitorService$ThermalStatusTask.run(SysPerfMonitorService.java:1949)
10-01 16:23:38.163  1337  1398 W System.err:    at com.android.server.SysEventSyncUtils.trainRunning(SysEventSyncUtils.java:61)
10-01 16:23:38.163  1337  1398 W System.err:    at com.android.server.SysPerfMonitorService$MainHandler.handleMessage(SysPerfMonitorService.java:511)
10-01 16:23:38.163  1337  1398 W System.err:    at android.os.Handler.dispatchMessage(Handler.java:107)
10-01 16:23:38.163  1337  1398 W System.err:    at android.os.Looper.loop(Looper.java:220)
10-01 16:23:38.163  1337  1398 W System.err:    at com.android.server.SysPerfMonitorService$InstructionThread.run(SysPerfMonitorService.java:478)
10-01 16:23:38.169   919  1886 E SysMonitorPowerStat: Error GetFanSpeed client not init !
10-01 16:23:38.169   919  1886 E SysMonitorPowerStat: Error GetFanRPM client not init !
10-01 16:23:38.170  1337  1398 I UidCpuTracker: small cpu total = 4177 ; idle = 3476 ; ratio = 16 ; mCpuUsageRatio = 18
10-01 16:23:38.170  1337  1398 I UidCpuTracker: larger cpu total = 3066 ; idle = 2450 ; ratio = 20 ; mCpuUsageRatio = 18
10-01 16:23:38.170  1337  1398 I UidCpuTracker: super cpu total = 1046 ; idle = 830 ; ratio = 20 ; mCpuUsageRatio = 18
10-01 16:23:38.171  4977  4977 W gtop    : type=1400 audit(0.0:5110): avc: denied { read } for name="gpu_procs" dev="proc" ino=4026533676 scontext=u:r:system_server:s0 tcontext=u:object_r:proc_gtop:s0 tclass=dir permissive=0
10-01 16:23:38.289  3815  4377 W System.err: java.lang.ClassNotFoundException: com.bytedance.services.apm.api.IHttpService__ServiceProxy
10-01 16:23:38.290  3815  4377 W System.err:    at java.lang.Class.classForName(Native Method)
10-01 16:23:38.290  3815  4377 W System.err:    at java.lang.Class.forName(Class.java:454)
10-01 16:23:38.290  3815  4377 W System.err:    at java.lang.Class.forName(Class.java:379)
10-01 16:23:38.290  3815  4377 W System.err:    at com.bytedance.news.common.service.manager.d.c(Unknown Source:16)
10-01 16:23:38.290  3815  4377 W System.err:    at com.bytedance.news.common.service.manager.d.a(Unknown Source:48)
10-01 16:23:38.290  3815  4377 W System.err:    at d.a.d.a.a.a.k.a(Unknown Source:2)
10-01 16:23:38.290  3815  4377 W System.err:    at d.a.d.a.a.a.i.d(Unknown Source:183)
10-01 16:23:38.290  3815  4377 W System.err:    at d.a.d.a.a.a.i.e(Unknown Source:14)
10-01 16:23:38.290  3815  4377 W System.err:    at d.a.d.a.a.a.f.j(Unknown Source:87)
10-01 16:23:38.290  3815  4377 W System.err:    at d.a.d.a.a.a.e.f(Unknown Source:145)
10-01 16:23:38.290  3815  4377 W System.err:    at d.a.d.a.a.a.e.e(Unknown Source:18)
10-01 16:23:38.291  3815  4377 W System.err:    at d.a.d.a.a.a.e.a(Unknown Source:9)
10-01 16:23:38.291  3815  4377 W System.err:    at d.a.d.a.a.b.a$a.run(Unknown Source:24)
10-01 16:23:38.291  3815  4377 W System.err:    at android.os.Handler.handleCallback(Handler.java:883)
10-01 16:23:38.291  3815  4377 W System.err:    at android.os.Handler.dispatchMessage(Handler.java:100)
10-01 16:23:38.291  3815  4377 W System.err:    at android.os.Looper.loop(Looper.java:220)
10-01 16:23:38.291  3815  4377 W System.err:    at d.a.d.a.a.b.c$d.onLooperPrepared(Unknown Source:37)
10-01 16:23:38.291  3815  4377 W System.err:    at android.os.HandlerThread.run(HandlerThread.java:66)
10-01 16:23:38.291  3815  4377 W System.err: Caused by: java.lang.ClassNotFoundException: com.bytedance.services.apm.api.IHttpService__ServiceProxy
10-01 16:23:38.291  3815  4377 W System.err:    ... 18 more
10-01 16:23:38.295  3815  4377 I om.pvr.picocas: Waiting for a blocking GC ClassLinker
10-01 16:23:38.304  3815  4377 I om.pvr.picocas: WaitForGcToComplete blocked ClassLinker on HeapTrim for 8.350ms
10-01 16:23:38.306  3815  4377 I DpmTcmClient: RegisterTcmMonitor from: $Proxy6
10-01 16:23:38.307  3815  4377 I DpmTcmClient: RegisterTcmMonitor from: $Proxy6
10-01 16:23:38.308   728  4979 E ResolverController: No valid NAT64 prefix (100, <unspecified>/0)
10-01 16:23:38.393  2420  3700 I PxrMetric: FPS=72/72,MTP=26.24ms,AppSW=off,FrmEarly=0,FrmLate=0,FrmCpu=1.59ms,FrmGpu=4.50ms,FrmTime=6.54ms,ATWGPU=2.74ms,VsyncDelay=1,GPU=57%/441Mhz,GPUTemp=55.2C,LayerCnt=4,Pkg=com.pvr.vrshell,FrmId=2597
10-01 16:23:38.419  4977  4977 W gtop    : type=1400 audit(0.0:5111): avc: denied { read } for name="gpu_procs" dev="proc" ino=4026533676 scontext=u:r:system_server:s0 tcontext=u:object_r:proc_gtop:s0 tclass=dir permissive=0
10-01 16:23:38.582  2420  2827 W APxrRuntime: PXRSDK_PM ENGINE FPS: 72, 49738772011
10-01 16:23:38.680   919  1886 E SysMonitorPowerStat: Error getBrightness client not init !
10-01 16:23:38.768  4982  4982 E libc    : Access denied finding property "libc.debug.gwp_asan.sample_rate.sleep"
10-01 16:23:38.768  4982  4982 E libc    : Access denied finding property "persist.libc.debug.gwp_asan.sample_rate.sleep"
10-01 16:23:38.763  4982  4982 W sleep   : type=1400 audit(0.0:5112): avc: denied { read } for name="u:object_r:default_prop:s0" dev="tmpfs" ino=22307 scontext=u:r:qti_init_shell:s0 tcontext=u:object_r:default_prop:s0 tclass=file permissive=0
10-01 16:23:38.768  4982  4982 E libc    : Access denied finding property "libc.debug.gwp_asan.sample_rate.system_default"
10-01 16:23:38.768  4982  4982 E libc    : Access denied finding property "persist.libc.debug.gwp_asan.sample_rate.system_default"
10-01 16:23:38.768  4982  4982 E libc    : Access denied finding property "libc.debug.gwp_asan.process_sampling.sleep"
10-01 16:23:38.768  4982  4982 E libc    : Access denied finding property "persist.libc.debug.gwp_asan.process_sampling.sleep"
10-01 16:23:38.763  4982  4982 W sleep   : type=1400 audit(0.0:5113): avc: denied { read } for name="u:object_r:default_prop:s0" dev="tmpfs" ino=22307 scontext=u:r:qti_init_shell:s0 tcontext=u:object_r:default_prop:s0 tclass=file permissive=0
10-01 16:23:38.768  4982  4982 E libc    : Access denied finding property "libc.debug.gwp_asan.process_sampling.system_default"
10-01 16:23:38.768  4982  4982 E libc    : Access denied finding property "persist.libc.debug.gwp_asan.process_sampling.system_default"
10-01 16:23:38.768  4982  4982 E libc    : Access denied finding property "libc.debug.gwp_asan.max_allocs.sleep"
10-01 16:23:38.768  4982  4982 E libc    : Access denied finding property "persist.libc.debug.gwp_asan.max_allocs.sleep"
10-01 16:23:38.763  4982  4982 W sleep   : type=1400 audit(0.0:5114): avc: denied { read } for name="u:object_r:default_prop:s0" dev="tmpfs" ino=22307 scontext=u:r:qti_init_shell:s0 tcontext=u:object_r:default_prop:s0 tclass=file permissive=0
10-01 16:23:38.768  4982  4982 E libc    : Access denied finding property "libc.debug.gwp_asan.max_allocs.system_default"
10-01 16:23:38.768  4982  4982 E libc    : Access denied finding property "persist.libc.debug.gwp_asan.max_allocs.system_default"
10-01 16:23:38.809  1337  1386 I system_server: Explicit concurrent copying GC freed 36319(2593KB) AllocSpace objects, 92(1920KB) LOS objects, 32% free, 12MB/18MB, paused 34us total 50.583ms
10-01 16:23:39.051   910  2524 I JNI_PART: PC_Services: 20230404A _For_MerlinE
10-01 16:23:39.051   910  2524 I JNI_PART: SENSOR version:  SENSOR 0.0.0.0 082ecf78-dirty
10-01 16:23:39.051   910  2524 I JNI_PART: PCLog_IMU_Data_1: 53.631506,31.530000,-0.023033,0.015147,0.016146,8.031006,5.357232,-1.302940,9.741388
10-01 16:23:39.061   910  1183 I PvrTrackingService-FalconEController: readImuLoop, tracker mac 0, count 2914, battery 1,10.000048
10-01 16:23:39.061   910  1183 I PvrTrackingService-FalconEController: readImuLoop, total imu count 3001, diff_ms 5000.100311
10-01 16:23:39.072   910  2524 I PvrTrackingService: logDebugControllerPose,right confidence 2, pose1 0.953245,0.091112,-0.061129,-0.281580,230.390134,-470.445467,-264.990538,pose2 0.953245,0.091112,-0.061129,-0.281580,230.390134,-470.445467,-264.990538
10-01 16:23:39.120   914  2495 I pxrhmdservice : Call <getCommonDataThread> - boot_completed=[1] ------------------------
10-01 16:23:39.163   910  2590 W PICO_3DoF: PN3 monitor FPS: 794.862340
10-01 16:23:39.178   878   878 E [email protected]: cyx convertHidlNanEnableReque 306 contentavg=122
10-01 16:23:39.242   870   951 E IPCMessagingProxy: MQClientControllerBase::init failed : 101
10-01 16:23:39.242   870   951 E IPCMessagingProxy: Exiting with error IPCMessagingProxy line 82 "1"
10-01 16:23:39.245   870   951 E IzatSvc_QNP: Exiting with error getInstance line 284 2
10-01 16:23:39.245   870   951 W IzatSvc_ComboNetworkProvider: Failed to initialize to NLP_MODE_QNP_ONLY for Emergency mode
10-01 16:23:39.245   870   951 E Subscription: sSubscriptionCb NULL !
10-01 16:23:39.245   870   951 I chatty  : uid=1021(gps) Loc_hal_worker identical 2 lines
10-01 16:23:39.245   870   951 E Subscription: sSubscriptionCb NULL !
10-01 16:23:39.393  2420  3700 I PxrMetric: FPS=72/72,MTP=26.26ms,AppSW=off,FrmEarly=0,FrmLate=0,FrmCpu=1.67ms,FrmGpu=4.94ms,FrmTime=7.10ms,ATWGPU=2.80ms,VsyncDelay=1,GPU=61%/441Mhz,GPUTemp=53.2C,LayerCnt=4,Pkg=com.pvr.vrshell,FrmId=2669
10-01 16:23:39.582  2420  2827 W APxrRuntime: PXRSDK_PM ENGINE FPS: 72, 50739242063
10-01 16:23:39.997  1601  1601 I ExoPlayerImpl: Init 2c37672 [AndroidXMedia3/1.4.1] [PICOA7Q10, A7Q50, PICO, 29]
10-01 16:23:40.004  1601  1601 I AVProVideo: 💬️ Player_ExoPlayer.CreateExoPlayer - CREATED new ExoPlayer object
10-01 16:23:40.006  1601  4890 I Unity   : playerID: 00004681
10-01 16:23:40.006  1601  4890 I Unity   : CreateCommandBuffers - flags: 00000000
10-01 16:23:40.006  1601  4890 I Unity   : flags: 00004681
10-01 16:23:40.039  1601  4890 I Unity   : [AVProVideo] Initialising AVPro Video v3.2.9 (native plugin v3.2.8-core) on Adreno (TM) 650/Vulkan 1.1.0 [512.607.0] (MT True) on Android
10-01 16:23:40.045  1601  4890 I Unity   : [AVProVideo] Opening jar:file:///data/app/com.DefaultCompany.avprorepro-TwQPA4UY9kR4F5u7UyEaDw==/base.apk!/assets/AVProVideoSamples/Cones-2D-1080p60-H264.mp4 (offset 0) with API ExoPlayer
10-01 16:23:40.049  1601  1601 I AVProVideo: 💬️ Player_ExoPlayer.BuildMediaSource - Video Loading: jar|zip|obb
10-01 16:23:40.051   910  2524 I JNI_PART: PC_Services: 20230404A _For_MerlinE
10-01 16:23:40.051   910  2524 I JNI_PART: SENSOR version:  SENSOR 0.0.0.0 082ecf78-dirty
10-01 16:23:40.051   910  2524 I JNI_PART: PCLog_IMU_Data_1: 54.631525,32.530000,-0.105130,-0.079036,-0.067616,7.869845,5.447645,-1.256420,9.653491
10-01 16:23:40.085  1601  4985 W VideoCapabilities: Unsupported mime image/vnd.android.heic
10-01 16:23:40.087  1601  4985 W VideoCapabilities: Unrecognized profile/level 0/3 for video/mpeg2
10-01 16:23:40.088  1601  4985 W VideoCapabilities: Unrecognized profile/level 0/3 for video/mpeg2
10-01 16:23:40.113  1601  4988 I AdrenoGLES-0: QUALCOMM build                   : 4e19010, I576589ecdb
10-01 16:23:40.113  1601  4988 I AdrenoGLES-0: Build Date                       : 03/16/22
10-01 16:23:40.113  1601  4988 I AdrenoGLES-0: OpenGL ES Shader Compiler Version: EV031.36.06.01
10-01 16:23:40.113  1601  4988 I AdrenoGLES-0: Local Branch                     : Promotion602
10-01 16:23:40.113  1601  4988 I AdrenoGLES-0: Remote Branch                    :
10-01 16:23:40.113  1601  4988 I AdrenoGLES-0: Remote Branch                    :
10-01 16:23:40.113  1601  4988 I AdrenoGLES-0: Reconstruct Branch               :
10-01 16:23:40.113  1601  4988 I AdrenoGLES-0: Build Config                     : S P 8.0.12 AArch32
10-01 16:23:40.113  1601  4988 I AdrenoGLES-0: Driver Path                      : /vendor/lib/egl/libGLESv2_adreno.so
10-01 16:23:40.113  1601  4988 I AdrenoGLES-0: Driver Version                   : 0607.0
10-01 16:23:40.115  1601  4988 I AdrenoGLES-0: PFP: 0x016dd104, ME: 0x00000000
10-01 16:23:40.126  1601  4988 I Surface : create surface : Surface(name=android.graphics.SurfaceTexture@1197ab3)/@0x4adf870 from : SurfaceTexture-1-1601-0
10-01 16:23:40.119  1601  1601 W ExoPlayer:Playb: type=1400 audit(0.0:5124): avc: denied { write } for path="/dev/blkio/tasks" dev="cgroup" ino=5 scontext=u:r:untrusted_app:s0:c116,c256,c512,c768 tcontext=u:object_r:cgroup:s0 tclass=file permissive=0
10-01 16:23:40.127  1601  4989 E libprocessgroup: AddTidToCgroup failed to write '4989'; fd=44: Permission denied
10-01 16:23:40.127  1601  4989 E libprocessgroup: Failed to add task into cgroup
10-01 16:23:40.127  1601  4989 W libprocessgroup: Failed to apply BlkIOForeground task profile: Permission denied
10-01 16:23:40.128  1601  4990 E libprocessgroup: AddTidToCgroup failed to write '4990'; fd=44: Permission denied
10-01 16:23:40.128  1601  4990 E libprocessgroup: Failed to add task into cgroup
10-01 16:23:40.128  1601  4990 W libprocessgroup: Failed to apply BlkIOForeground task profile: Permission denied
10-01 16:23:40.123  1601  1601 W ExoPlayer:Playb: type=1400 audit(0.0:5125): avc: denied { write } for path="/dev/blkio/tasks" dev="cgroup" ino=5 scontext=u:r:untrusted_app:s0:c116,c256,c512,c768 tcontext=u:object_r:cgroup:s0 tclass=file permissive=0
10-01 16:23:40.130  1601  4990 I OMXClient: IOmx service obtained
10-01 16:23:40.123  1601  1601 W CodecLooper: type=1400 audit(0.0:5126): avc: denied { write } for path="/dev/blkio/tasks" dev="cgroup" ino=5 scontext=u:r:untrusted_app:s0:c116,c256,c512,c768 tcontext=u:object_r:cgroup:s0 tclass=file permissive=0
10-01 16:23:40.130  1601  4991 E libprocessgroup: AddTidToCgroup failed to write '4991'; fd=44: Permission denied
10-01 16:23:40.130  1601  4991 E libprocessgroup: Failed to add task into cgroup
10-01 16:23:40.130  1601  4991 W libprocessgroup: Failed to apply BlkIOForeground task profile: Permission denied
10-01 16:23:40.130  1231  2276 I OMXMaster: makeComponentInstance(OMX.qcom.video.decoder.avc) in android.hardwar process
10-01 16:23:40.130  1231  2276 E         : Service not available yet
10-01 16:23:40.143  1231  1231 W HwBinder:1231_7: type=1400 audit(0.0:5127): avc: denied { search } for name="tmp" dev="sda16" ino=75 scontext=u:r:mediacodec:s0 tcontext=u:object_r:shell_data_file:s0 tclass=dir permissive=0
10-01 16:23:40.151   914  2495 I pxrhmdservice : Call <getCommonDataThread> - boot_completed=[1] ------------------------
10-01 16:23:40.156  1231  2276 I OMX-VDEC-1080P: omx_vdec::component_init() success : OMX.qcom.video.decoder.avc fd=10
10-01 16:23:40.157  1601  4989 I BufferQueueProducer: [SurfaceTexture-1-1601-0] connect: api 3, w*h = 1 * 1
10-01 16:23:40.157  1601  4989 I MediaCodec: [OMX.qcom.video.decoder.avc] setting surface generation to 1639425
10-01 16:23:40.157  1601  4989 I BufferQueueProducer: [SurfaceTexture-1-1601-0] disconnect: api 3, w*h = 1 * 1
10-01 16:23:40.157  1601  4989 I BufferQueueProducer: [SurfaceTexture-1-1601-0] connect: api 3, w*h = 1 * 1
10-01 16:23:40.158  1224  2274 E PxrMediaAnalyticsService: submitMediaItemToTeaTracker fail
10-01 16:23:40.158  1224  2274 W SceneManagerHelper: submitMediaItemToSceneManager: null value for kEventCodecRenderFrameRate
10-01 16:23:40.158  1224  2274 W SceneManagerHelper: submitMediaItemToSceneManager: null value for kEventCodecVideoVRType
10-01 16:23:40.160  1231  2276 E OMX-VDEC-1080P: Extension: OMX.google.android.index.useAndroidNativeBuffer is supported
10-01 16:23:40.160  1231  2276 E OMX-VDEC-1080P: get_parameter: unknown param 0x7f000046
10-01 16:23:40.160  1231  2276 E OMXNodeInstance: getParameter(0xeabd8304:qcom.decoder.avc, ??(0x7f000046)) ERROR: UnsupportedIndex(0x8000101a)
10-01 16:23:40.160  1231  2276 E OMX-VDEC-1080P: set_parameter: Error: 0x80001019, setting param 0x7f00005d
10-01 16:23:40.160  1231  2276 E OMXNodeInstance: setParameter(0xeabd8304:qcom.decoder.avc, OMX.google.android.index.allocateNativeHandle(0x7f00005d): Output:1 en=0) ERROR: UnsupportedSetting(0x80001019)
10-01 16:23:40.160  1601  4990 I ExtendedACodec: setupVideoDecoder()
10-01 16:23:40.165  1231  2275 I C2DColorConvert: getDummySurfaceDef: half stride for Cb Cr planes
10-01 16:23:40.166  1601  4990 I ExtendedACodec: Decoder will be in frame by frame mode
10-01 16:23:40.167  1231  2275 E OMX-VDEC-1080P: Setparameter: unknown param 2130706434
10-01 16:23:40.167  1231  2275 E OMX-VDEC-1080P: set_parameter: Error: 0x8000101a, setting param 0x7f000002
10-01 16:23:40.167  1231  2275 E OMXNodeInstance: setParameter(0xeabd8304:qcom.decoder.avc, ??(0x7f000002)) ERROR: UnsupportedIndex(0x8000101a)
10-01 16:23:40.167  1601  4990 W ExtendedACodec: Failed to set frame packing format on component
10-01 16:23:40.168  1231  2276 E OMXNodeInstance: getConfig(0xeabd8304:qcom.decoder.avc, ??(0x7f000062)) ERROR: UnsupportedSetting(0x80001019)
10-01 16:23:40.170  1224  2274 W PxrEventUploader: codec_name: OMX.qcom.video.decoder.avc
10-01 16:23:40.171  1224  2274 I PxrNotificationClient: sendPxrNotification,action:teatracker_event_action id:0,value1:media_codec_configure value2:0,ext:{"caller_package_name":"com.DefaultCompany.avprorepro","codec_name":"OMX.qcom.video.decoder.avc","frame_rate":60,"is_protected":0,"metrics_session_id":10,"rotation_degrees":0,"video_height":1080,"video_width":1920}
10-01 16:23:40.171  1637  1790 E PxrNotificationUtils: non system uid checkSendPxrMessagePermissionWithAction:teatracker_event_action permission granted.
10-01 16:23:40.172  1637  1790 I PxrNotificationService: sendPxrMessage callingPid:1224 action teatracker_event_action ,value1:media_codec_configure ,value2:0 ,ext:{"caller_package_name":"com.DefaultCompany.avprorepro","codec_name":"OMX.qcom.video.decoder.avc","frame_rate":60,"is_protected":0,"metrics_session_id":10,"rotation_degrees":0,"video_height":1080,"video_width":1920} ,mPvrCallbacks.size():8
10-01 16:23:40.172  1224  2274 W SceneManagerHelper: submitMediaItemToSceneManager: discrad invailed keyEvent: media_codec_configure
10-01 16:23:40.172  1224  2274 E PxrMediaAnalyticsService: submitMediaItemToSceneManager fail
10-01 16:23:40.214  1231  2276 I C2DColorConvert: getDummySurfaceDef: half stride for Cb Cr planes
10-01 16:23:40.215  1231  2276 E OMXNodeInstance: getConfig(0xeabd8304:qcom.decoder.avc, ??(0x7f000062)) ERROR: UnsupportedSetting(0x80001019)
10-01 16:23:40.216  1231  4993 I OMX-VDEC-1080P: allocate_down_scaler_buf::reset
10-01 16:23:40.231  1231  2276 E OMXNodeInstance: getConfig(0xeabd8304:qcom.decoder.avc, ??(0x7f000062)) ERROR: UnsupportedSetting(0x80001019)
10-01 16:23:40.232  1224  2274 E PxrMediaAnalyticsService: submitMediaItemToTeaTracker fail
10-01 16:23:40.232  1224  2274 W SceneManagerHelper: submitMediaItemToSceneManager: null value for kEventCodecRenderFrameRate
10-01 16:23:40.232  1224  2274 W SceneManagerHelper: submitMediaItemToSceneManager: null value for kEventCodecVideoVRType
10-01 16:23:40.361  1601  1601 I AVProVideo: 💬️ (AHardwareBufferPlayerSurface.cpp:168) AHardwareBufferPlayerSurface::AHardwareBufferPlayerSurface - creating surface with 2 images
10-01 16:23:40.362  1601  4997 E libprocessgroup: AddTidToCgroup failed to write '4997'; fd=44: Permission denied
10-01 16:23:40.362  1601  4997 E libprocessgroup: Failed to add task into cgroup
10-01 16:23:40.362  1601  4997 W libprocessgroup: Failed to apply BlkIOForeground task profile: Permission denied
10-01 16:23:40.355  1601  1601 W pany.avprorepro: type=1400 audit(0.0:5128): avc: denied { write } for path="/dev/blkio/tasks" dev="cgroup" ino=5 scontext=u:r:untrusted_app:s0:c116,c256,c512,c768 tcontext=u:object_r:cgroup:s0 tclass=file permissive=0
10-01 16:23:40.362  1601  1601 I AVProVideo: 💬️ Player_Base.UpdateForResolutionChange - UpdateForResolutionChange : m_bVideo_AcceptCommands.set( true )
10-01 16:23:40.363  1601  4989 I BufferQueueProducer: [ImageReader-1920x1080f22u256m2-1601-0] connect: api 3, w*h = 1920 * 1080
10-01 16:23:40.363  1601  4989 I MediaCodec: [OMX.qcom.video.decoder.avc] setting surface generation to 1639426
10-01 16:23:40.363  1601  4989 I BufferQueueProducer: [ImageReader-1920x1080f22u256m2-1601-0] disconnect: api 3, w*h = 1920 * 1080
10-01 16:23:40.363  1601  4989 I BufferQueueProducer: [ImageReader-1920x1080f22u256m2-1601-0] connect: api 3, w*h = 1920 * 1080
10-01 16:23:40.364  1601  4989 I BufferQueueProducer: [SurfaceTexture-1-1601-0] disconnect: api 3, w*h = 1 * 1
10-01 16:23:40.364  1224  2274 E PxrMediaAnalyticsService: submitMediaItemToTeaTracker fail
10-01 16:23:40.364  1224  2274 W SceneManagerHelper: submitMediaItemToSceneManager: null value for kEventCodecRenderFrameRate
10-01 16:23:40.364  1224  2274 W SceneManagerHelper: submitMediaItemToSceneManager: null value for kEventCodecVideoVRType
10-01 16:23:40.376  1601  4998 W NdkImageReader: Unable to acquire a lockedBuffer, very likely client tries to lock more than maxImages buffers
10-01 16:23:40.406  2420  3700 I PxrMetric: FPS=72/72,MTP=26.94ms,AppSW=off,FrmEarly=0,FrmLate=0,FrmCpu=1.60ms,FrmGpu=4.41ms,FrmTime=6.48ms,ATWGPU=2.75ms,VsyncDelay=1,GPU=61%/441Mhz,GPUTemp=52.5C,LayerCnt=4,Pkg=com.pvr.vrshell,FrmId=2742
10-01 16:23:40.476  1601  4998 W NdkImageReader: Unable to acquire a lockedBuffer, very likely client tries to lock more than maxImages buffers
10-01 16:23:40.546  1601  4998 I chatty  : uid=10116(com.DefaultCompany.avprorepro) identical 1 line
10-01 16:23:40.587  1601  4998 W NdkImageReader: Unable to acquire a lockedBuffer, very likely client tries to lock more than maxImages buffers
10-01 16:23:40.596  2420  2827 W APxrRuntime: PXRSDK_PM ENGINE FPS: 72, 51752723781
10-01 16:23:40.643  1601  4998 W NdkImageReader: Unable to acquire a lockedBuffer, very likely client tries to lock more than maxImages buffers
10-01 16:23:40.699  1601  4998 I chatty  : uid=10116(com.DefaultCompany.avprorepro) identical 1 line
10-01 16:23:40.754  1601  4998 W NdkImageReader: Unable to acquire a lockedBuffer, very likely client tries to lock more than maxImages buffers
10-01 16:23:40.773  4999  4999 E libc    : Access denied finding property "libc.debug.gwp_asan.sample_rate.sleep"
10-01 16:23:40.773  4999  4999 E libc    : Access denied finding property "persist.libc.debug.gwp_asan.sample_rate.sleep"
10-01 16:23:40.773  4999  4999 E libc    : Access denied finding property "libc.debug.gwp_asan.sample_rate.system_default"
10-01 16:23:40.773  4999  4999 E libc    : Access denied finding property "persist.libc.debug.gwp_asan.sample_rate.system_default"
10-01 16:23:40.773  4999  4999 E libc    : Access denied finding property "libc.debug.gwp_asan.process_sampling.sleep"
10-01 16:23:40.773  4999  4999 E libc    : Access denied finding property "persist.libc.debug.gwp_asan.process_sampling.sleep"
10-01 16:23:40.773  4999  4999 E libc    : Access denied finding property "libc.debug.gwp_asan.process_sampling.system_default"
10-01 16:23:40.773  4999  4999 E libc    : Access denied finding property "persist.libc.debug.gwp_asan.process_sampling.system_default"
10-01 16:23:40.773  4999  4999 E libc    : Access denied finding property "libc.debug.gwp_asan.max_allocs.sleep"
10-01 16:23:40.773  4999  4999 E libc    : Access denied finding property "persist.libc.debug.gwp_asan.max_allocs.sleep"
10-01 16:23:40.773  4999  4999 E libc    : Access denied finding property "libc.debug.gwp_asan.max_allocs.system_default"
10-01 16:23:40.773  4999  4999 E libc    : Access denied finding property "persist.libc.debug.gwp_asan.max_allocs.system_default"
10-01 16:23:40.810  1601  4998 W NdkImageReader: Unable to acquire a lockedBuffer, very likely client tries to lock more than maxImages buffers
10-01 16:23:40.852  1337  2626 E TaskPersister: File error accessing recents directory (directory doesn't exist?).
10-01 16:23:40.865  1601  4998 I chatty  : uid=10116(com.DefaultCompany.avprorepro) identical 1 line
10-01 16:23:40.921  1601  4998 W NdkImageReader: Unable to acquire a lockedBuffer, very likely client tries to lock more than maxImages buffers
10-01 16:23:40.952  4179  4551 I ByteCast: cybergarageDevice:deviceSearchResponse, ssdpPacket:/192.168.0.101 ssdpST urn:dial-multiscreen-org:service:dial:1
10-01 16:23:40.976  1601  4998 W NdkImageReader: Unable to acquire a lockedBuffer, very likely client tries to lock more than maxImages buffers
10-01 16:23:41.032  1601  4998 W NdkImageReader: Unable to acquire a lockedBuffer, very likely client tries to lock more than maxImages buffers
10-01 16:23:41.042   913  1956 I PxrFanService: FanControlSetDriver changed settings - [52199329562]    [20567041814]
10-01 16:23:41.043   913  1956 I PxrFanService: FanControlSetDriver fanRpm=14
10-01 16:23:41.043   913  1956 I PxrFanService: FanControlSetDriver fanState=35
10-01 16:23:41.051   910  2524 I JNI_PART: PC_Services: 20230404A _For_MerlinE
10-01 16:23:41.051   910  2524 I JNI_PART: SENSOR version:  SENSOR 0.0.0.0 082ecf78-dirty
10-01 16:23:41.051   910  2524 I JNI_PART: PCLog_IMU_Data_1: 55.631550,33.530000,0.083119,-0.144925,0.024828,7.367401,5.741137,-2.740263,9.733873
10-01 16:23:41.072   910  2524 I PvrTrackingService: logDebugControllerPose,right confidence 2, pose1 0.930570,0.178255,-0.092905,-0.305999,241.953303,-415.235096,-240.435845,pose2 0.930570,0.178255,-0.092905,-0.305999,241.953303,-415.235096,-240.435845
10-01 16:23:41.087  1601  4998 W NdkImageReader: Unable to acquire a lockedBuffer, very likely client tries to lock more than maxImages buffers
10-01 16:23:41.128  1337  3185 W ActivityManager: Unable to start service Intent { cmp=com.bytedance.pico.matrix/.server.MatrixServer } U=0: not found
10-01 16:23:41.143  1601  4998 W NdkImageReader: Unable to acquire a lockedBuffer, very likely client tries to lock more than maxImages buffers
10-01 16:23:41.181   914  2495 I pxrhmdservice : Call <getCommonDataThread> - boot_completed=[1] ------------------------
10-01 16:23:41.197  1601  4998 W NdkImageReader: Unable to acquire a lockedBuffer, very likely client tries to lock more than maxImages buffers
10-01 16:23:41.310  1601  4998 I chatty  : uid=10116(com.DefaultCompany.avprorepro) identical 2 lines
10-01 16:23:41.365  1601  4998 W NdkImageReader: Unable to acquire a lockedBuffer, very likely client tries to lock more than maxImages buffers
10-01 16:23:41.407  2420  3700 I PxrMetric: FPS=72/72,MTP=26.20ms,AppSW=off,FrmEarly=0,FrmLate=0,FrmCpu=1.43ms,FrmGpu=4.59ms,FrmTime=6.49ms,ATWGPU=2.81ms,VsyncDelay=1,GPU=58%/441Mhz,GPUTemp=55.6C,LayerCnt=4,Pkg=com.pvr.vrshell,FrmId=2814
10-01 16:23:41.421  1601  4998 W NdkImageReader: Unable to acquire a lockedBuffer, very likely client tries to lock more than maxImages buffers
10-01 16:23:41.477  1601  4998 I chatty  : uid=10116(com.DefaultCompany.avprorepro) identical 1 line
10-01 16:23:41.532  1601  4998 W NdkImageReader: Unable to acquire a lockedBuffer, very likely client tries to lock more than maxImages buffers
10-01 16:23:41.557  1913  2202 E XRShell-Native: EventDispatcher , sourceType=4 , trigger=1.000000
10-01 16:23:41.557  1913  2199 I XRShell : EventDispatcher , disp2WinByUInput, displayId: 5 , Event{clientId=5, displayId=5, action=0, keyCode=0, buttonId=10004, deviceId=2, isMotion=true, x=1426.6378, y=517.8369, Window{Binder=0, mClientId=5, mDisplayId=5, mWindowType=3002, mWidth=1922, mHeight=1082, mNeedSendMove=false}}
10-01 16:23:41.596  2420  2827 W APxrRuntime: PXRSDK_PM ENGINE FPS: 72, 52753121802
10-01 16:23:41.630  1601  4998 W NdkImageReader: Unable to acquire a lockedBuffer, very likely client tries to lock more than maxImages buffers
10-01 16:23:41.644  1601  4998 I chatty  : uid=10116(com.DefaultCompany.avprorepro) identical 1 line
10-01 16:23:41.697  1601  4998 W NdkImageReader: Unable to acquire a lockedBuffer, very likely client tries to lock more than maxImages buffers
10-01 16:23:41.710  1913  2202 E XRShell-Native: EventDispatcher , sourceType=4 , trigger=0.000000
10-01 16:23:41.710  1913  2199 I XRShell : EventDispatcher , disp2WinByUInput, displayId: 5 , Event{clientId=5, displayId=5, action=1, keyCode=0, buttonId=10004, deviceId=2, isMotion=true, x=1420.7037, y=522.6414, Window{Binder=0, mClientId=5, mDisplayId=5, mWindowType=3002, mWidth=1922, mHeight=1082, mNeedSendMove=false}}
10-01 16:23:41.768  1601  4998 W NdkImageReader: Unable to acquire a lockedBuffer, very likely client tries to lock more than maxImages buffers
10-01 16:23:42.051   910  2524 I JNI_PART: PC_Services: 20230404A _For_MerlinE
10-01 16:23:42.051   910  2524 I JNI_PART: SENSOR version:  SENSOR 0.0.0.0 082ecf78-dirty
10-01 16:23:42.051   910  2524 I JNI_PART: PCLog_IMU_Data_1: 56.631574,34.530000,0.017808,-0.026887,0.058880,7.372141,5.936721,-2.735471,9.852711
10-01 16:23:42.194   878   878 E [email protected]: cyx convertHidlNanEnableReque 308 contentavg=102
10-01 16:23:42.211   914  2495 I pxrhmdservice : Call <getCommonDataThread> - boot_completed=[1] ------------------------
10-01 16:23:42.419  2420  3700 I PxrMetric: FPS=72/72,MTP=26.53ms,AppSW=off,FrmEarly=0,FrmLate=0,FrmCpu=1.54ms,FrmGpu=4.56ms,FrmTime=6.57ms,ATWGPU=2.83ms,VsyncDelay=1,GPU=60%/441Mhz,GPUTemp=56.3C,LayerCnt=4,Pkg=com.pvr.vrshell,FrmId=2887
10-01 16:23:42.572  1337  1409 I UidCpuTracker: small cpu total = 6087 ; idle = 5115 ; ratio = 15 ; mCpuUsageRatio = 18
10-01 16:23:42.572  1337  1409 E PrefetchManagerService: onCpuState state = CPU_NORMAL
10-01 16:23:42.610  2420  2827 W APxrRuntime: PXRSDK_PM ENGINE FPS: 72, 53767208260
10-01 16:23:42.724  1913  2202 E XRShell-Native: EventDispatcher , sourceType=4 , trigger=1.000000
10-01 16:23:42.724  1913  2199 I XRShell : EventDispatcher , disp2WinByUInput, displayId: 5 , Event{clientId=5, displayId=5, action=0, keyCode=0, buttonId=10004, deviceId=2, isMotion=true, x=1433.9968, y=511.83087, Window{Binder=0, mClientId=5, mDisplayId=5, mWindowType=3002, mWidth=1922, mHeight=1082, mNeedSendMove=false}}
10-01 16:23:42.781  5000  5000 E libc    : Access denied finding property "libc.debug.gwp_asan.sample_rate.sleep"
10-01 16:23:42.781  5000  5000 E libc    : Access denied finding property "persist.libc.debug.gwp_asan.sample_rate.sleep"
10-01 16:23:42.775  5000  5000 W sleep   : type=1400 audit(0.0:5141): avc: denied { read } for name="u:object_r:default_prop:s0" dev="tmpfs" ino=22307 scontext=u:r:qti_init_shell:s0 tcontext=u:object_r:default_prop:s0 tclass=file permissive=0
10-01 16:23:42.781  5000  5000 E libc    : Access denied finding property "libc.debug.gwp_asan.sample_rate.system_default"
10-01 16:23:42.781  5000  5000 E libc    : Access denied finding property "persist.libc.debug.gwp_asan.sample_rate.system_default"
10-01 16:23:42.781  5000  5000 E libc    : Access denied finding property "libc.debug.gwp_asan.process_sampling.sleep"
10-01 16:23:42.775  5000  5000 W sleep   : type=1400 audit(0.0:5142): avc: denied { read } for name="u:object_r:default_prop:s0" dev="tmpfs" ino=22307 scontext=u:r:qti_init_shell:s0 tcontext=u:object_r:default_prop:s0 tclass=file permissive=0
10-01 16:23:42.781  5000  5000 E libc    : Access denied finding property "persist.libc.debug.gwp_asan.process_sampling.sleep"
10-01 16:23:42.781  5000  5000 E libc    : Access denied finding property "libc.debug.gwp_asan.process_sampling.system_default"
10-01 16:23:42.781  5000  5000 E libc    : Access denied finding property "persist.libc.debug.gwp_asan.process_sampling.system_default"
10-01 16:23:42.781  5000  5000 E libc    : Access denied finding property "libc.debug.gwp_asan.max_allocs.sleep"
10-01 16:23:42.775  5000  5000 W sleep   : type=1400 audit(0.0:5143): avc: denied { read } for name="u:object_r:default_prop:s0" dev="tmpfs" ino=22307 scontext=u:r:qti_init_shell:s0 tcontext=u:object_r:default_prop:s0 tclass=file permissive=0
10-01 16:23:42.781  5000  5000 E libc    : Access denied finding property "persist.libc.debug.gwp_asan.max_allocs.sleep"
10-01 16:23:42.781  5000  5000 E libc    : Access denied finding property "libc.debug.gwp_asan.max_allocs.system_default"
10-01 16:23:42.781  5000  5000 E libc    : Access denied finding property "persist.libc.debug.gwp_asan.max_allocs.system_default"
10-01 16:23:42.775  5000  5000 W sleep   : type=1400 audit(0.0:5144): avc: denied { read } for name="u:object_r:default_prop:s0" dev="tmpfs" ino=22307 scontext=u:r:qti_init_shell:s0 tcontext=u:object_r:default_prop:s0 tclass=file permissive=0
10-01 16:23:42.775  5000  5000 W sleep   : type=1400 audit(0.0:5145): avc: denied { read } for name="u:object_r:default_prop:s0" dev="tmpfs" ino=22307 scontext=u:r:qti_init_shell:s0 tcontext=u:object_r:default_prop:s0 tclass=file permissive=0
10-01 16:23:42.849  1913  2202 E XRShell-Native: EventDispatcher , sourceType=4 , trigger=0.000000
10-01 16:23:42.849  1913  2199 I XRShell : EventDispatcher , disp2WinByUInput, displayId: 5 , Event{clientId=5, displayId=5, action=1, keyCode=0, buttonId=10004, deviceId=2, isMotion=true, x=1404.9298, y=498.39566, Window{Binder=0, mClientId=5, mDisplayId=5, mWindowType=3002, mWidth=1922, mHeight=1082, mNeedSendMove=false}}
10-01 16:23:42.862  1601  4998 W NdkImageReader: Unable to acquire a lockedBuffer, very likely client tries to lock more than maxImages buffers
Oct 01 '25 14:10 Auroriax
Interesting issue this one.
It stems from the fact that under the hood the Android plugin runs a command queue to ensure ExoPlayer commands are executed on the correct thread (an absolute requirement). This causes a few issues that lead to the problem you are seeing.
The Pause() function checks to see if the player is playing. As the Play() call has not actually executed at plugin level yet, the player is not playing and therefore the call to pause is ignored. This is at C# level and can be adjusted as follows.
Great, now Pause is passed down to the plugin level for execution. However, we now see a different issue...
There are Update() calls inside the C# PlatformMediaPlayer.cs code when you call to Play/Pause/Seek. These are here to enact the commands as quickly as possible (not waiting for potentially the next frame update). However, these call the Play() to happen a millisecond or two before the Pause() call happens. This is enough for the audio (which is run on a much tighter timed thread at ADK/API level) to issue some data for audio-render...which you hear.
This can be overcome by removal of the two Update() calls in the Play/Pause C# functions...but the knock on effect is that you may end up with deferred calls.
Conclusion: We are not sure what form a fix for this will take just yet. Needs more thought. But you can make the above two changes in your project for now to address the issue. Do let us know how this works out for you. We will leave this issue open until we decide on what fix we will put in for this issue.
Nov 24 '25 18:11 Ste-RH