Forum Discussion

🚨 This forum is archived and read-only. To submit a forum post, please visit our new Developer Forum. 🚨
MP.Oculus.3D's avatar
MP.Oculus.3D
Honored Guest
1 year ago

Intermittent GPU Hangs - No Application Crash

My team is working with Meta's branch of the UE Source Engine.
UE Version 5.2.0 

Release 1.87.0

Oculus SDK version 55.0

 

Over the holidays, roughly coincidental with the v60 firmware release, we've been experiencing a very strange bug where audio will continue, but the frame hangs and the GPU crashes - sometimes for minutes at a time, and then will occasionally render a single frame, or a second or two of frames before hanging again repeatedly.  but the application itself never crashes, and pending audio files will play if/when the GPU springs back to life.

We're testing and experiencing this bug on Quest 2/Pro/3's  

We've tried a number of things, but I thought I'd put feelers out and see if anyone else has had similar issues or good suggestions on how to isolate a root cause.

16:24:33.759
OsSdk
failed to extrapolate controller tracking state.
16:24:34.173
Tracking
EXPCTRL: [Throttled count=44] Failed to find exposure settings in history.Setting the exposure commands is probably taking more than 3/4 frames
16:24:34.269
[CT]
Anchor:SlamAnchorMemoryOSSDKClient: [Throttled count=712] Skipping invalid anchorHandle when attempting to cache AnchorStates
16:24:35.190
Tracking
EXPCTRL: [Throttled count=45] Failed to find exposure settings in history.Setting the exposure commands is probably taking more than 3/4 frames
16:24:35.269
[CT]
Anchor:SlamAnchorMemoryOSSDKClient: [Throttled count=712] Skipping invalid anchorHandle when attempting to cache AnchorStates
16:24:35.536
adbd
failed to connect to socket 'tcp:6666': Connection refused
16:24:35.657
libosutils
Failed to open the file : error[-2]
16:24:35.657
libosutils
Failed to open the file : error[-2]
16:24:35.659
libosutils
Failed to open the file : error[-2]
16:24:35.660
libosutils
Failed to open the file : error[-2]
16:24:35.657
healthd
battery l=55 v=3865 t=23.5 h=2 st=2 c=155273 fc=5642000 cc=1 chg=a
16:24:35.666
libosutils
Failed to open the file : error[-2]
16:24:35.666
libosutils
Failed to open the file : error[-2]
16:24:35.773
OsSdk
failed to extrapolate controller tracking state.
16:24:36.190
Tracking
EXPCTRL: [Throttled count=44] Failed to find exposure settings in history.Setting the exposure commands is probably taking more than 3/4 frames
16:24:36.310
[CT]
Anchor:SlamAnchorMemoryOSSDKClient: [Throttled count=712] Skipping invalid anchorHandle when attempting to cache AnchorStates
16:24:37.190
Tracking
EXPCTRL: [Throttled count=44] Failed to find exposure settings in history.Setting the exposure commands is probably taking more than 3/4 frames
16:24:37.308
Tracking
CalibrationConversions: Serializing lookup table model by bypassing the normal procedure!
16:24:37.310
[CT]
Anchor:SlamAnchorMemoryOSSDKClient: [Throttled count=689] Skipping invalid anchorHandle when attempting to cache AnchorStates
16:24:37.540
adbd
failed to connect to socket 'tcp:6666': Connection refused
16:24:37.786
OsSdk
failed to extrapolate controller tracking state.
16:24:38.224
Tracking
EXPCTRL: [Throttled count=45] Failed to find exposure settings in history.Setting the exposure commands is probably taking more than 3/4 frames
16:24:38.338
[CT]
Anchor:SlamAnchorMemoryOSSDKClient: [Throttled count=712] Skipping invalid anchorHandle when attempting to cache AnchorStates
16:24:39.224
Tracking
EXPCTRL: [Throttled count=44] Failed to find exposure settings in history.Setting the exposure commands is probably taking more than 3/4 frames
16:24:39.242
Adreno-GSL
<log_gpu_snapshot:471>: panel.gpuSnapshotPath is not set.not generating user snapshot
16:24:39.246
OsSdk
Requested historical tracking state too far in the past; oldest: 12676.634840s, requested: 12289.658668s
16:24:39.249
kgsl-3d0
CP opcode error interrupt | opcode=0x00000000
16:24:39.295
libosutils
Failed to open the file : error[-2]
16:24:39.295
libosutils
Failed to open the file : error[-2]
16:24:39.296
healthd
battery l=55 v=3863 t=23.5 h=2 st=2 c=92773 fc=5642000 cc=1 chg=a
16:24:39.300
libosutils
Failed to open the file : error[-2]
16:24:39.300
libosutils
Failed to open the file : error[-2]
16:24:39.308
libosutils
Failed to open the file : error[-2]
16:24:39.308
libosutils
Failed to open the file : error[-2]
16:24:39.346
FenceChecker
FenceChecker: wait timed out after 100 msec
16:24:39.346
kgsl-3d0
MISC: GPU hang detected
16:24:39.346
kgsl-3d0
main[24710]: gpu fault ctx 6 ctx_type VK ts 9488 status 00800005 rb 0540/0b02 ib1 0000004007F7C000/027e ib2 00000040082826D0/02df
16:24:39.346
kgsl-3d0
main[24710]: gpu fault rb 2 rb sw r/w 0540/0b02
16:24:39.347
platform 3d6a000.qcom,gmu
Suspended GMU
16:24:39.351
kgsl-3d0
main[24710]: gpu skipcmd ctx 6 ts 9489 policy 80
16:24:39.353
kgsl-3d0
CP opcode error interrupt | opcode=0x00000000
16:24:39.360
kgsl-3d0
MISC: GPU hang detected
16:24:39.361
kgsl-3d0
main[24710]: gpu fault ctx 6 ctx_type VK ts 9500 status 00800005 rb 0600/0afc ib1 000000400873E000/027c ib2 00000040039BD000/079d
16:24:39.361
kgsl-3d0
main[24710]: gpu fault rb 2 rb sw r/w 0600/0afc
16:24:39.362
platform 3d6a000.qcom,gmu
Suspended GMU
16:24:39.366
kgsl-3d0
main[24710]: gpu skipcmd ctx 6 ts 9501 policy 80
16:24:39.370
Adreno-GSL
<log_gpu_snapshot:471>: panel.gpuSnapshotPath is not set.not generating user snapshot
16:24:39.377
kgsl-3d0
CP opcode error interrupt | opcode=0x00000000
16:24:39.384
kgsl-3d0
MISC: GPU hang detected
16:24:39.384
kgsl-3d0
main[24710]: gpu fault ctx 6 ctx_type VK ts 9513 status 00800005 rb 0b30/0c3b ib1 00000040089C5000/0280 ib2 0000004008BD1AB8/01fe
16:24:39.384
kgsl-3d0
main[24710]: gpu fault rb 2 rb sw r/w 0b30/0c3b
16:24:39.384
platform 3d6a000.qcom,gmu
Suspended GMU
16:24:39.393
[CT]
Anchor:SlamAnchorMemoryOSSDKClient: [Throttled count=712] Skipping invalid anchorHandle when attempting to cache AnchorStates
16:24:39.388
kgsl-3d0
main[24710]: gpu skipcmd ctx 6 ts 9514 policy 80
16:24:39.418
MediaCodec
mapFormat: no mediaType information
16:24:39.447
Adreno-GSL
<log_gpu_snapshot:471>: panel.gpuSnapshotPath is not set.not generating user snapshot


Looking over the logs and errors, I am seeing a lot of 'Failed at frameworks/native/libs/binder/PersistableBundle.cpp:168 (erase)' 

and we've seen an error thrown in the OVRPlugin about "Unexpected Event Type 304" in Compositor::PollEvent from OVRPlugin/Src/Util/Compositor.cpp 
We also have seen some logs mentioning GPU page faults...  

We're in the process of testing just upgrading engine versions to see if it's some issue that's quarantined to v55.

 

2 Replies

Replies have been turned off for this discussion
  • GzsIceberg's avatar
    GzsIceberg
    Explorer
    1 year ago

    Hi,

    We've encountered a similar issue with our game. After the freeze, our logs also show a GPU PAGE FAULT. We're using Unity with the Oculus SDK v65, and testing on Meta Quest 3.

    If you find a solution or have any insights, we'd greatly appreciate it. We're currently exploring various debugging avenues and will share any findings that might be helpful to both teams.

    6:12:08.308 27957 27977 I Unity         : [avatar] SwitchSpringBones(enable = Burst)
16:12:08.308 27957 27977 I Unity         : XRIF._Core.Rigs.FVRArtRig:SetupSpringBones(SpringBoneType)
16:12:08.308 27957 27977 I Unity         : XRIF._Core.Rigs.FVRVirtualRig:OnAvatarChange(FVRArtRig)
16:12:08.308 27957 27977 I Unity         : XRIF._Core.Rigs.FVRVirtualRig:OnLoadNewArtRig()
16:12:08.308 27957 27977 I Unity         : XRIF._Core.Rigs.FVRVirtualRig:BeforePhysicsStep()
16:12:08.308 27957 27977 I Unity         : FusionExt.NetworkRunnerExtension:InvokeOnBeforePhysicsStep(NetworkRunner)
16:12:08.308 27957 27977 I Unity         : FusionExt.NetworkPhysicsSimulationCustom:Simulate()
16:12:08.308 27957 27977 I Unity         : Fusion.SimulationBehaviourUpdater:InvokeFixedUpdateNetwork(SimulationStages, SimulationModes)
16:12:08.308 27957 27977 I Unity         : Fusion.NetworkRunner:Fusion.Simulation.ICallbacks.OnTick()
16:12:08.308 27957 27977 I Unity         : Fusion.Simulation:InvokeTick(SimulationStages, Boolean)
16:12:08.308 27957 27977 I Unity         : Fusion.Simulation:StepSimulation(SimulationStages, Boolean, Boolean, Boolean)
16:12:08.308 27957 27977 I Unity         : Fusion.Simulation:Update(Double)
16:12:08.308 27957 27977 I Unity         : Fusion.NetworkRunner:UpdateInternal(Double)
16:12:08.308 27957 27977 I Unity         : Fusion.NetworkRunner:InvokeUpdate(Single)
16:12:08.308 27957 27977 I Unity         : 
16:12:08.327 27957 27977 D Unity         : Uploaded shader variant to the GPU driver: Forking/StandardLit, pass: Forward, stage: all, keywords FOG_EXP LIGHTMAP_ON LOD_FADE_CROSSFADE SHADOWS_SHADOWMASK STEREO_MULTIVIEW_ON
16:12:08.356     0     0 D wlan          : [0:IH:DP] dp_fisa_fse_cache_flush_timer: FSE cache flush for 1 flows
16:12:08.356     0     0 D wlan          : [0:IH:DP] dp_htt_rx_flow_fse_operation: HTT_H2T RX_FSE_OPERATION_CFG sent to FW for pdev = 0
16:12:08.369   641 19533 I libcamerahal  : [INSIGHTS][2.01s] recv/ret/req heartbeat: cam0: 75/75/75, cam1: 75/75/75, cam2: 75/75/75, cam3: 75/75/75
16:12:08.389     0     0 F kgsl-3d0      : GPU PAGE FAULT: addr = 400BB8D100 pid= 27957 name=m.Forking.BowTD drawctxt=405504 context pid = 0
16:12:08.389     0     0 F kgsl-3d0      : context=gfx3d_user TTBR0=0x82e9d9000 (write permission fault)
16:12:08.389     0     0 F kgsl-3d0      : FAULTING BLOCK: Flag cache
16:12:08.389     0     0 F kgsl-3d0      : GPU PAGE FAULT: addr = 400BB9F600 pid= 27957 name=m.Forking.BowTD drawctxt=405504 context pid = 0
16:12:08.389     0     0 F kgsl-3d0      : context=gfx3d_user TTBR0=0x82e9d9000 (write translation fault)
16:12:08.389     0     0 F kgsl-3d0      : FAULTING BLOCK: Flag cache
16:12:08.389     0     0 F kgsl-3d0      : ---- nearby memory ----
16:12:08.389     0     0 E kgsl-3d0      : [000000400BB9D000 - 000000400BB9EFFF]   (pid = 27957) (vk_cmdbuffer)
16:12:08.389     0     0 F kgsl-3d0      : <- fault @ 000000400bb9f600
16:12:08.389     0     0 E kgsl-3d0      : [000000400BBA0000 - 000000400BBAFFFF]   (pid = 27957) (vk_any)
16:12:08.390     0     0 F kgsl-3d0      : GPU PAGE FAULT: addr = 50879FFD00 pid= 27957 name=m.Forking.BowTD drawctxt=405504 context pid = 0
16:12:08.390     0     0 F kgsl-3d0      : context=gfx3d_user TTBR0=0x82e9d9000 (read translation fault)
16:12:08.390     0     0 F kgsl-3d0      : FAULTING BLOCK: UCHE: BR_SP
16:12:08.390     0     0 F kgsl-3d0      : ---- nearby memory ----
16:12:08.390     0     0 E kgsl-3d0      : [0000004047705000 - 0000004047724FFF]   (pid = 27957) (vk_cmdbuffer)
16:12:08.390     0     0 F kgsl-3d0      : <- fault @ 00000050879ffd00
16:12:08.390     0     0 F kgsl-3d0      : **EMPTY**
16:12:08.390     0     0 F adreno-gen7-gmu 3d68000.qcom,gmu: CP opcode error interrupt | opcode=0x01010101
16:12:08.390     0     0 E kgsl-3d0      : m.Forking.BowTD[27957]: ctx 24 ctx_type VK ts 1106 status 00800005 dispatch_queue=-1 rb 0284/02b7 ib1 0000004044517000/1f7c ib2 00000040006DB0B8/175c
16:12:08.390     0     0 E kgsl-3d0      : m.Forking.BowTD[27957]: cmdline: com.Forking.BowTD
16:12:08.391     0     0 E adreno-gen7-gmu 3d68000.qcom,gmu: Suspended GMU
16:12:08.393 27957 27977 D Unity         : Uploaded shader variant to the GPU driver: Forking/StandardLit, pass: Forward, stage: all, keywords FOG_EXP LIGHTMAP_ON SHADOWS_SHADOWMASK STEREO_MULTIVIEW_ON _FAKESHADOW_ON _PREBAKE_ON
16:12:08.394 27957 27977 D Unity         : Uploaded shader variant to the GPU driver: Forking/StandardLit, pass: Forward, stage: all, keywords FOG_EXP LIGHTMAP_ON SHADOWS_SHADOWMASK STEREO_MULTIVIEW_ON _FAKESHADOW_ON _SPLAT_THREEWAY
16:12:08.396 27957 27977 D Unity         : Uploaded shader variant to the GPU driver: Forking/StandardLit, pass: Forward, stage: all, keywords FOG_EXP LIGHTMAP_ON SHADOWS_SHADOWMASK STEREO_MULTIVIEW_ON _FAKESHADOW_ON _SPLAT_ON
16:12:08.397 27957 27977 D Unity         : Uploaded shader variant to the GPU driver: Forking/StandardLit, pass: Forward, stage: all, keywords FOG_EXP LIGHTMAP_ON SHADOWS_SHADOWMASK STEREO_MULTIVIEW_ON _FAKESHADOW_ON
16:12:08.399 27957 27977 D Unity         : Uploaded shader variant to the GPU driver: Forking/StandardLit, pass: Forward, stage: all, keywords FOG_EXP LIGHTMAP_ON SHADOWS_SHADOWMASK STEREO_MULTIVIEW_ON _ALPHA_TEST _FAKESHADOW_ON
16:12:08.401 27957 27977 D Unity         : Uploaded shader variant to the GPU driver: Cartoon FX/Remaster/Particle Ubershader, pass: BASE_URP, stage: all, keywords FOG_EXP STEREO_MULTIVIEW_ON _ALPHABLEND_ON _CFXR_SINGLE_CHANNEL
16:12:08.406  2904 19437 I CompositorVR  : Will tear: 0.020191
16:12:08.406  2904 19437 I Vsync         : Vsync 24872:  2 x 13.89 ms (72.00 Hz, at 1758.633)
16:12:08.406  2904 19437 I CompositorUtil: Adjusting 24872.35 > 24871 to 24872
16:12:08.414  2904 19437 I CompositorVR  : Nonsensical slice time: SliceTime=0.0321 SliceTargetWakeupTime=1758.5987 SliceGpuStart=1758.6297 SliceGpuEnd=1758.6308 GpuTimestampSync=0.0000
16:12:08.426  2904  3216 I PerformanceManager_ZSF: perfmgr: SetClockLevels: Apply pending clock request change: 4,3 -> 4,4
16:12:08.427  2904  3216 I Clocks        : ApplySystemModes( cpu=4, gpu=4 ) - start
16:12:08.427  2904  3216 I Clocks        : CPU levels [0, 8]
16:12:08.427  2904  3216 I Clocks        : GPU levels [0, 7]
16:12:08.427  2904  3216 I ClocksChange  : Changing SystemModes to: [VR_MODE_CPU_4,VR_MODE_GPU_4,SUSTAINED_PERFORMANCE_VR_MODE]
16:12:08.427  2904  3216 I Clocks        : ApplySystemModes( cpu=4, gpu=4 ) - end (0.1 ms)
16:12:08.434  2325  2660 E TREX          : Interface 'MrLightingEstimationInterface', version 1 was not found. Check debug build for more information.
16:12:08.503 27957 28085 W FenceChecker  : FenceChecker: wait timed out after 100 msec
16:12:08.518  2904 19437 W CompositorVR  : TimeWarp::RetireFrame wait sync timed out after 100 msec!
16:12:08.518  2904 19437 I CompositorVR  : Will tear: 0.090278
16:12:08.518  2904 19437 I Vsync         : Vsync 24880:  7 x 13.89 ms (72.00 Hz, at 1758.745)
16:12:08.518  2904 19437 I CompositorUtil: Adjusting 24880.40 > 24874 to 24880
16:12:08.603 27957 28085 W FenceChecker  : FenceChecker: wait timed out after 100 msec
16:12:08.619  2904 19437 W CompositorVR  : TimeWarp::RetireFrame wait sync timed out after 100 msec!
16:12:08.619  2904 19437 I CompositorVR  : Will tear: 0.093760
16:12:08.619  2904 19437 I Vsync         : Vsync 24887:  7 x 13.89 ms (72.00 Hz, at 1758.845)
16:12:08.619  2904 19437 I CompositorUtil: Adjusting 24887.65 > 24881 to 24888
16:12:08.635  2904  3216 I PerformanceManager_ZSF: perfmgr: SetClockLevels: Apply pending clock request change: 4,4 -> 4,5
16:12:08.635  2904  3216 I Clocks        : ApplySystemModes( cpu=4, gpu=5 ) - start
16:12:08.635  2904  3216 I Clocks        : CPU levels [0, 8]
16:12:08.635  2904  3216 I Clocks        : GPU levels [0, 7]
16:12:08.635  2904  3216 I ClocksChange  : Changing SystemModes to: [VR_MODE_CPU_4,VR_MODE_GPU_5,SUSTAINED_PERFORMANCE_VR_MODE]
16:12:08.635  2904  3216 I Clocks        : ApplySystemModes( cpu=4, gpu=5 ) - end (0.1 ms)
16:12:08.719  2904 19437 W CompositorVR  : TimeWarp::RetireFrame wait sync timed out after 100 msec!
16:12:08.719  2904 19437 I CompositorVR  : Will tear: 0.082973
16:12:08.719  2904 19437 I Vsync         : Vsync 24894:  7 x 13.89 ms (72.00 Hz, at 1758.946)
16:12:08.719  2904 19437 I CompositorUtil: Adjusting 24894.87 > 24889 to 24895
16:12:08.728 27957 28085 W FenceChecker  : FenceChecker: wait timed out after 100 msec
16:12:08.819  2904 19437 W CompositorVR  : TimeWarp::RetireFrame wait sync timed out after 100 msec!
16:12:08.819  2904 19437 I CompositorVR  : Will tear: 0.086070
16:12:08.820  2904 19437 I Vsync         : Vsync 24902:  8 x 13.89 ms (72.00 Hz, at 1759.046)
16:12:08.820  2904 19437 I CompositorUtil: Adjusting 24902.09 > 24896 to 24902
16:12:08.920  2904 19437 W CompositorVR  : TimeWarp::RetireFrame wait sync timed out after 100 msec!
16:12:08.920  2904 19437 I CompositorVR  : Will tear: 0.089234
16:12:08.920  2904 19437 I Vsync         : Vsync 24909:  7 x 13.89 ms (72.00 Hz, at 1759.146)
16:12:08.920  2904 19437 I CompositorUtil: Adjusting 24909.32 > 24903 to 24909
16:12:08.978  2997  3766 I [CT]          : SlamAnchorClientRuntimeIPC: Kicked off bulk re-registration of 5 slam anchors.
16:12:09.004 27957 28073 I VrApi         : FPS=52/72,Prd=46ms,Tear=0,Early=33,Stale=56,Stale2/5/10/max=1/1/0/6,VSnc=1,Lat=-1,Fov=0,CPU4/GPU=4/3,2361/492MHz,OC=3F,TA=0/38/38,SP=N/N/N,Mem=3196MHz,Free=4109MB,PLS=0,Temp=42.0C/0.0C,TW=1.07ms,App=3.72ms,GD=0.00ms,CPU&GPU=13.14ms,LCnt=1(DR73,LM0),GPU%=0.14,CPU%=0.40(W0.67),DSF=1.00,CFL=9.87/14.02,ICFLp95=12.78,LD=0,SF=1.30,LP=0,DVFS=0,ShrpLCnt=1,ShrpR=1.000,SSLCnt=0/0
16:12:09.020  2904 19437 W CompositorVR  : TimeWarp::RetireFrame wait sync timed out after 100 msec!
16:12:09.020  2904 19437 I CompositorVR  : Will tear: 0.092400
16:12:09.020  2904 19437 I Vsync         : Vsync 24916:  7 x 13.89 ms (72.00 Hz, at 1759.247)
16:12:09.020  2904 19437 I CompositorUtil: Adjusting 24916.55 > 24910 to 24917
16:12:09.120  2904 19437 W CompositorVR  : TimeWarp::RetireFrame wait sync timed out after 100 msec!
16:12:09.120  2904 19437 I CompositorVR  : Will tear: 0.081555
16:12:09.121  2904 19437 I Vsync         : Vsync 24923:  7 x 13.89 ms (72.00 Hz, at 1759.347)
16:12:09.121  2904 19437 I CompositorUtil: Adjusting 24923.77 > 24918 to 24924
16:12:09.175   853 19607 I Tracking      : CONSTELLATION:VISION_STATUS: ea66a205736aa4ac (L): BlobDetection:[#BlobsDetected=(25.32, 27.58)]Matching:[MatchSuccess=1.000, MatchCost=(0.00167, 0.00000), #MatchedBlobs=(17.40, 7.22), #MatchedLeds=(6.83, 0.68), RematchM=0.000, SkipRefine=0.000] MatchMethod:[Prox=1.000, ConstBrute=0.000, UnconstBrute=0.000] MatchResets[AnomBlobSize=0.000, SharedMatch=0.000, DenseReacq=0.000] | Fusion:[FusionError=0.000, ImuFromCamMs=(-1752949.800, 2116266.667), RematchF=0.000] FusionUpdates:[Successful=0.000, Reliable=0.000, TightFusion=0.000, FullPose=0.000, StaticPrior=0.000, StaticForHandTrans=0.000, PoseUpdateMD=(0.0000, 0.0000)] FusionResets[6DoFReset=0.000, ResetSuccess=0.000] FusionStatus[HasPosition=0.000, HasVelocity=0.000] 
16:12:09.175   853 19607 I Tracking      : CONSTELLATION:VISION_STATUS: 64c4290f22b5f4df (R): BlobDetection:[#BlobsDetected=(25.32, 27.58)]Matching:[MatchSuccess=1.000, MatchCost=(0.00230, 0.00000), #MatchedBlobs=(7.84, 7.91), #MatchedLeds=(5.68, 0.92), RematchM=0.000, SkipRefine=0.000] MatchMethod:[Prox=1.000, ConstBrute=0.000, UnconstBrute=0.000] MatchResets[AnomBlobSize=0.000, SharedMatch=0.000, DenseReacq=0.000] | Fusion:[FusionError=0.000, ImuFromCamMs=(-1752949.800, 2116266.667), RematchF=0.000] FusionUpdates:[Successful=0.000, Reliable=0.000, TightFusion=0.000, FullPose=0.000, StaticPrior=0.000, StaticForHandTrans=0.000, PoseUpdateMD=(0.0000, 0.0000)] FusionResets[6DoFReset=0.000, ResetSuccess=0.000] FusionStatus[HasPosition=0.000, HasVelocity=0.000] 
16:12:09.221  2904 19437 W CompositorVR  : TimeWarp::RetireFrame wait sync timed out after 100 msec!
16:12:09.221  2904 19437 I CompositorVR  : Will tear: 0.084652
16:12:09.221  2904 19437 I Vsync         : Vsync 24930:  7 x 13.89 ms (72.00 Hz, at 1759.447)
16:12:09.221  2904 19437 I CompositorUtil: Adjusting 24930.99 > 24925 to 24931
16:12:09.312   641  1039 E FrameSetCollator: [worldTracking] 26 FrameSets had unexpected timestamps (over 1040.05s). Ex: slot 0 1754529.800ms -> 1754569.800ms (observed period: 40.000ms, expected period: 20.000ms (20.000ms))
16:12:09.330     0     0 W healthd       : battery l=93 v=4254 t=42.0 h=2 st=3 c=-451183 fc=4865000 cc=53 chg=a
16:12:09.321  2904 19437 W CompositorVR  : TimeWarp::RetireFrame wait sync timed out after 100 msec!
16:12:09.321  2904 19437 I CompositorVR  : Will tear: 0.087739
16:12:09.321  2904 19437 I Vsync         : Vsync 24938:  8 x 13.89 ms (72.00 Hz, at 1759.548)
16:12:09.321  2904 19437 I CompositorUtil: Adjusting 24938.21 > 24932 to 24938
16:12:09.403   853 19537 W Tracking      : VEGA:HM:MAP_TRACKING: Validation failed on Submap id = 1, version = 160554684
16:12:09.421  2904 19437 W CompositorVR  : TimeWarp::RetireFrame wait sync timed out after 100 msec!
16:12:09.421  2904 19437 I CompositorVR  : Will tear: 0.090811
16:12:09.421  2904 19437 I Vsync         : Vsync 24945:  7 x 13.89 ms (72.00 Hz, at 1759.648)
16:12:09.421  2904 19437 I CompositorUtil: Adjusting 24945.43 > 24939 to 24945
16:12:09.428 27957 27957 I OVRPlatform   : [QueueAsyncResponseHandler] success: 
16:12:09.429  1075  1594 D SntpClient    : request time failed: java.net.SocketTimeoutException: Poll timed out
16:12:09.429  1075  1594 D SntpClient    : request time failed
16:12:09.522  2904 19437 W CompositorVR  : TimeWarp::RetireFrame wait sync timed out after 100 msec!
16:12:09.522  2904 19437 I CompositorVR  : Will tear: 0.093907
16:12:09.522  2904 19437 I Vsync         : Vsync 24952:  7 x 13.89 ms (72.00 Hz, at 1759.748)
16:12:09.522  2904 19437 I CompositorUtil: Adjusting 24952.66 > 24946 to 24953
16:12:09.576   853 19391 I Tracking      : BOLT:HexagonTelemetry: Global DSP utilization: 6.02% (301 / 5003 ms), Scalar Util: 0.00%, HVX Util: 0.00%, HMX Util: 0.00%, Freq: 1171.2 MHz
16:12:09.622  2904 19437 W CompositorVR  : TimeWarp::RetireFrame wait sync timed out after 100 msec!
16:12:09.622  2904 19437 I CompositorVR  : Will tear: 0.083091
16:12:09.622  2904 19437 I Vsync         : Vsync 24959:  7 x 13.89 ms (72.00 Hz, at 1759.849)
16:12:09.622  2904 19437 I CompositorUtil: Adjusting 24959.88 > 24954 to 24960

     

     

     

     

     

  • Yis's avatar
    Yis
    Honored Guest
    9 months ago

    Hey
    Did you ever manage to sort this out
    I am coming across the same issue in my game