[Android] Ambisonic audio with high resolution video has audio distorted

[phoria-sam-tg]

Unity version

6.2.9f1

Unity editor platform

macOS

AVPro Video edition

Ultra

AVPro Video version

3.3.3

Device hardware

GalaxyXR

Which Android OS version are you using?

8

Unity Graphics API

Vulkan

Video API

MediaPlayer

Texture format

BGRA

Audio output

Facebook Audio 360

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

No response

Which output component(s) are you using?

Apply to Mesh

Any other component configuration required to reproduce the issue.

No response

The issue

Hi AVPro

I'm having an issue with playback that has occured after updates to unity 6.2, AVPro 3+ triggered by the 16kb updates required. I think I've followed along with the developments, initially dealing with crashes, trying fixes, now using 3.3.3

Where I've arrived is that now the video will play, but the audio comes through as a loud blaring distorted effect. The audio is actually 16 channel + 2, but previously was working with the 8 + 2 initial setting.

I've replicated this in two projects (though were both already active), and am using a few different video sizes, which makes me thing it is the ambisonics specifically.

I'm setting up a new project to try and replicate, but need to run on Galaxy XR (Android XR) headset, and might need to finish that test tomorrow.

So

• Was crashing on high res playback
• Updates, video plays now, Ambisonics (previously worked) sound distorted
• Worked through any fixes I could find, bit stumped

I'll follow up with test projects, but have replicated in these two, I'll test in Oculus also, however this is for the Galaxy.

Thanks!

Media information

Will email direct download links as quite large

Logcat output

Details

11-10 15:36:06.790 14413 14441 I Unity   : playerID: 0000CE32
11-10 15:36:06.790 14413 14441 I Unity   : UnityEngine.DebugLogHandler:Internal_Log(LogType, LogOption, String, Object)
11-10 15:36:06.790 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.PlatformMediaPlayer:CreateCommandBuffers()
11-10 15:36:06.790 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.PlatformMediaPlayer:.ctor(PlatformOptions)
11-10 15:36:06.790 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.MediaPlayer:CreateMediaPlayer(OptionsAndroid)
11-10 15:36:06.790 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.MediaPlayer:CreateMediaPlayer()
11-10 15:36:06.790 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.MediaPlayer:Initialise()
11-10 15:36:06.790 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.MediaPlayer:OpenMedia(Boolean)
11-10 15:36:06.790 14413 14441 I Unity   : VideoPlayerManager:Start()
11-10 15:36:06.790 14413 14441 I Unity   : 
11-10 15:36:06.790 14413 14441 I Unity   : CreateCommandBuffers - flags: 00000002
11-10 15:36:06.790 14413 14441 I Unity   : UnityEngine.DebugLogHandler:Internal_Log(LogType, LogOption, String, Object)
11-10 15:36:06.790 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.PlatformMediaPlayer:CreateCommandBuffers()
11-10 15:36:06.790 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.PlatformMediaPlayer:.ctor(PlatformOptions)
11-10 15:36:06.790 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.MediaPlayer:CreateMediaPlayer(OptionsAndroid)
11-10 15:36:06.790 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.MediaPlayer:CreateMediaPlayer()
11-10 15:36:06.790 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.MediaPlayer:Initialise()
11-10 15:36:06.790 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.MediaPlayer:OpenMedia(Boolean)
11-10 15:36:06.790 14413 14441 I Unity   : VideoPlayerManager:Start()
11-10 15:36:06.790 14413 14441 I Unity   : 
11-10 15:36:06.790 14413 14441 I Unity   : flags: 0002CE32
11-10 15:36:06.790 14413 14441 I Unity   : UnityEngine.DebugLogHandler:Internal_Log(LogType, LogOption, String, Object)
11-10 15:36:06.790 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.PlatformMediaPlayer:CreateCommandBuffers()
11-10 15:36:06.790 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.PlatformMediaPlayer:.ctor(PlatformOptions)
11-10 15:36:06.790 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.MediaPlayer:CreateMediaPlayer(OptionsAndroid)
11-10 15:36:06.790 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.MediaPlayer:CreateMediaPlayer()
11-10 15:36:06.790 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.MediaPlayer:Initialise()
11-10 15:36:06.790 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.MediaPlayer:OpenMedia(Boolean)
11-10 15:36:06.790 14413 14441 I Unity   : VideoPlayerManager:Start()
11-10 15:36:06.790 14413 14441 I Unity   : 
11-10 15:36:06.793 14413 14441 I Unity   : [AVProVideo] Initialising AVPro Video v3.3.3 (native plugin v3.3.3-ultra) on Adreno (TM) 740/Vulkan 1.1.0 [512.821.6 (0x80335006)] (MT True) on Android
11-10 15:36:06.793 14413 14441 I Unity   : UnityEngine.DebugLogHandler:Internal_Log(LogType, LogOption, String, Object)
11-10 15:36:06.793 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.MediaPlayer:Initialise()
11-10 15:36:06.793 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.MediaPlayer:OpenMedia(Boolean)
11-10 15:36:06.793 14413 14441 I Unity   : VideoPlayerManager:Start()
11-10 15:36:06.793 14413 14441 I Unity   : 
11-10 15:36:06.794 14413 14441 I Unity   : [AVProVideo] Opening /storage/emulated/0/Android/data/com.PHORIAStudios.XRVideoPlayer/files/Kenya.mkv (offset 0) with API ExoPlayer
11-10 15:36:06.794 14413 14441 I Unity   : UnityEngine.DebugLogHandler:Internal_Log(LogType, LogOption, String, Object)
11-10 15:36:06.794 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.MediaPlayer:InternalOpenMedia()
11-10 15:36:06.794 14413 14441 I Unity   : VideoPlayerManager:Start()
11-10 15:36:06.794 14413 14441 I Unity   : 
11-10 15:36:06.800 14413 14413 E TBAudioEngine: JavaVM is not set. Certain codecs on Android API < 21 might be unavailable.
11-10 15:36:06.800 14413 14413 I TBAudioEngine: Android try fast path? 0 
11-10 15:36:06.801 14413 14413 D com.PHORIAStudios.XRVideoPlayer: PlayerBase::PlayerBase()
11-10 15:36:06.801 14413 14413 D com.PHORIAStudios.XRVideoPlayer: TrackPlayerBase::TrackPlayerBase()
11-10 15:36:06.801 14413 14413 I libOpenSLES: Emulating old channel mask behavior (ignoring positional mask 0x3, using default mask 0x3 based on channel count of 2)
11-10 15:36:06.802  3228  3285 I CPMScene: Node queued for creation, id: 817, handle: 0xb40000713617d900, parentId: -1, clientId: -1
11-10 15:36:06.802 14413 14413 W AudioTrack: createTrack_l: CpmNodeInFocus not found. Setting spatialRequest.type as NONE
11-10 15:36:06.809  1213  4148 W native  : W0000 00:00:1762749366.809039    4148 broker_callback_state.cc:228] [CIB] [P-DepthRGB] Callback duration over limit: 5.1ms > 5.0ms. Start time: 1762749366803907643
11-10 15:36:06.809  1213  4148 W native  : W0000 00:00:1762749366.809146    4148 broker_callback_state.cc:228] [CIB] [DepthRGB] Callback duration over limit: 5.3ms > 5.0ms. Start time: 1762749366803874414
11-10 15:36:06.569  3755  3755 W cnss    : Increased QMI send usage count to 1
11-10 15:36:06.572  3755  3755 W cnss    : Decreased QMI send usage count to 0
11-10 15:36:06.809  1206  1243 D AHAL: FM: fm_get_parameters: 231: enter
11-10 15:36:06.809  1206  1243 D AHAL: FM: fm_get_parameters: 238: exit
11-10 15:36:06.810  1316 15501 D AudioFlinger: Client defaulted notificationFrames to 2565 for frameCount 5130
11-10 15:36:06.810  1588  1619 W FFSDevice: timed out while waiting for FUNCTIONFS_BIND, trying again
11-10 15:36:06.814  1206  1243 D AHAL: AudioStream: astream_out_get_parameters: 961: enter
11-10 15:36:06.814  1206  1243 D AHAL: AudioStream: astream_out_get_parameters: 983: keys: is_direct_pcm_track
11-10 15:36:06.814  1206  1243 D AHAL: AudioStream: astream_out_get_parameters: 1020: exit: returns - is_direct_pcm_track=false
11-10 15:36:06.815  3228  3680 I CPMScene: Node created, id: 817, handle: 0xb40000713617d900, parentId: -1, clientId: -1
11-10 15:36:06.817  3228  3680 I CPM     : Starting node subscription.
11-10 15:36:06.819  1213  2147 I native  : I0000 00:00:1762749366.818960    2147 landmark_triangulator_lite.cc:34] Received DSP feature measurements 3d.
11-10 15:36:06.820  1213  2700 I native  : I1110 15:36:06.820119    2700 calibration_service.cc:177] [CAL]: Requesting online camera calibration for sensor 34
11-10 15:36:06.820  1213  2700 I native  : I0000 00:00:1762749366.820334    2700 depth_calibration_manager.cc:471] [Depth] Load Camera Model from Opx at timestamp: 198135577725912, Camera Stream ID: rgb-left_curved, 1, Camera Model: kannala_brandt_fu_fv_u0_v0_k1_k2_k3_k4_curved_window, pinhole intrinsics: [1454, 1456, 1496, 1501], distortion params: [ -0.06351,   0.04122,  -0.01909, -0.002553], extrinsics: q = [x: -0.999864, y: -0.0140108, z: -0.00665069, w: 0.00552834], p = [x: 0.0298252, y: -0.0267669, z: -0.00303577]
11-10 15:36:06.820  1213  2700 I native  : I1110 15:36:06.820378    2700 calibration_service.cc:177] [CAL]: Requesting online camera calibration for sensor 35
11-10 15:36:06.820  1213  2700 I native  : I0000 00:00:1762749366.820492    2700 depth_calibration_manager.cc:471] [Depth] Load Camera Model from Opx at timestamp: 198135577725912, Camera Stream ID: rgb-right_curved, 1, Camera Model: kannala_brandt_fu_fv_u0_v0_k1_k2_k3_k4_curved_window, pinhole intrinsics: [1460, 1460, 1493, 1491], distortion params: [-0.06856,  0.06175, -0.04147, 0.005176], extrinsics: q = [x: 0.999927, y: 0.00842341, z: 0.00840373, w: 0.00205696], p = [x: 0.091449, y: -0.0249226, z: -0.00200308]
11-10 15:36:06.842 14413 14413 I TBAudioEngine: Initialised Audio360: v1.7.12-62c879690ee3 48000.0 1024. With FBA: 0 
11-10 15:36:06.843 14413 14413 I AVProVideo: Facebook360 audio: Engine latency = -42.666666666666664 | Passed in latency = 0 | Final latency set = -42.666666666666664
11-10 15:36:06.845 14413 14413 I ExoPlayerImpl: Init bc4ff57 [AndroidXMedia3/1.8.0] [xrdk2, SM-I610, samsung, 34]
11-10 15:36:06.851 14413 14413 I AVProVideo: 💬️ Player_ExoPlayer.CreateExoPlayer - CREATED new ExoPlayer object
11-10 15:36:06.855 14413 14413 I AVProVideo: Video Loading: TYPE_OTHER : No file offset | uri = /storage/emulated/0/Android/data/com.PHORIAStudios.XRVideoPlayer/files/Kenya.mkv
11-10 15:36:06.868 14413 14441 I Unity   : StartedBuffering
11-10 15:36:06.868 14413 14441 I Unity   : UnityEngine.DebugLogHandler:Internal_Log(LogType, LogOption, String, Object)
11-10 15:36:06.868 14413 14441 I Unity   : VideoPlayerManager:HandleEvent(MediaPlayer, EventType, ErrorCode)
11-10 15:36:06.868 14413 14441 I Unity   : UnityEngine.Events.UnityEvent`3:Invoke(T0, T1, T2)
11-10 15:36:06.868 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.MediaPlayer:FireEventIfPossible(EventType, Boolean)
11-10 15:36:06.868 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.MediaPlayer:HandleRecurringEvents()
11-10 15:36:06.868 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.MediaPlayer:Update()
11-10 15:36:06.868 14413 14441 I Unity   : 
11-10 15:36:06.628 14130 14130 W cnss    : Bandwidth vote to 2, save 1
11-10 15:36:06.871 14413 14941 I DrmHalHidl: found instance=wfdhdcp version=android.hardware.drm@1.3::IDrmFactory
11-10 15:36:06.871 14413 14941 I DrmHalHidl: found instance=widevine version=android.hardware.drm@1.4::IDrmFactory
11-10 15:36:06.874 14413 14941 I DrmUtils: found IDrmFactory. Instance name:[android.hardware.drm.IDrmFactory/clearkey]
11-10 15:36:06.874  1213  4139 I native  : I0000 00:00:1762749366.874924    4139 client_state.cc:85] [CIB] 'P-EyeT' FPS: 29.9351
11-10 15:36:06.875  1209  1209 E clearkey-DrmFactory: Clearkey Drm HAL: failed to create drm plugin, invalid crypto scheme
11-10 15:36:06.875 14413 14941 E DrmHalAidl: uuid=[edef8ba979d64ace a3c827dcd51d21ed] Failed to make drm plugin: 6
11-10 15:36:06.875 14413 14941 E DrmHalHidl: uuid=[edef8ba979d64ace a3c827dcd51d21ed] No supported hal instance found
11-10 15:36:06.875  1210  1242 I WVCdm   : [app][com.PHORIAStudios.XRVideoPlayer] calling virtual Return<void> wvdrm::hardware::drm::V1_4::widevine::WVDrmFactory::createPlugin(const hidl_array<uint8_t, 16> &, const hidl_string &, createPlugin_cb)
11-10 15:36:06.875  1210  1242 D WVCdm   : Instantiating CDM.
11-10 15:36:06.877  1210  1242 D WVCdm   : [cdm_engine.cpp(555):QueryStatus] security_level = Default, query_token = WidevineCdmVersion
11-10 15:36:06.877  1210  1242 I WVCdm   : [file_utils.cpp(40):Exists] stat failed: ENOENT
11-10 15:36:06.877  1210  1242 I WVCdm   : [oemcrypto_adapter_dynamic.cpp(783):Initialize] Level 3 Build Info (v16): OEMCrypto Level3 Code 28917 Feb  2 2023 02:22:33
11-10 15:36:06.877  1210 14944 I WVCdm   : [(0):] Level3 Library 28917 Feb  2 2023 02:22:33
11-10 15:36:06.880  1210  1242 I WVCdm   : [oemcrypto_adapter_dynamic.cpp(797):Initialize] L3 Initialized. Trying L1.
11-10 15:36:06.880  1210  1242 I QCOM-OEMCrypto: OEMCrypto_Initialize
11-10 15:36:06.880  1210  1242 I QCOM-OEMCrypto: License: Widevine: YES CPZ: YES
11-10 15:36:06.902  1210  1242 I QCOM-OEMCrypto: ---------------Widevine OEMCrypto configuration-----------------
11-10 15:36:06.902  1210  1242 I QCOM-OEMCrypto: TA-HLOS interface                      :   SMCInvoke
11-10 15:36:06.902  1210  1242 I QCOM-OEMCrypto: Crypto Engine SMMU Virtual Address     :   Yes
11-10 15:36:06.902  1210  1242 I QCOM-OEMCrypto: Measure elapsed time                   :   No
11-10 15:36:06.902  1210  1242 I QCOM-OEMCrypto: Inplace decryption for clear buffer    :   Yes
11-10 15:36:06.902  1210  1242 I QCOM-OEMCrypto: Frame Assembly                         :   Yes
11-10 15:36:06.902  1210  1242 I QCOM-OEMCrypto: Scratch buffer size                    :   4194304
11-10 15:36:06.902  1210  1242 I QCOM-OEMCrypto: Decrypt hash tests                     :   No
11-10 15:36:06.902  1210  1242 I QCOM-OEMCrypto: Secure buffer tests                    :   No
11-10 15:36:06.902  1210  1242 I QCOM-OEMCrypto: Idle mode enabled for external display :   No
11-10 15:36:06.902  1210  1242 I QCOM-OEMCrypto: HLOS data path                       :   No
11-10 15:36:06.902  1210  1242 I QCOM-OEMCrypto: ----------------------------------------------------------------
11-10 15:36:06.904   859   870 D DrmLibTime: Time dispatch starts
11-10 15:36:06.904   859   870 D DrmLibTime: command id, time_cmd_id = 770
11-10 15:36:06.904   859   870 D DrmLibTime: time_getutcsec starts!
11-10 15:36:06.904   859   870 D DrmLibTime: QSEE Time Listener: time_getutcsec
11-10 15:36:06.904   859   870 D DrmLibTime: QSEE Time Listener: get_utc_seconds
11-10 15:36:06.904   859   870 D DrmLibTime: QSEE Time Listener: time_get_modem_time
11-10 15:36:06.904   859   870 D DrmLibTime: QSEE Time Listener: Checking if ATS_MODEM is set or not.
11-10 15:36:06.904   859   870 D QC-time-services: Lib:time_genoff_operation: pargs->base = 13
11-10 15:36:06.904   859   870 D QC-time-services: Lib:time_genoff_operation: pargs->operation = 2
11-10 15:36:06.904   859   870 D QC-time-services: Lib:time_genoff_operation: pargs->ts_val = 0
11-10 15:36:06.904   859   870 D QC-time-services: Lib:time_genoff_operation: Send to server  passed!!
11-10 15:36:06.905  1112  1125 D QC-time-services: Daemon: Connection accepted:time_genoff
11-10 15:36:06.905  1112 14945 D QC-time-services: Daemon:Received base = 13, unit = 1, operation = 2,value = 0
11-10 15:36:06.905  1112 14945 D QC-time-services: Daemon:genoff_opr: Base = 13, val = 0, operation = 2
11-10 15:36:06.905  1112 14945 D QC-time-services: offset is: 0 for base: 13
11-10 15:36:06.905   859   870 E QC-time-services: Receive Passed == base = 13, unit = 1, operation = 2, result = 0
11-10 15:36:06.905   859   870 D DrmLibTime: QSEE Time Listener: ATS_MODEM is not set. Fallback to Android system time.
11-10 15:36:06.905   859   870 D DrmLibTime: QSEE Time Listener: Retrieved Android system time: 1762749366
11-10 15:36:06.905  1112  1125 E QC-time-services: Daemon: Time-services: Waiting to acceptconnection
11-10 15:36:06.905   859   870 D DrmLibTime: time_getutcsec returns 0, sec = 1762749366; nsec = 0
11-10 15:36:06.905   859   870 D DrmLibTime: time_getutcsec finished! 
11-10 15:36:06.905   859   870 D DrmLibTime: time_services Dispatch ends and ret = 0! 
11-10 15:36:06.916  1213  2147 I native  : I0000 00:00:1762749366.916715    2147 landmark_triangulator_lite.cc:34] Received DSP feature measurements 3d.
11-10 15:36:06.926  1210  1242 I DMABUFHEAPS: Using DMA-BUF heap named: qcom,qseecom
11-10 15:36:06.933  1213  4264 I native  : I0000 00:00:1762749366.933015    4264 qvr_stream.cc:420] [Live DS] GetFrame for camera face-tracking succeeds, acquired 60 frames in time 1.000060833s, with frame disabled: true, and resolution: 0x0
11-10 15:36:06.934  1210  1242 I DMABUFHEAPS: Using DMA-BUF heap named: qcom,qseecom
11-10 15:36:06.697  1242  1242 I qcedev_ioctl: info: vaddr = ffa00000
11-10 15:36:06.697  1242  1242 I         : , fd = 14
11-10 15:36:06.942  1210  1242 I DMABUFHEAPS: Using DMA-BUF heap named: qcom,qseecom
11-10 15:36:06.705  1242  1242 I qcedev_ioctl: info: vaddr = ff600000
11-10 15:36:06.705  1242  1242 I         : , fd = 16
11-10 15:36:06.712  1242  1242 I qcedev_ioctl: info: vaddr = ff200000
11-10 15:36:06.712  1242  1242 I         : , fd = 18
11-10 15:36:06.949  1210  1242 I QCOM-OEMCrypto: Allocated all scratch buffers
11-10 15:36:06.949  1210  1242 I QCOM-OEMCrypto: LibOEMCrypto version 1.67
11-10 15:36:06.949  1210  1242 I QCOM-OEMCrypto: TA   version 1.181
11-10 15:36:06.949  1210  1242 I QCOM-OEMCrypto: Api  version 16.4
11-10 15:36:06.950  1210  1242 W WVCdm   : [oemcrypto_adapter_dynamic.cpp(999):LoadLevel1] Could not load L1 _oecc109.
11-10 15:36:06.712 13422 13422 I ts_print: tsens[0:679][1:706][2:706][3:689][4:703][5:692][6:699][7:696][8:692][9:696][10:696][11:713][12:706][13:686][14:692][15:673][16:673][17:666][18:663][19:656][20:653][21:653][22:653][23:653][24:653][25:656][26:669][27:666][28:653][29:656]
11-10 15:36:06.950  1210  1242 W WVCdm   : [oemcrypto_adapter_dynamic.cpp(1000):LoadLevel1] Could not load L1 _oecc110.
11-10 15:36:06.950  1210  1242 W WVCdm   : [oemcrypto_adapter_dynamic.cpp(1004):LoadLevel1] Could not load L1 _oecc113.
11-10 15:36:06.950  1210  1242 W WVCdm   : [oemcrypto_adapter_dynamic.cpp(1005):LoadLevel1] Could not load L1 _oecc114.
11-10 15:36:06.950  1210  1242 D WVCdm   : [oemcrypto_adapter_dynamic.cpp(816):Initialize] OEMCrypto_Initialize Level 1 success. I will use level 1.
11-10 15:36:06.950  1210  1242 I QCOM-OEMCrypto: Created OEMCrypto idle_thread
11-10 15:36:06.950  1210  1242 I WVCdm   : [oemcrypto_adapter_dynamic.cpp(819):Initialize] Level 1 Build Info (v16): Build Information: API_Version: 16.4 LibOEMCrypto_Version: 1.67 TA_Version: 1.181
11-10 15:36:06.951  1210  1242 I WVCdm   : [file_utils.cpp(40):Exists] stat failed: ENOENT
11-10 15:36:06.951  1210  1242 D WVCdm   : [crypto_session.cpp(398):CacheVersion] OEMCrypto version (default security level): 16.4
11-10 15:36:06.951  1210  1242 D WVCdm   : [crypto_session.cpp(407):CacheVersion] OEMCrypto version (L3 security level): 16.3
11-10 15:36:06.728 14130 14130 W cnss    : Bandwidth vote to 3, save 1
11-10 15:36:06.977  1213 14545 I native  : I0000 00:00:1762749366.977448   14545 qvr_stream.cc:420] [Live DS] GetFrame for camera d-depth succeeds, acquired 10 frames in time 1.000581041s, with frame disabled: false, and resolution: 24x120
11-10 15:36:06.982  1213  4132 I native  : I0000 00:00:1762749366.981932    4132 client_state.cc:85] [CIB] 'P-HT' FPS: 599.459
11-10 15:36:06.989 14413 14941 W AudioCapabilities: Unsupported mime audio/alac
11-10 15:36:06.989 14413 14941 W AudioCapabilities: Unsupported mime audio/x-ape
11-10 15:36:06.989 14413 14941 W AudioCapabilities: Unsupported mime audio/dsd
11-10 15:36:06.989 14413 14941 W AudioCapabilities: Unsupported mime audio/amr-wb-plus
11-10 15:36:06.989 14413 14941 W AudioCapabilities: Unsupported mime audio/amr-wb+
11-10 15:36:06.990 14413 14941 W AudioCapabilities: Unsupported mime audio/x-ms-wma
11-10 15:36:06.990 14413 14941 W AudioCapabilities: Unsupported mime audio/alac
11-10 15:36:06.990 14413 14941 W AudioCapabilities: Unsupported mime audio/x-ape
11-10 15:36:06.995 14413 14941 W VideoCapabilities: Unsupported mime image/vnd.android.heic
11-10 15:36:06.995 14413 14941 W VideoCapabilities: Unsupported mime image/vnd.android.heic
11-10 15:36:07.002  1950  1999 I AS.SpatializerHelper: getCapableImmersiveAudioLevel:1
11-10 15:36:07.003  1950  1999 I AS.SpatializerHelper: isAvailable:1
11-10 15:36:07.003  1950  1999 I AS.SpatializerHelper: isEnabled:true
11-10 15:36:07.004  1950  1999 I AS.SpatializerHelper: canBeSpatialized usage:1 format:10ch 48000Hz ENCODING_PCM_16BIT returning true
11-10 15:36:07.008 14413 14948 I AdrenoGLES-0: QUALCOMM build                   : 1cf4b54f47, I47c84a613a
11-10 15:36:07.008 14413 14948 I AdrenoGLES-0: Build Date                       : 08/25/25
11-10 15:36:07.008 14413 14948 I AdrenoGLES-0: OpenGL ES Shader Compiler Version: E031.50.02.00
11-10 15:36:07.008 14413 14948 I AdrenoGLES-0: Local Branch                     : 
11-10 15:36:07.008 14413 14948 I AdrenoGLES-0: Remote Branch                    : refs/tags/AU_LINUX_ANDROID_LA.VENDOR.14.3.4.11.00.00.1126.555
11-10 15:36:07.008 14413 14948 I AdrenoGLES-0: Remote Branch                    : NONE
11-10 15:36:07.008 14413 14948 I AdrenoGLES-0: Reconstruct Branch               : NOTHING
11-10 15:36:07.008 14413 14948 I AdrenoGLES-0: Build Config                     : S P 16.1.2 AArch64
11-10 15:36:07.008 14413 14948 I AdrenoGLES-0: Driver Path                      : /vendor/lib64/egl/libGLESv2_adreno.so
11-10 15:36:07.008 14413 14948 I AdrenoGLES-0: Driver Version                   : 0821.0.6
11-10 15:36:07.008 14413 14948 I AdrenoGLES-0: Process Name                     : com.PHORIAStudios.XRVideoPlayer
11-10 15:36:07.008 14413 14441 I Unity   : MetaDataReady
11-10 15:36:07.008 14413 14441 I Unity   : UnityEngine.DebugLogHandler:Internal_Log(LogType, LogOption, String, Object)
11-10 15:36:07.008 14413 14441 I Unity   : VideoPlayerManager:HandleEvent(MediaPlayer, EventType, ErrorCode)
11-10 15:36:07.008 14413 14441 I Unity   : UnityEngine.Events.UnityEvent`3:Invoke(T0, T1, T2)
11-10 15:36:07.008 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.MediaPlayer:FireEventIfPossible(EventType, Boolean)
11-10 15:36:07.008 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.MediaPlayer:HandleOneShotEvents()
11-10 15:36:07.008 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.MediaPlayer:UpdateEvents()
11-10 15:36:07.008 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.MediaPlayer:Update()
11-10 15:36:07.008 14413 14441 I Unity   : 
11-10 15:36:07.013 14413 14948 I AdrenoGLES-0: PFP: 0x01511181, ME: 0x00000000
11-10 15:36:07.023 14413 14948 W libc    : Access denied finding property "vendor.display.enable_optimal_refresh_rate"
11-10 15:36:07.021 14413 14413 W ExoPlayer:Place: type=1400 audit(0.0:24729): avc:  denied  { read } for  name="u:object_r:vendor_display_prop:s0" dev="tmpfs" ino=444 scontext=u:r:untrusted_app:s0:c235,c256,c512,c768 tcontext=u:object_r:vendor_display_prop:s0 tclass=file permissive=0 app=com.PHORIAStudios.XRVideoPlayer
11-10 15:36:07.024 14413 14441 I Unity   : ResolutionChanged
11-10 15:36:07.024 14413 14441 I Unity   : UnityEngine.DebugLogHandler:Internal_Log(LogType, LogOption, String, Object)
11-10 15:36:07.024 14413 14441 I Unity   : VideoPlayerManager:HandleEvent(MediaPlayer, EventType, ErrorCode)
11-10 15:36:07.024 14413 14441 I Unity   : UnityEngine.Events.UnityEvent`3:Invoke(T0, T1, T2)
11-10 15:36:07.024 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.MediaPlayer:FireEventIfPossible(EventType, Boolean)
11-10 15:36:07.024 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.MediaPlayer:HandleRecurringEvents()
11-10 15:36:07.024 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.MediaPlayer:Update()
11-10 15:36:07.024 14413 14441 I Unity   : 
11-10 15:36:07.027  1213  2147 I native  : I0000 00:00:1762749367.027048    2147 landmark_triangulator_lite.cc:34] Received DSP feature measurements 3d.
11-10 15:36:07.029 14413 14950 D CCodec  : allocate(c2.qti.vp9.decoder)
11-10 15:36:07.033 14413 14950 I Codec2Client: Available Codec2 services: "default" "default2" "default9" "software"
11-10 15:36:07.034 14413 14950 I CCodec  : setting up 'default' as default (vendor) store
11-10 15:36:07.035  1259  1259 I QC2Registry: search [c2.qti.vp9.decoder] in mEntries
11-10 15:36:07.035  1259  1259 I QC2Comp : [c2.qti.vp9.decoder] PreProcessingPipelining 0
11-10 15:36:07.035  1259  1259 I QC2Registry: search [c2.qti.vp9.decoder] in mEntries
11-10 15:36:07.035  1259  1259 W QC2Comp : [c2.qti.vp9.decoder] mismatched libqcodec2_core.so. Actual: Wed Mar 12 08:17:03 2025 -0700 vs Expected: Mon Mar 31 23:17:19 2025 -0700
11-10 15:36:07.035  1259  1259 W QC2Comp : [c2.qti.vp9.decoder] mismatched libqcodec2_utils.so. Actual: Wed Mar 12 08:17:03 2025 -0700 vs Expected: Mon Mar 31 23:17:19 2025 -0700
11-10 15:36:07.035  1259  1259 E vendor.qti.media.c2@1.0-service: Missing dependency coding.temporal-layering
11-10 15:36:07.036  1259  1259 I vendor.qti.media.c2@1.0-service: missing struct descriptor #Param::CoreIndex(--002) for field mastering of struct #Param::CoreIndex(--180a)
11-10 15:36:07.036  1259  1259 I QC2Registry: search [c2.qti.vp9.decoder] in mEntries
11-10 15:36:07.036  1259  1259 I QC2Comp : Create: Allocated component[58] for name c2.qti.vp9.decoder : [64 bit]
11-10 15:36:07.036  1259  1259 I QC2Comp : Create Latency:1.46 ms
11-10 15:36:07.036  1259  1259 I QC2CompStore: Created component(c2.qti.vp9.decoder) id(58)
11-10 15:36:07.039 14413 14950 I CCodec  : Created component [c2.qti.vp9.decoder]
11-10 15:36:07.040 14413 14950 D CCodecConfig: read media type: video/x-vnd.on2.vp9
11-10 15:36:07.042 14413 14950 D ReflectedParamUpdater: extent() != 1 for single value type: output.subscribed-indices.values
11-10 15:36:07.043  1213  4139 I native  : I0000 00:00:1762749367.042997    4139 client_state.cc:85] [CIB] 'EyeT' FPS: 119.916
11-10 15:36:07.044 14413 14950 D ReflectedParamUpdater: extent() != 1 for single value type: vendor.qti-ext-transcode-info-stats.stats_info
11-10 15:36:07.044 14413 14950 D ReflectedParamUpdater: extent() != 1 for single value type: output.buffers.pool-ids.values
11-10 15:36:07.044 14413 14950 D CCodecConfig: ignoring local param raw.size (0xd2001800) as it is already supported
11-10 15:36:07.044 14413 14950 D CCodecConfig: ignoring local param default.color (0x5200180b) as it is already supported
11-10 15:36:07.044 14413 14950 D CCodecConfig: ignoring local param raw.hdr-static-info (0xd200180a) as it is already supported
11-10 15:36:07.045 14413 14950 I CCodecConfig: query failed after returning 19 values (BAD_INDEX)
11-10 15:36:07.045 14413 14950 D CCodecConfig: c2 config diff is Dict {
11-10 15:36:07.045 14413 14950 D CCodecConfig:   c2::i32 algo.priority.value = -1
11-10 15:36:07.045 14413 14950 D CCodecConfig:   c2::float algo.rate.value = 30
11-10 15:36:07.045 14413 14950 D CCodecConfig:   c2::u32 algo.secure-mode.value = 0
11-10 15:36:07.045 14413 14950 D CCodecConfig:   c2::float coded.frame-rate.value = 30
11-10 15:36:07.045 14413 14950 D CCodecConfig:   c2::u32 coded.pl.level = 0
11-10 15:36:07.045 14413 14950 D CCodecConfig:   c2::u32 coded.pl.profile = 28672
11-10 15:36:07.045 14413 14950 D CCodecConfig:   c2::u32 coded.vui.color.matrix = 255
11-10 15:36:07.045 14413 14950 D CCodecConfig:   c2::u32 coded.vui.color.primaries = 0
11-10 15:36:07.045 14413 14950 D CCodecConfig:   c2::u32 coded.vui.color.range = 0
11-10 15:36:07.045 14413 14950 D CCodecConfig:   c2::u32 coded.vui.color.transfer = 255
11-10 15:36:07.045 14413 14950 D CCodecConfig:   c2::u32 default.color.matrix = 0
11-10 15:36:07.045 14413 14950 D CCodecConfig:   c2::u32 default.color.primaries = 0
11-10 15:36:07.045 14413 14950 D CCodecConfig:   c2::u32 default.color.range = 0
11-10 15:36:07.045 14413 14950 D CCodecConfig:   c2::u32 default.color.transfer = 0
11-10 15:36:07.045 14413 14950 D CCodecConfig:   c2::u32 input.buffers.max-size.value = 17694720
11-10 15:36:07.045 14413 14950 D CCodecConfig:   c2::u32 input.delay.value = 0
11-10 15:36:07.045 14413 14950 D CCodecConfig:   Buffer input.hdr10-plus-info.value = {
11-10 15:36:07.045 14413 14950 D CCodecConfig:     00000000:  00                                                .
11-10 15:36:07.045 14413 14950 D CCodecConfig:   }
11-10 15:36:07.045 14413 14950 D CCodecConfig:   string input.media-type.value = "video/x-vnd.on2.vp9"
11-10 15:36:07.045 14413 14950 D CCodecConfig:   c2::u32 output.delay.value = 0
11-10 15:36:07.045 14413 14950 D CCodecConfig:   Buffer output.hdr10-plus-info.value = {
11-10 15:36:07.045 14413 14950 D CCodecConfig:   }
11-10 15:36:07.045 14413 14950 D CCodecConfig:   string output.media-type.value = "video/raw"
11-10 15:36:07.045 14413 14950 D CCodecConfig:   c2::u32 raw.color.matrix = 255
11-10 15:36:07.045 14413 14950 D CCodecConfig:   c2::u32 raw.color.primaries = 0
11-10 15:36:07.045 14413 14950 D CCodecConfig:   c2::u32 raw.color.range = 0
11-10 15:36:07.045 14413 14950 D CCodecConfig:   c2::u32 raw.co
11-10 15:36:07.045 14413 14950 W ColorUtils: expected specified color aspects (0:0:255:255)
11-10 15:36:07.048 14413 14941 W libc    : Access denied finding property "vendor.display.enable_optimal_refresh_rate"
11-10 15:36:07.048 14413 14949 D MediaCodec: flushMediametrics
11-10 15:36:07.048 14413 14949 D SurfaceUtils: connecting to surface 0xb400006ff6027a60, reason connectToSurface
11-10 15:36:07.048 14413 14949 I MediaCodec: [c2.qti.vp9.decoder] setting surface generation to 14758913
11-10 15:36:07.048 14413 14949 D SurfaceUtils: disconnecting from surface 0xb400006ff6027a60, reason connectToSurface(reconnect)
11-10 15:36:07.048 14413 14949 D SurfaceUtils: connecting to surface 0xb400006ff6027a60, reason connectToSurface(reconnect)
11-10 15:36:07.048 14413 14950 D CCodec  : [c2.qti.vp9.decoder] buffers are bound to CCodec for this session
11-10 15:36:07.048 14413 14950 D CCodecConfig: no c2 equivalents for log-session-id
11-10 15:36:07.048 14413 14950 D CCodecConfig: no c2 equivalents for native-window
11-10 15:36:07.048 14413 14950 D CCodecConfig: no c2 equivalents for flags
11-10 15:36:07.049 14413 14950 D CCodecConfig: c2 config diff is   c2::i32 algo.priority.value = 0
11-10 15:36:07.049 14413 14950 D CCodecConfig:   c2::u32 input.buffers.max-size.value = 4747264
11-10 15:36:07.049 14413 14950 D CCodecConfig:   c2::u32 raw.max-size.height = 2250
11-10 15:36:07.049 14413 14950 D CCodecConfig:   c2::u32 raw.max-size.width = 4500
11-10 15:36:07.049 14413 14950 D CCodecConfig:   c2::u32 raw.size.height = 2250
11-10 15:36:07.049 14413 14950 D CCodecConfig:   c2::u32 raw.size.width = 4500
11-10 15:36:07.049 14413 14950 W Codec2Client: query -- param skipped: index = 1107298332.
11-10 15:36:07.049 14413 14950 D CCodec  : client requested max input size 3796875, which is smaller than what component recommended (4747264); overriding with component recommendation.
11-10 15:36:07.049 14413 14950 W CCodec  : This behavior is subject to change. It is recommended that app developers double check whether the requested max input size is in reasonable range.
11-10 15:36:07.049 14413 14950 D CCodec  : encoding statistics level = 0
11-10 15:36:07.050 14413 14950 D CCodec  : setup formats input: AMessage(what = 0x00000000) = {
11-10 15:36:07.050 14413 14950 D CCodec  :   int32_t feature-secure-playback = 0
11-10 15:36:07.050 14413 14950 D CCodec  :   int32_t frame-rate = 30
11-10 15:36:07.050 14413 14950 D CCodec  :   int32_t height = 2250
11-10 15:36:07.050 14413 14950 D CCodec  :   int32_t max-input-size = 4747264
11-10 15:36:07.050 14413 14950 D CCodec  :   string mime = "video/x-vnd.on2.vp9"
11-10 15:36:07.050 14413 14950 D CCodec  :   int32_t priority = 0
11-10 15:36:07.050 14413 14950 D CCodec  :   int32_t profile = 1
11-10 15:36:07.050 14413 14950 D CCodec  :   int32_t width = 4500
11-10 15:36:07.050 14413 14950 D CCodec  :   Rect crop(0, 0, 4499, 2249)
11-10 15:36:07.050 14413 14950 D CCodec  : }
11-10 15:36:07.050 14413 14950 D CCodec  : setup formats output: AMessage(what = 0x00000000) = {
11-10 15:36:07.050 14413 14950 D CCodec  :   int32_t android._color-format = 0
11-10 15:36:07.050 14413 14950 D CCodec  :   int32_t android._video-scaling = 1
11-10 15:36:07.050 14413 14950 D CCodec  :   int32_t android._dataspace = 281411584
11-10 15:36:07.050 14413 14950 D CCodec  :   int32_t color-standard = 130822
11-10 15:36:07.050 14413 14950 D CCodec  :   int32_t color-range = 2
11-10 15:36:07.050 14413 14950 D CCodec  :   int32_t color-transfer = 65791
11-10 15:36:07.050 14413 14950 D CCodec  :   int32_t sar-height = 1
11-10 15:36:07.050 14413 14950 D CCodec  :   int32_t rotation-degrees = 0
11-10 15:36:07.050 14413 14950 D CCodec  :   Buffer hdr-static-info = {
11-10 15:36:07.050 14413 14950 D CCodec  :     00000000:  00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ................
11-10 15:36:07.050 14413 14950 D CCodec  :     00000010:  00 00 00 00 00 00 00 00  00                       .........
11-10 15:36:07.050 14413 14950 D CCodec  :   }
11-10 15:36:07.050 14413 14950 D CCodec  :   int32_t sar-width = 1
11-10 15:36:07.050 14413 14950 D CCodec  :   Rect crop(0, 0, 4499, 2249)
11-10 15:36:07.050 14413 14950 D CCodec  :   int32_t width = 4500
11-10 15:36:07.050 14413 14950 D CCodec  :   int32_t feature-secure-playback = 0
11-10 15:36:07.050 14413 14950 D CCodec  :   int32_t frame-rate = 30
11-10 15:36:07.050 14413 14950 D CCodec  :   Buffer hdr10-plus-info = {
11-10 15:36:07.050 14413 14950 D CCodec  :   }
11-10 15:36:07.050 14413 14950 D CCodec  :   int32_t height = 2250
11-10 15:36:07.050 14413 14950 D CCodec  :   int32_t max-height = 2250
11-10 15:36:07.050 14413 14950 D CCodec  :   int32_t max-width = 4500
11-10 15:36:07.050 14413 14950 D CCodec  :   string mime = "video/raw"
11-10 15:36:07.050 14413 14950 D CCodec  :   int32_t priority = 0
11-10 15:36:07.050 14413 14950 D CCodec  :   int32_t color-format = 2130708361
11-10 15:36:07.050 14413 14950 D CCodec  : }
11-10 15:36:07.050 14413 14950 I CCodecConfig: query failed after returning 19 values (BAD_INDEX)
11-10 15:36:07.050  1259 14954 I QC2Registry: search [c2.qti.vp9.decoder] in mEntries
11-10 15:36:07.050  1259 14954 I QC2Registry: Build pipelined codec for session
11-10 15:36:07.050  1259 14954 I QC2Registry: Getting stages for pipelined codec
11-10 15:36:07.050  1259 14954 I QC2Registry: search [c2.qti.vp9.decoder] in mEntries
11-10 15:36:07.050  1259 14954 W QC2Registry: mismatched libqcodec2_basecodec.so Actual: Wed Mar 12 08:17:03 2025 -0700 vs Expected: Mon Mar 31 23:17:19 2025 -0700
11-10 15:36:07.050  1259 14954 I QC2Registry: search [c2.qti.vp9.decoder] in mFactories
11-10 15:36:07.050  1259 14954 W QC2V4l2Codec: [vp9D_58] mismatched libqcodec2_v4l2codec.so Actual: Wed Mar 12 08:17:03 2025 -0700 vs Expected: Mon Mar 31 23:17:19 2025 -0700
11-10 15:36:07.050  1259 14954 I QC2Registry: Pipelining not enabled
11-10 15:36:07.050  1259 14954 I QC2Registry: Doesn't support pipelining. Create standalone codec
11-10 15:36:07.050  1259 14954 I QC2Registry: search [c2.qti.vp9.decoder] in mEntries
11-10 15:36:07.051  1259 14954 I QC2V4l2Driver: [vp9D_58] Device /dev/video32 opened with fd: 17
11-10 15:36:07.045 14413 14413 W ExoPlayer:Playb: type=1400 audit(0.0:24730): avc:  denied  { read } for  name="u:object_r:vendor_display_prop:s0" dev="tmpfs" ino=444 scontext=u:r:untrusted_app:s0:c235,c256,c512,c768 tcontext=u:object_r:vendor_display_prop:s0 tclass=file permissive=0 app=com.PHORIAStudios.XRVideoPlayer
11-10 15:36:06.815   685   685 I msm_vidc: fw: <VFW_E:HostDr:vp9d:e381afc0:01> VenusHostDriver_ParseC2SysPacket(894): QC_IMAGE_VERSION_STRING=video-firmware.3.3-c2a2db72a68525d32eeb935ec8b186519c744a93
11-10 15:36:06.815   685   685 I msm_vidc: fw: <VFW_E:HostDr:vp9d:e381afc0:01> VenusHostDriver_ParseC2SysPacket(895): IMAGE_VARIANT_STRING=PROD
11-10 15:36:06.815   685   685 I msm_vidc: fw: <VFW_E:HostDr:vp9d:e381afc0:01> VenusHostDriver_ParseC2SysPacket(896): OEM_IMAGE_VERSION_STRING=hyd-lablnx446
11-10 15:36:06.815   685   685 I msm_vidc: fw: <VFW_E:HostDr:vp9d:e381afc0:01> VenusHostDriver_ParseC2SysPacket(897): BUILD_TIME: Jun 19 2025 10:54:49
11-10 15:36:07.052  1259 14954 E QC2V4l2Codec: [vp9D_58]  ? is not a supported pixel format!
11-10 15:36:07.052  1259 14954 W QC2V4l2Codec: [vp9D_58] unknown/unsupported param raw.max-size index = 0x52002003
11-10 15:36:07.052  1259 14954 W QC2V4l2Codec: [vp9D_58] unknown/unsupported param input.buffers.max-size index = 0xC200081E
11-10 15:36:07.052  1259 14954 E QC2V4l2Decoder: [vp9D_58] failed to read private_transcode_metadata_stat value
11-10 15:36:07.053  1259 14954 D BufferPoolAccessor2.0: bufferpool2 0xb400007ee2fa35e8 : 20(327680 size) total buffers - 20(327680 size) used buffers - 0/20 (recycle/alloc) - 0/0 (fetch/transfer)
11-10 15:36:07.054 14413 14950 W Codec2Client: query -- param skipped: index = 1342179345.
11-10 15:36:07.054 14413 14950 W Codec2Client: query -- param skipped: index = 2415921170.
11-10 15:36:07.054 14413 14950 W Codec2Client: query -- param skipped: index = 1610614798.
11-10 15:36:07.055 14413 14950 D CCodecBufferChannel: [c2.qti.vp9.decoder#424] Query input allocators returned 0 params => BAD_INDEX (6)
11-10 15:36:07.055 14413 14950 D C2Store : debug.c2.use_dmabufheaps set, forcing DMABUF Heaps
11-10 15:36:07.055 14413 14950 D C2Store : Using DMABUF Heaps
11-10 15:36:07.055 14413 14950 D CCodecBufferChannel: [c2.qti.vp9.decoder#424] Created input block pool with allocatorID 16 => poolID 17 - OK (0)
11-10 15:36:07.056 14413 14950 D CCodecBufferChannel: [c2.qti.vp9.decoder#424] start: max output delay 11
11-10 15:36:07.056 14413 14950 D CCodecBufferChannel: [c2.qti.vp9.decoder#424] Query output allocators returned 0 params => BAD_INDEX (6)
11-10 15:36:07.056 14413 14950 D CCodecBufferChannel: [c2.qti.vp9.decoder#424] Query output surface allocator returned 0 params => BAD_INDEX (6)
11-10 15:36:07.056 14413 14950 I CCodecBufferChannel: [c2.qti.vp9.decoder#424] Created output block pool with allocatorID 18 => poolID 92 - OK
11-10 15:36:07.056 14413 14950 D CCodecBufferChannel: [c2.qti.vp9.decoder#424] Configured output block pool ids 92 => OK
11-10 15:36:07.056 14413 14950 D Codec2-OutputBufferQueue: remote graphic buffer migration 0/0
11-10 15:36:07.056  1259  1259 E QC2Interface: Few parameters failed to configure
11-10 15:36:07.056 14413 14950 D Codec2Client: setOutputSurface -- failed to set consumer usage (6/BAD_INDEX)
11-10 15:36:07.057 14413 14950 D Codec2Client: setOutputSurface -- generation=14758913 consumer usage=0x900
11-10 15:36:07.057  1250  1355 D qdgralloc: Falling back to default YUV format - no camera/video specific format defined, usage 0x100
11-10 15:36:07.057  1259  1259 D qdgralloc: Falling back to default YUV format - no camera/video specific format defined, usage 0x100
11-10 15:36:07.058  1259  1259 D C2BqBuffer: local generation change 14758913 , bqId: 61903363637250 migrated buffers # 0
11-10 15:36:07.058 14413 14950 D Codec2Client: Surface configure completed
11-10 15:36:07.058 14413 14950 D CCodecBufferChannel: [c2.qti.vp9.decoder#424] start: updating output delay 0
11-10 15:36:07.058  1259  1259 I QC2CompStore: Setting heap usage to system
11-10 15:36:07.058 14413 14950 I DMABUFHEAPS: Using DMA-BUF heap named: system
11-10 15:36:07.059  1535  3032 W ResourceManagerService: Ignoring request to add new resource entry with value <= 0
11-10 15:36:07.062  1259 14954 I QC2Buf  : use shared ref tracker
11-10 15:36:07.062  1259 14954 I QC2Buf  : Reconfig: clearing from 0 bufs. Peak mem=0 MB
11-10 15:36:07.062  1259 14954 I QC2Buf  : Reconfig: clearing from 0 bufs. Peak mem=0 MB
11-10 15:36:07.062  1259 14954 I QC2CompStore: Setting heap usage to system
11-10 15:36:07.064  1259 14957 I QC2Buf  : Reconfig: clearing from 0 bufs. Peak mem=0 MB
11-10 15:36:07.065 14413 14941 I DMCodecAdapterFactory: Creating an asynchronous MediaCodec adapter for track type audio
11-10 15:36:07.068 14413 14950 D CCodecConfig: c2 config diff is   c2::u32 output.delay.value = 7
11-10 15:36:07.068 14413 14950 D CCodecBufferChannel: [c2.qti.vp9.decoder#424] onWorkDone: updating output delay 7
11-10 15:36:07.068 14413 14950 I CCodecBufferChannel: [c2.qti.vp9.decoder#424] onWorkDone: updating max output delay 14
11-10 15:36:07.068 14413 14950 D Codec2-OutputBufferQueue: set max dequeue count 14 from update
11-10 15:36:06.828 14130 14130 W cnss    : Bandwidth vote to 1, save 1
11-10 15:36:07.070 14413 14958 D CCodec  : allocate(c2.android.opus.decoder)
11-10 15:36:07.071 14413 14958 I CCodec  : setting up 'default' as default (vendor) store
11-10 15:36:07.077 14413 14958 I CCodec  : Created component [c2.android.opus.decoder]
11-10 15:36:07.078 14413 14958 D CCodecConfig: read media type: audio/opus
11-10 15:36:07.081 14413 14958 D ReflectedParamUpdater: extent() != 1 for single value type: algo.buffers.max-count.values
11-10 15:36:07.082 14413 14958 D ReflectedParamUpdater: extent() != 1 for single value type: output.subscribed-indices.values
11-10 15:36:07.082 14413 14958 D ReflectedParamUpdater: extent() != 1 for single value type: input.buffers.allocator-ids.values
11-10 15:36:07.082 14413 14958 D ReflectedParamUpdater: extent() != 1 for single value type: output.buffers.allocator-ids.values
11-10 15:36:07.082 14413 14958 D ReflectedParamUpdater: extent() != 1 for single value type: algo.buffers.allocator-ids.values
11-10 15:36:07.082 14413 14958 D ReflectedParamUpdater: extent() != 1 for single value type: output.buffers.pool-ids.values
11-10 15:36:07.082 14413 14958 D ReflectedParamUpdater: extent() != 1 for single value type: algo.buffers.pool-ids.values
11-10 15:36:07.082 14413 14942 D CCodecBufferChannel: [c2.qti.vp9.decoder#424] Ignoring stale input buffer done callback: last flush index = 0, frameIndex = 0
11-10 15:36:07.083 14413 14958 I CCodecConfig: query failed after returning 7 values (BAD_INDEX)
11-10 15:36:07.083 14413 14958 D CCodecConfig: c2 config diff is Dict {
11-10 15:36:07.083 14413 14958 D CCodecConfig:   c2::u32 coded.bitrate.value = 6000
11-10 15:36:07.083 14413 14958 D CCodecConfig:   c2::u32 input.buffers.max-size.value = 5760
11-10 15:36:07.083 14413 14958 D CCodecConfig:   c2::u32 input.delay.value = 0
11-10 15:36:07.083 14413 14958 D CCodecConfig:   string input.media-type.value = "audio/opus"
11-10 15:36:07.083 14413 14958 D CCodecConfig:   string output.media-type.value = "audio/raw"
11-10 15:36:07.083 14413 14958 D CCodecConfig:   c2::u32 raw.channel-count.value = 1
11-10 15:36:07.083 14413 14958 D CCodecConfig:   c2::u32 raw.sample-rate.value = 48000
11-10 15:36:07.083 14413 14958 D CCodecConfig: }
11-10 15:36:07.085  1213  4135 I native  : I0000 00:00:1762749367.085202    4135 client_state.cc:85] [CIB] 'P-Hat' FPS: 59.883
11-10 15:36:07.085  1259  1259 I QC2CompStore: Setting heap usage to system
11-10 15:36:07.086 14413 14958 I MediaCodec: MediaCodec will operate in async mode
11-10 15:36:07.086 14413 14958 D MediaCodec: flushMediametrics
11-10 15:36:07.086 14413 14958 D CCodec  : [c2.android.opus.decoder] buffers are bound to CCodec for this session
11-10 15:36:07.086 14413 14958 D CCodecConfig: no c2 equivalents for csd-1
11-10 15:36:07.086 14413 14958 D CCodecConfig: no c2 equivalents for csd-2
11-10 15:36:07.086 14413 14958 D CCodecConfig: no c2 equivalents for log-session-id
11-10 15:36:07.086 14413 14958 D CCodecConfig: no c2 equivalents for flags
11-10 15:36:07.087 14413 14958 D CCodecConfig: c2 config diff is   c2::u32 input.buffers.max-size.value = 17360
11-10 15:36:07.087 14413 14958 D CCodecConfig:   c2::u32 raw.channel-count.value = 10
11-10 15:36:07.087 14413 14958 W Codec2Client: query -- param skipped: index = 1107298332.
11-10 15:36:07.087 14413 14958 D CCodec  : client requested max input size 5760, which is smaller than what component recommended (17360); overriding with component recommendation.
11-10 15:36:07.087 14413 14958 W CCodec  : This behavior is subject to change. It is recommended that app developers double check whether the requested max input size is in reasonable range.
11-10 15:36:07.087 14413 14958 D CCodec  : encoding statistics level = 0
11-10 15:36:07.087 14413 14958 D CCodec  : setup formats input: AMessage(what = 0x00000000) = {
11-10 15:36:07.087 14413 14958 D CCodec  :   int32_t bitrate = 6000
11-10 15:36:07.087 14413 14958 D CCodec  :   int32_t channel-count = 10
11-10 15:36:07.087 14413 14958 D CCodec  :   int32_t max-input-size = 17360
11-10 15:36:07.087 14413 14958 D CCodec  :   string mime = "audio/opus"
11-10 15:36:07.087 14413 14958 D CCodec  :   int32_t sample-rate = 48000
11-10 15:36:07.087 14413 14958 D CCodec  : }
11-10 15:36:07.087 14413 14958 D CCodec  : setup formats output: AMessage(what = 0x00000000) = {
11-10 15:36:07.087 14413 14958 D CCodec  :   int32_t channel-count = 10
11-10 15:36:07.087 14413 14958 D CCodec  :   string mime = "audio/raw"
11-10 15:36:07.087 14413 14958 D CCodec  :   int32_t sample-rate = 48000
11-10 15:36:07.087 14413 14958 D CCodec  :   int32_t android._config-pcm-encoding = 2
11-10 15:36:07.087 14413 14958 D CCodec  : }
11-10 15:36:07.087 14413 14958 I CCodecConfig: query failed after returning 7 values (BAD_INDEX)
11-10 15:36:07.088  1259 14954 I QC2Comp : [vp9D_58] Output Format at frame 0: VENUS_NV12_UBWC 4500x2250
11-10 15:36:07.088 14413 14941 D MediaCodec: keep callback message for reclaim
11-10 15:36:07.088 14413 14958 W Codec2Client: query -- param skipped: index = 1342179345.
11-10 15:36:07.088 14413 14958 W Codec2Client: query -- param skipped: index = 2415921170.
11-10 15:36:07.088 14413 14950 D CCodecConfig: c2 config diff is   c2::u32 raw.color.matrix = 5
11-10 15:36:07.088 14413 14950 D CCodecConfig:   c2::u32 raw.color.primaries = 6
11-10 15:36:07.088 14413 14950 D CCodecConfig:   c2::u32 raw.color.range = 2
11-10 15:36:07.088 14413 14950 D CCodecConfig:   c2::u32 raw.color.transfer = 3
11-10 15:36:07.088 14413 14950 D CCodecConfig:   c2::u32 raw.crop.height = 2250
11-10 15:36:07.088 14413 14950 D CCodecConfig:   c2::u32 raw.crop.left = 0
11-10 15:36:07.088 14413 14950 D CCodecConfig:   c2::u32 raw.crop.top = 0
11-10 15:36:07.088 14413 14950 D CCodecConfig:   c2::u32 raw.crop.width = 4500
11-10 15:36:07.089 14413 14958 D CCodecBufferChannel: [c2.android.opus.decoder#152] Created input block pool with allocatorID 16 => poolID 18 - OK (0)
11-10 15:36:07.089 14413 14950 D CCodecBuffers: [c2.qti.vp9.decoder#424:2D-Output] popFromStashAndRegister: at 1000000007000us, output format changed to AMessage(what = 0x00000000) = {
11-10 15:36:07.089 14413 14950 D CCodecBuffers:   int32_t android._color-format = 0
11-10 15:36:07.089 14413 14950 D CCodecBuffers:   int32_t android._video-scaling = 1
11-10 15:36:07.089 14413 14950 D CCodecBuffers:   int32_t android._dataspace = 281411584
11-10 15:36:07.089 14413 14950 D CCodecBuffers:   int32_t color-standard = 6
11-10 15:36:07.089 14413 14950 D CCodecBuffers:   int32_t color-range = 2
11-10 15:36:07.089 14413 14950 D CCodecBuffers:   int32_t color-transfer = 3
11-10 15:36:07.089 14413 14950 D CCodecBuffers:   int32_t sar-height = 1
11-10 15:36:07.089 14413 14950 D CCodecBuffers:   int32_t rotation-degrees = 0
11-10 15:36:07.089 14413 14950 D CCodecBuffers:   Buffer hdr-static-info = {
11-10 15:36:07.089 14413 14950 D CCodecBuffers:     00000000:  00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ................
11-10 15:36:07.089 14413 14950 D CCodecBuffers:     00000010:  00 00 00 00 00 00 00 00  00                       .........
11-10 15:36:07.089 14413 14950 D CCodecBuffers:   }
11-10 15:36:07.089 14413 14950 D CCodecBuffers:   int32_t sar-width = 1
11-10 15:36:07.089 14413 14950 D CCodecBuffers:   Rect crop(0, 0, 4499, 2249)
11-10 15:36:07.089 14413 14950 D CCodecBuffers:   int32_t width = 4500
11-10 15:36:07.089 14413 14950 D CCodecBuffers:   int32_t feature-secure-playback = 0
11-10 15:36:07.089 14413 14950 D CCodecBuffers:   int32_t frame-rate = 30
11-10 15:36:07.089 14413 14950 D CCodecBuffers:   Buffer hdr10-plus-info = {
11-10 15:36:07.089 14413 14950 D CCodecBuffers:   }
11-10 15:36:07.089 14413 14950 D CCodecBuffers:   int32_t height = 2250
11-10 15:36:07.089 14413 14950 D CCodecBuffers:   int32_t max-height = 2250
11-10 15:36:07.089 14413 14950 D CCodecBuffers:   int32_t max-width = 4500
11-10 15:36:07.089 14413 14950 D CCodecBuffers:   string mime = "video/raw"
11-10 15:36:07.089 14413 14950 D CCodecBuffers:   int32_t priority = 0
11-10 15:36:07.089 14413 14950 D CCodecBuffers:   int32_t color-format = 2130708361
11-10 15:36:07.089 14413 14950 D CCodecBuffers: }
11-10 15:36:07.089  1559  3053 D BufferPoolAccessor2.0: bufferpool2 0xb40000757c315508 : 0(0 size) total buffers - 0(0 size) used buffers - 1854/1857 (recycle/alloc) - 7/1857 (fetch/transfer)
11-10 15:36:07.090  1259  1259 I QC2CompStore: Setting heap usage to system
11-10 15:36:07.090 14413 14958 I CCodecBufferChannel: [c2.android.opus.decoder#152] Created output block pool with allocatorID 16 => poolID 103 - OK
11-10 15:36:07.090 14413 14958 D CCodecBufferChannel: [c2.android.opus.decoder#152] Configured output block pool ids 103 => OK
11-10 15:36:07.090 14413 14958 D CCodecBufferChannel: [c2.android.opus.decoder#152] start: updating output delay 0
11-10 15:36:07.090  1259  1259 I QC2CompStore: Setting heap usage to system
11-10 15:36:07.091  1559 14959 D SimpleC2Component: Using output block pool with poolID 103 => got 103 - 0
11-10 15:36:07.094  1559 14959 I C2SoftOpusDec: Configuring decoder: 48000 Hz, 10 channels
11-10 15:36:07.095  1259 14954 W QC2V4l2Codec: [vp9D_58] Ignore resolution setting when o/p port is streaming
11-10 15:36:07.103  1259  3009 I QC2CompStore: Setting heap usage to system
11-10 15:36:07.118  1213  4148 W native  : W0000 00:00:1762749367.118058    4148 broker_callback_state.cc:228] [CIB] [P-DepthRGB] Callback duration over limit: 5.1ms > 5.0ms. Start time: 1762749367112943008
11-10 15:36:07.118  1213  4148 W native  : W0000 00:00:1762749367.118205    4148 broker_callback_state.cc:228] [CIB] [DepthRGB] Callback duration over limit: 5.3ms > 5.0ms. Start time: 1762749367112864518
11-10 15:36:07.123  1213  2147 I native  : I0000 00:00:1762749367.123421    2147 landmark_triangulator_lite.cc:34] Received DSP feature measurements 3d.
11-10 15:36:07.128  3228  3285 I CPMScene: Node queued for creation, id: 818, handle: 0xb4000071361615c0, parentId: -1, clientId: -1
11-10 15:36:07.129 14413 14941 W AudioTrack: createTrack_l: CpmNodeInFocus not found. Setting spatialRequest.type as NONE
11-10 15:36:07.131  1259 14957 W QC2V4l2Codec: [vp9D_58] queueOutputs: Allocation failed!
11-10 15:36:07.131  1213  4145 I native  : I0000 00:00:1762749367.131283    4145 client_state.cc:85] [CIB] 'Hat' FPS: 359
11-10 15:36:07.134  1206  1243 D AHAL: FM: fm_get_parameters: 231: enter
11-10 15:36:07.134  1206  1243 D AHAL: FM: fm_get_parameters: 238: exit
11-10 15:36:07.135  3228  3680 I CPMScene: Node created, id: 818, handle: 0xb4000071361615c0, parentId: -1, clientId: -1
11-10 15:36:07.135  1316 15501 D AudioFlinger: Client defaulted notificationFrames to 10260 for frameCount 20520
11-10 15:36:07.141  1259 14957 W QC2V4l2Codec: [vp9D_58] queueOutputs: Allocation failed!
11-10 15:36:07.144  1206  1243 D AHAL: AudioStream: astream_out_get_parameters: 961: enter
11-10 15:36:07.144  1206  1243 D AHAL: AudioStream: astream_out_get_parameters: 983: keys: is_direct_pcm_track
11-10 15:36:07.145  1206  1243 D AHAL: AudioStream: astream_out_get_parameters: 1020: exit: returns - is_direct_pcm_track=false
11-10 15:36:07.150  3228  3680 I CPM     : Starting node subscription.
11-10 15:36:07.151  1259 14957 W QC2V4l2Codec: [vp9D_58] queueOutputs: Allocation failed!
11-10 15:36:07.161 14413 14441 I Unity   : FinishedBuffering
11-10 15:36:07.161 14413 14441 I Unity   : UnityEngine.DebugLogHandler:Internal_Log(LogType, LogOption, String, Object)
11-10 15:36:07.161 14413 14441 I Unity   : VideoPlayerManager:HandleEvent(MediaPlayer, EventType, ErrorCode)
11-10 15:36:07.161 14413 14441 I Unity   : UnityEngine.Events.UnityEvent`3:Invoke(T0, T1, T2)
11-10 15:36:07.161 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.MediaPlayer:FireEventIfPossible(EventType, Boolean)
11-10 15:36:07.161 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.MediaPlayer:HandleRecurringEvents()
11-10 15:36:07.161 14413 14441 I Unity   : RenderHeads.Media.AVProVideo.MediaPlayer:Update()
11-10 15:36:07.161 14413 14441 I Unity   : 
11-10 15:36:06.928 14130 14130 W cnss    : Bandwidth vote to 3, save 1
11-10 15:36:07.167  1254  4362 E ANDR-PERF-LM: FpsMeter: setFpsPeriodicity() 192: Unable to open /sys/class/drm/sde-crtc-0/fps_periodicity_ms file
11-10 15:36:07.167  1254  4362 E ANDR-PERF-LM: FpsMeter: start() 81: Failed to open fps file /sys/class/drm/sde-crtc-0/measured_fps State No such file or directory
11-10 15:36:07.167  1254  4362 E ANDR-PERF-LM: FpsMeter: getNodeData() 150: File Handle is closed(not available) /sys/class/drm/sde-crtc-0/measured_fps
11-10 15:36:07.167  1254  4362 E ANDR-PERF-LM: FpsMeter: getNodeData() 150: File Handle is closed(not available) /sys/class/drm/sde-crtc-0/measured_fps
11-10 15:36:07.171  1213  4274 I native  : I0000 00:00:1762749367.171885    4274 qvr_stream.cc:420] [Live DS] GetFrame for camera eye-tracking-left succeeds, acquired 30 frames in time 1.000066926s, with frame disabled: true, and resolution: 0x0
11-10 15:36:07.175 14413 14413 I AVProVideo: 💬️ (AHardwareBufferPlayerSurface.cpp:52) AHardwareBufferPlayerSurface::AHardwareBufferPlayerSurface - creating surface with 2 images
11-10 15:36:07.175 14413 14413 W libc    : Access denied finding property "vendor.display.enable_optimal_refresh_rate"
11-10 15:36:07.173 14413 14413 W s.XRVideoPlayer: type=1400 audit(0.0:24731): avc:  denied  { read } for  name="u:object_r:vendor_display_prop:s0" dev="tmpfs" ino=444 scontext=u:r:untrusted_app:s0:c235,c256,c512,c768 tcontext=u:object_r:vendor_display_prop:s0 tclass=file permissive=0 app=com.PHORIAStudios.XRVideoPlayer
11-10 15:36:07.200  1213  4265 I native  : I0000 00:00:1762749367.200241    4265 qvr_stream.cc:420] [Live DS] GetFrame for camera ctrl-tracking succeeds, acquired 60 frames in time 1.002536719s, with frame disabled: true, and resolution: 0x0
11-10 15:36:07.202 14413 14413 I AVProVideo: 💬️ Player_Base.UpdateForResolutionChange - UpdateForResolutionChange : m_bVideo_AcceptCommands.set( true )

[Chris-RH]

Have you tried switching the graphics API from Vulkan to openGLES, and then switching the Texture Format to YCbCr420_OES in the Android media player settings, You'll need to make sure you're using OES shaders. This saves memory and improves performance

[MorrisRH]

I would recommend you investigate using XR composition layers for rendering as they have significantly lower overheads than our standard mesh renderer, see #2373 for more details.

The audio track in the video you provided is only 10 channel and I can see from the metadata it is tbe_8.2 so I'm not sure why you think it should be 16 channel. The output from ffprobe for reference:

Stream #0:1: Audio: opus, 48000 Hz, 10 channels, fltp, start -0.007000
    Metadata:
      FB360           : <fb360>
                      :   <audio>
                      :     <AudioChannelConfiguration
                      :       schemeIdUri="tag:facebook.com,2016-08-16:fb360:audio:channel_layout"
                      :       value="tbe_8.2" />
                      :   </audio>
                      : </fb360>
      DURATION        : 00:16:07.281000000

[samueltates]

hi @MorrisRH and @Chris-RH, thanks for follow up and for running ffprobe.

@MorrisRH - Good to know those channels are correct, to be specific we have been using 8+2 setting, but our video editor had mentioned the 16 as a target and I thought maybe it was an issue or I'd used the wrong one. Thanks again for checking the file.

@Chris-RH thanks for those specific settings, I'll try them first thing tomorrow. We'd used OES in the original ecosphere but I dont think so for this.

Just to clarify, the file I shared was working on android xr with pretty basic setup : fb360 8+2 ambi, 8k, exoplayer this wqs pre upates for 16kb security issue. Just wanted to let you know as I saw a bit of fancy footwork with the buffer, mip maps, headroom generally, so wanted to flag I'd seen ambisonics stop working and maybe a regression though I might have just messed something up.

If its helpful I can give specific versions where it was and wasnt working, basically pretty recent AVPro 3 \ Unity and Android \ OpenXR as we've been keeping it all pretty current.

I'll definitely try the composition layers, I also want to try the MV-HEVC support, though I held off as didn't want to change too much till I'd resolved the issue.

Another point (though I may be mistaken), is AndroidXR I think needs vulkan, or at least it is reccomended in Unity setup. I'll double check those settings first thing also.

I'll post back with results tomorrow, just on my phone atm. Thanks again, very happy AVPro user over the years.

[phoria-sam-tg]

Hi @Chris-RH @MorrisRH, so setting up fresh test project, and getting a few variants to isolate issue. Wanted to flag I've created a 1k 30second clip (so low overhead), but kept audio (8+2) and still getting same audio distortion.

I've sent you an email with the 1k clip with ambisonics and without, as well as a stripped down version of test player. If necesary I can roll back and send you a version where its working.

I'm pretty sure its not a performance issue for reasons outlined above. I noticed there was some changes to audio handling when issue with track in 3.30, and wondering if that introduced an error. The sound actually sounds the same as in the past when I played ambisonic without ambisonics properly configured.

Hopefully can get to the bottom of this as we can't roll back due to 16kb issue, and its blocking updated build on the store.

Also above comment was me, was replying from phone and I guess it was logged in to personal github.

[phoria-sam-tg]

A note for anyone that may encounter this issue, the AVPro team identified an issue with decoder selection in the most recent update, and provided a patch. It was a pretty specific situation where the OPUS native decoder was getting triggered instead of the FB360 required for the ambisonics file.

Big thanks to the AVPro team for jumping on that and all the other issues arising from urgency of the 16kb updates!

[MorrisRH]

A fix for this will make it into the next release.

[Chris-RH]

AVPro Video version 3.3.4 has been released. Please let us know if it has not fixed this issue.