Support Oculus Quest 3

[weier123]

hi guys,

I try to read the joystick pose of quest 3. I got the pose information of joystick, however, it seems i cannot move the joystick along its y and z axis, always a deviation there. So i wonder if there is any update I need do for the code that is used for quest2 or is there other reason?
really thanks
de

[yizt]

@weier123, i meet the same problem,did you solved?

[YunxiTang]

@weier123 @yizt hi all, did you find a way to solve such problem on quest 3

[MarSaKi]

hi guys,

I try to read the joystick pose of quest 3. I got the pose information of joystick, however, it seems i cannot move the joystick along its y and z axis, always a deviation there. So i wonder if there is any update I need do for the code that is used for quest2 or is there other reason? really thanks de

I met the same problem, have you solved it yet? thanks!

[jborbik]

Thanks for reporting! Unfortunately I don't have Oculus Quest 3 to test the APK myself and would need your help to wrap things up. If you are willing to help me with testing, here are the steps:

Initial step:

1. Connect the oculus quest as you would do it before for DROID
2. Run original APK with "python3 oculus_reader/reader.py” and check how the interface looks like

To install the new APK:

1. Replace "teleop-debug.apk” in the locally cloned repo with the one from this link: https://drive.google.com/file/d/17iIzHUHXtzlwmAXW5QfGqwRpsePxq_MA/view?usp=share_link
2. Run “python3 oculus_reader/install.py --reinstall” to install the new APK
3. Run "python3 oculus_reader/reader.py” and wear the helmet. Verify that the interface changed which means that the new APK has been installed successfully.

To test the new APK:

1. Run “python3 oculus_reader/reader.py”
2. Try to press the buttons and move the controllers in front of headset
3. Check the printed output for the following:
   • do the poses change as expected?
   • if you press the buttons on controller, can you see them in the printed output?

In case of any issues:

1. Add the line in the file at this position:

   oculus_reader/oculus_reader/reader.py

   Line 189 in c52b4b6

   data = self.extract_data(line)

   . Content: “print(line)”
2. Try to press the buttons and move the controllers in front of headset
3. Report the issues to me including the printed output from “reader.py". I’ll try to get to it as soon as I can.

Thanks!

[MarSaKi]

Thanks for reporting! Unfortunately I don't have Oculus Quest 3 to test the APK myself and would need your help to wrap things up. If you are willing to help me with testing, here are the steps:

Initial step:

1. Connect the oculus quest as you would do it before for DROID
2. Run original APK with "python3 oculus_reader/reader.py” and check how the interface looks like

To install the new APK:

1. Replace "teleop-debug.apk” in the locally cloned repo with the one from this link: https://drive.google.com/file/d/1Fg3lsOmtAh7GwQocDAKgV7JZEr7CivMv/view?usp=sharing
2. Run “python3 oculus_reader/install.py --reinstall” to install the new APK
3. Run "python3 oculus_reader/reader.py” and wear the helmet. Verify that the interface changed which means that the new APK has been installed successfully.

To test the new APK:

1. Run “python3 oculus_reader/reader.py”

2. Try to press the buttons and move the controllers in front of headset

3. Check the printed output for the following:

   • do the poses change as expected?
   • if you press the buttons on controller, can you see them in the printed output?

In case of any issues:

1. Add the line in the file at this position:

     [oculus_reader/oculus_reader/reader.py](https://github.com/rail-berkeley/oculus_reader/blob/c52b4b6193879b6cf32a1db1bd5727652626f00d/oculus_reader/reader.py#L189)
   
   
        Line 189
     in
     [c52b4b6](/rail-berkeley/oculus_reader/commit/c52b4b6193879b6cf32a1db1bd5727652626f00d)
   
   
   
   
   
       
         
          data = self.extract_data(line) 
   

   . Content: “print(line)”

2. Try to press the buttons and move the controllers in front of headset

3. Report the issues to me including the printed output from “reader.py". I’ll try to get to it as soon as I can.

Thanks!

Hi thanks for your feedback!
However, I found the new APK can not be installed, while the old version can be installed on quest 3. The log looks like below:

Do you have any idea about this?
Thanks,

[MarSaKi]

04-25 22:09:18.388   829  2401 D vendor.qti.bluetooth@1.1-wake_lock: Release wakelock is released
04-25 22:09:18.410     0     0 I msm_cvp : warn: DBLP Set: status 0 0
04-25 22:09:18.411     0     0 I msm_cvp : pwr: cvp (eva) power collapsed
04-25 22:09:18.451  3792  4046 I SafetyIntentMgr: [SafetyIntentMgr::UpdateSafetyIntentMode] SafetyIntentMode: SAFETY_INTENT_VR_HOME -> SAFETY_INTENT_INVALID (Package Name: )
04-25 22:09:18.451  3792  4046 I StateMachine: StateMachine::HandleEvent SafetyIntent: safetyIntentSuspend -> SafetyIntentInvalid
04-25 22:09:18.451  3792  4046 I GuardianPassthroughMgr: GuardianPassthroughMgr::UpdateTransition - Transition state change (PT_TRANSITION_VR -> PT_TRANSITION_NONE)
04-25 22:09:18.451  3792  4046 I GuardianLifecycleMgr: [GuardianLifecycleMgr::OnSystemThreadLifecycleState] State transition SAFETY_SYSTEM_VR->SUSPENDED
04-25 22:09:18.451  3792  4046 I StateMachine: StateMachine::Reset ForceShow: ForceShowOnForTime -> ForceShowOff
04-25 22:09:18.451  3792  4046 I ForceShowStateMachine: ForceShowGuardian for 2.101735 seconds.
04-25 22:09:18.451  3792  4046 I StateMachine: StateMachine::HandleEvent ForceShow: forceShowForTime -> ForceShowOnForTime
04-25 22:09:18.451  3792  4046 W StateMachine: StateMachine::ProcessSignal GuardianRoomCapture received registered signal lifecycleSuspend but the current state InvalidState is not listening to this signal
04-25 22:09:18.451  3792  4046 W StateMachine: StateMachine::ProcessSignal ManualRoomCapture received registered signal lifecycleSuspend but the current state ManualInvalidState is not listening to this signal
04-25 22:09:18.451  3792  4046 I StateMachine2: StateMachine2Base::AddHistoryEntry TrackingLost: Transition Valid -> Valid | Lifecycle suspended; resetting TrackingLostSM to initial state
04-25 22:09:18.451  3792  4046 I StateMachine2: StateMachine2Base::AddHistoryEntry TrackingLost: Signal lifecycleSuspend event
04-25 22:09:18.451  3792  4046 I GuardianMapMgr: [GuardianMapMgr::SetUserPreferredGroundPlaneHeight] Updating user-preferred floor height from NONE to NONE
04-25 22:09:18.451  3792  4046 I GuardianMapMgr: [GuardianMapMgr::SetCachedGroundPlaneHeightErrorCode] Updating cached ground plane height error code from 0 to 99998
04-25 22:09:18.451  3792  4046 I GuardianFreespaceMgr: [GuardianFreespaceMgr::StopFreespaceScanningNonBlocking], isForegroundScan: 0
04-25 22:09:18.451  3792  4046 W IAppAnchorDataStore: IAppAnchorDataStoreBase::DeleteAnchorData - no anchor 966d2621-77fb-cc3e-7c27-3ea299d23a61 to erase.
04-25 22:09:18.451  3792  4046 W AnchorCoreBase: AnchorCoreBase::DestroyAnchorInternal - Attempted to remove anchor 966d2621-77fb-cc3e-7c27-3ea299d23a61, but it was not found in the map
04-25 22:09:18.451  3792  4046 I GuardianDialogMgr: [GuardianDialogMgr::SetGuardianDialogAndDefinition] pendingDialogDefinition = -1
04-25 22:09:18.452  1083  4384 I MRSS    : MIXEDREALITY: MrSystemServiceIPCServer: ChangeMrCapabilityStatusNonBlocking: client 4030635500, capability MetaBackgroundFreespaceScan, command 0.
04-25 22:09:18.452  1083  4384 I MRSS    : MIXEDREALITY: MrSystemService: FramesetTelemetry: valid: -/-, stereo/depth/color: avail:   27/   0/  50, accepted %: 100.0/0.0/100.0, dropped %: 0.0/0.0/0.0, valid %: 100.0/0.0/100.0, textured %: 0.0/0.0/100.0.
04-25 22:09:18.452  1083  4384 I MRSS    : MIXEDREALITY: MrSystemService: FramesetTelemetry: timeouts stereo/color: compute side: 0/0, render side: 0/0, egl: 0.
04-25 22:09:18.452  3792  4046 I GuardianTelemetry: OVR::Telemetry Guardian Stats thread stopped
04-25 22:09:18.452  3792  4046 I Input   : Input::Suspend ... destroying hand trackers
04-25 22:09:18.452  3792  4046 I OpenXR_Hands: ------------ xrDestroyHandTrackerEXT -----------
04-25 22:09:18.452  3792  4046 I OpenXR_Hands: ------------ xrDestroyHandTrackerEXT -----------
04-25 22:09:18.452  3792  4046 I GuardianRender: Suspend: GuardianRender suspending.
04-25 22:09:18.452  3792  4046 I OpenXR_Passthrough: ------------ xrPassthroughLayerPauseFB -----------
04-25 22:09:18.452  1083  4259 I MRSS    : MIXEDREALITY: MrClientModelStates: setLayerPaused true layer -1 for client 4030635500.
04-25 22:09:18.452  1083  4259 I MRSS    : MIXEDREALITY: MrSvc: PassthroughLayer: setPaused: Layer -1 is being paused
04-25 22:09:18.452  3792  4046 I GuardianRender: Suspend: Passthrough layer paused
04-25 22:09:18.452  3792  4046 I GuardianRender: Suspend: GuardianRender suspend complete.
04-25 22:09:18.452  1083  1481 I MRSS    : MIXEDREALITY: PassthroughPresentationLayerImpl: Deallocating framebuffers for layer -1.
04-25 22:09:18.453  1083  1481 I MRSS    : MIXEDREALITY: GlFramebufferFactory: framebuffer pool releasing buffer (elements in pool=0)
04-25 22:09:18.453  1083  1481 I MRSS    : MIXEDREALITY: GlFramebufferFactory: framebuffer pool cached the released buffer (elements in pool=1). GlobalCount: 1
04-25 22:09:18.453  1055  1471 D TelemetryService: PathwayGuard: safety_state_suspended is not allowed to send from OSSDK/NDK; dropping; Please use the Statsd approach https://www.internalfb.com/intern/wiki/HzOS_Systems/HzOS_Systems_Performance_and_Reliability/VROS_System_Insights/VROS_Telemetry/XFN/Statsd/
04-25 22:09:18.453  1055  1471 D TelemetryService: Reported oculus_telemetry_blocked_ossdk_events breach event
04-25 22:09:18.453  3792  4046 I GuardianTelemetry: [ovrStatsThreadGuardian::SendGuardianInterstitialEventTelemetry] Sending guardian interstitial event type START, frame type BLACK, reason UNMOUNTED, duration 0.000000, timestampInMs 1221122, order 98
04-25 22:09:18.453  1768  4055 I VrRuntimeService: RuntimeServiceSDKServer::ReleaseClientFrameAsync
04-25 22:09:18.453  1768  4387 I VrRuntimeService: RuntimeServiceSDKServer::UpdateGuardianInterstitialRequest 1
04-25 22:09:18.453  1768  4387 I InterstitialManager: Guardian request: black
04-25 22:09:18.461  1768  2307 I InterstitialRenderer: SetTransitionType: background=0
04-25 22:09:18.461  1768  2307 I InterstitialRenderer: ovrInterstitialRenderer::Start frameType=2, clientPackageName=com.oculus.vrshell, description=Guardian black launchId=
04-25 22:09:18.461  1768  2307 I CompositorClientManager: Enable client 0
04-25 22:09:18.461  1768  2307 I CompositorServer: EnableCompositorClient client(0): default aperture id(0) layer placement(4294967291) flags(8) sm(0x7c68ebd318)
04-25 22:09:18.461  1768  2307 I InterstitialRendererLoggerSession: ---------- InterstitialRenderer LoggerSession Begin ----------
04-25 22:09:18.461  1768  2307 I InterstitialRendererLoggerSession: ID: dc305f252a467e8447b91d595e27cd2a
04-25 22:09:18.461  1768  2307 I InterstitialRendererLoggerSession: Frame Type: Black[2]
04-25 22:09:18.461  1768  2307 I InterstitialRendererLoggerSession: Event Type: start
04-25 22:09:18.461  1768  2307 I InterstitialRendererLoggerSession: Status: Started [3]
04-25 22:09:18.461  1768  2307 I InterstitialRendererLoggerSession: [Start Time 1221131]
04-25 22:09:18.461  1768  2307 I InterstitialRendererLoggerSession:      [event_type: start]
04-25 22:09:18.461  1768  2307 I InterstitialRendererLoggerSession:      [launch_id: ]
04-25 22:09:18.461  1768  2307 I InterstitialRendererLoggerSession:      [last_top_activity_start: com.oculus.sdk.xrcontrollers]
04-25 22:09:18.461  1768  2307 I InterstitialRendererLoggerSession:      [top_activity_start: com.oculus.vrshell]
04-25 22:09:18.461  1768  2307 I InterstitialRendererLoggerSession:      [description: Guardian black]
04-25 22:09:18.461  1768  2307 I InterstitialRendererLoggerSession:      [client_package_name: com.oculus.vrshell]
04-25 22:09:18.461  1768  2307 I InterstitialRendererLoggerSession:      [transition_type: 0]
04-25 22:09:18.461  1768  2307 I InterstitialRendererLoggerSession:      [frame_type: 2]
04-25 22:09:18.461  1768  2307 I InterstitialRendererLoggerSession:
04-25 22:09:18.461  1768  2307 I InterstitialRendererLoggerSession: ---------- InterstitialRenderer LoggerSession End ----------
04-25 22:09:18.461  1768  2307 I InterstitialRendererLoggerSession: SessionId: dc305f252a467e8447b91d595e27cd2a, Event: start, FrameType: Black, Reason: Guardian black, Duration: 0
04-25 22:09:18.461  1768  2307 I InterstitialRenderer: Starting logsoftTimer_
04-25 22:09:18.465  1083  1481 W PhaseSync: [PS_Ver_1_0] DiscardFrame is triggered, frame->Stage 2 FrameBeginIndex 518
04-25 22:09:18.465  1083  1481 I MRSS    : MIXEDREALITY: OpenXrSupport: OpenXR qualified success: xrBeginFrame(clientState_.session, &beginFrameDesc): XR_FRAME_DISCARDED
04-25 22:09:18.465  1083  1481 I MRSS    : MIXEDREALITY: MrServiceView: renderIteration: Sleeping the rendering thread for 500 ms since ID is off and no active PT layers.
04-25 22:09:18.476  1768  2061 I EnvironmentBlendModeHelper: Client 3 (com.oculus.vrguardianservice) changed environment blend mode to INVALID
({}, {})
04-25 22:09:18.586  1342  1665 W SntpClient: Unknown host: time.android.com
04-25 22:09:18.587  1342  1665 D SntpClient: request time failed
04-25 22:09:18.641  1055 30732 E healthcounters: healthcounters::hcstore: Error while uploading: Curl error: [6] Couldn't resolve host name (Could not resolve host: graph.facebook.com)
04-25 22:09:18.642  1768  2307 I PerformanceManager_ZSF: perfmgr: SetClockLevels: Apply pending clock request change: 3,3 -> 3,2
04-25 22:09:18.642  1768  2307 I Clocks  : ApplySystemModes( cpu=3, gpu=2 ) - start
04-25 22:09:18.642  1768  2307 I Clocks  : CPU levels [0, 6]
04-25 22:09:18.642  1768  2307 I Clocks  : GPU levels [0, 7]
04-25 22:09:18.642  1768  2307 I ClocksChange: Changing SystemModes to: [VR_MODE_CPU_3,VR_MODE_GPU_2,VR_MODE_CX_0,VR_MODE_OVERRIDE_FOREGROUND_TO_CONTENT_CORES,SUSTAINED_PERFORMANCE_VR_MODE,SUSTAINED_PERFORMANCE_VR_MODE,SUSTAINED_PERFORMANCE_VR_MODE,SUSTAINED_PERFORMANCE_VR_MODE,SUSTAINED_PERFORMANCE_VR_MODE,SUSTAINED_PERFORMANCE_VR_MODE,SUSTAINED_PERFORMANCE_VR_MODE,SUSTAINED_PERFORMANCE_VR_MODE,SUSTAINED_PERFORMANCE_VR_MODE,SUSTAINED_PERFORMANCE_VR_MODE,SUSTAINED_PERFORMANCE_VR_MODE,SUSTAINED_PERFORMANCE_VR_MODE,SUSTAINED_PERFORMANCE_VR_MODE,SUSTAINED_PERFORMANCE_VR_MODE,SUSTAINED_PERFORMANCE_VR_MODE,SUSTAINED_PERFORMANCE_VR_MODE,SUSTAINED_PERFORMANCE_VR_MODE,SUSTAINED_PERFORMANCE_VR_MODE,SUSTAINED_PERFORMANCE_VR_MODE,SUSTAINED_PERFORMANCE_VR_MODE,SUSTAINED_PERFORMANCE_VR_MODE,SUSTAINED_PERFORMANCE_VR_MODE]
04-25 22:09:18.642  1768  2307 I Clocks  : ApplySystemModes( cpu=3, gpu=2 ) - end (0.1 ms)
04-25 22:09:18.653  3792  4046 I StateMachine: StateMachine::HandleEvent BoundaryNotFound: tryRelocalizeActiveSafetyBound -> RelocalizingGracePeriodState
04-25 22:09:18.653  3792  4046 I GuardianMapMgr: [GuardianMapMgr::SetCachedGroundPlaneHeightErrorCode] Updating cached ground plane height error code from 99998 to 0
04-25 22:09:18.689   851  1192 I SyncBossHAL: [info   ] syncboss_hal_powerstate.c(54): Reporting powerstate event: PROX_ON
04-25 22:09:18.689   851  1192 D SensorService: Received Powerstate PROX_ON
04-25 22:09:18.690  1342  1784 I VrPowerManagerService: transition from HEADSET_UNMOUNTED to HEADSET_MOUNTED
04-25 22:09:18.690  1090  1297 I TrackingService: ActivityMonitor: prox_on event received
04-25 22:09:18.690  1083 11816 D SensorClientUtils: Received power state from VRPM: RUNNING
04-25 22:09:18.690  1083 11816 D PowerMonitor: Received transition request from STANDBY to RUNNING
04-25 22:09:18.690  1088  1740 D SensorClientUtils: Received power state from VRPM: RUNNING
04-25 22:09:18.690  1088  1740 D PowerMonitor: Received transition request from STANDBY to RUNNING
04-25 22:09:18.690  1088  1740 D PowerMonitor: PowerGateProxy: apply power state 'Running'
04-25 22:09:18.690  1090  5737 D PowerMonitor: PowerGateDelegate: apply power state 'Running'
04-25 22:09:18.690  1090  5737 D PowerMonitor: Received transition request from STANDBY to RUNNING
04-25 22:09:18.690  2124  2210 D VrPowerManagerClient: Headset state: 1
04-25 22:09:18.690  2119  2186 D SensorClientUtils: Received power state from VRPM: RUNNING
04-25 22:09:18.690  2119  2186 D PowerMonitor: Received transition request from STANDBY to RUNNING
04-25 22:09:18.690  2124  2210 V ControllerNotifications: Headset was mounted, requesting that notifications are enabled
04-25 22:09:18.690  2124  2210 V ControllerNotifications: Queueing enable of notifications in 5000ms
04-25 22:09:18.691  1055  1471 D telemetry: Transitioning to session state: Active Session, Headset In Use (donned and screen on), from state Active Session, Headset Not In Use
04-25 22:09:18.691  2974  2974 D [SEO] VrNotificationFeedManager: ScreenStateReceiver received broadcast: com.oculus.intent.action.MOUNT_STATE_CHANGED
04-25 22:09:18.691  2974  2974 D [SEO] VrNotificationFeedManager: ScreenStateReceiver mount state changed: true
04-25 22:09:18.691  2974  2974 V DeviceLifecycleManager: Got action: com.oculus.intent.action.MOUNT_STATE_CHANGED
04-25 22:09:18.691  2974  2974 V DeviceLifecycleManager: Mount state changed: isMounted = true
04-25 22:09:18.691   842   868 I SyncBossFW: 1213141 [info   ] {PROX}: Prox state: 1
04-25 22:09:18.706     0     0 I init    : processing action (sys.hmt.mounted=1) from (/vendor/etc/init/hw/init.eureka.rc:12)
04-25 22:09:18.706     0     0 I init    : processing action (sys.hmt.mounted=1) from (/vendor/etc/init/hw/init.anorak.rc:436)
04-25 22:09:18.693  1342  1369 I AS.AudioService: Setting mMicMuteFromPrivacyToggle to false
04-25 22:09:18.693  1342  1369 I AS.AudioService: Mic mute false, user=0
04-25 22:09:18.693  2124  2526 I MicService.Privacy: Setting mic sensor privacy to false, reason: POWER_STATE, mounted: true, sensor lock engaged: false, setting: false, user: 0
04-25 22:09:18.695  1342  2348 I Telecom : CallAudioRouteStateMachine: Message received: MUTE_EXTERNALLY_CHANGED=3004, arg1=0: CARSM.mCR->CARSM.pM_MUTE_EXTERNALLY_CHANGED@AIo
04-25 22:09:18.695  1342  2348 I Telecom : QuiescentSpeakerRoute: Processing message MUTE_EXTERNALLY_CHANGED: CARSM.mCR->CARSM.pM_MUTE_EXTERNALLY_CHANGED@AIo
04-25 22:09:18.698  1768  2307 I UnifiedTelemetryWrapper: Started UT session don
04-25 22:09:18.704  2974  4062 I VrRuntimeClient: UpdateEvents: HMT was mounted
04-25 22:09:18.718  2974  3162 I [SEO] VrPlatformOpenXr: xrPollEvent: Unknown event
04-25 22:09:18.718  2974  3162 I [SEO] VrPlatformOpenXr: xrPollEvent: received XR_TYPE_EVENT_DATA_USER_PRESENCE_CHANGED_EXT event: isUserPresent 1
04-25 22:09:18.730  2974  3162 I [SEO] ShellApp: ResolveDeviceState: change from WaitingForSleep to Awake
04-25 22:09:18.730  2974  3162 I [SEO] ShellApp: ResolveDeviceState: isWakingOrUnlockingIntoWake : 0
04-25 22:09:18.730  2974  3162 I [SEO] ShellApp: ResolveDeviceState: interactionState : InputFocus
04-25 22:09:18.730  2974  3162 I [SEO] ShellApp: ResolveDeviceState: Cycling input focus
04-25 22:09:18.730  2974  3162 I [SEO] ShellApp: HMD Mount State Changed - mounted - duration in prior state 0.957583s
04-25 22:09:18.730  2974  3162 I [SEO] LocalSpace: ResetNextFrame
04-25 22:09:18.730  2974  3086 D [UNSET] ShellApplication: performFocusCycle
04-25 22:09:18.730  2974  3086 D [SEO] ShellService: setOverlayInputFocus : entrypoint - inputFocus=false
04-25 22:09:18.730  2974  2974 D [SEO] ShellService: setOverlayInputFocus(0) : Vwr(1)
04-25 22:09:18.730  2974  2974 D FocusPlaceholderActivity: Finishing activity
04-25 22:09:18.730  2974  3162 I [SEO] ShellEnvHomeController: ShellEnvHomeController: processing 3 anchors
04-25 22:09:18.730  2974  3162 I [SEO] ShellEnvHomeController: curio anchorConfiguration created with env size x: 1.000000, y: 1.000000
04-25 22:09:18.730  2974  3162 I [SEO] ShellEnvHomeController: portal anchorConfiguration created with env size x: 1.000000, y: 1.000000
04-25 22:09:18.730  2974  3162 I [SEO] ShellEnvHomeController: theater anchorConfiguration created with env size x: 4.660000, y: 2.621250
04-25 22:09:18.731  2974  3162 I [SEO] VrShellDesktopBase: BackgroundDesktop: Spatial, reason guardian
04-25 22:09:18.731  2974  3162 I [SEO] DesktopPanelController: Backgrounded desktop Spatial with reason guardian
04-25 22:09:18.731  2974  3162 I [SEO] VirtualObjectSystem: Send anchorUpdatedIPC to VirtualObjects service {"AnchorState":{"curio_01":"curio_01","portal_01":"portal_01","theater":"theater"}}
04-25 22:09:18.731  5605  5605 I [SUI] AnytimeUIServicePanelApp: Bar visibility changed to false
04-25 22:09:18.734  5605  5605 I [SUI] GuardianManager: Stop Polling
04-25 22:09:18.734  2974  2974 W [SEO] ShellService: setOverlayInputFocus : no valid immersive volumetric window token found. Focusing the default display instead.
04-25 22:09:18.734  5605  5605 I [SUI] RunningAppManager: onBarVisibilityChanged: false
04-25 22:09:18.734  2974  2974 D [SEO] ShellService: setOverlayInputFocus : completed - inputFocus=false
04-25 22:09:18.734  2974  5874 D IPCService: Sending command: entity {"mr_object": null}
04-25 22:09:18.734  5605  5885 E FollowUserOnlineRepo: Exception thrown while getting userId and scheduling next follow online check
04-25 22:09:18.734  5605  5885 E FollowUserOnlineRepo: kotlinx.coroutines.JobCancellationException: StandaloneCoroutine was cancelled; job=StandaloneCoroutine{Cancelling}@1da024b
04-25 22:09:18.734  2974  3162 I [SEO] GuardianController: ShouldAllowShellUi changed from 1 to 0
04-25 22:09:18.735  2974  3162 I [SEO] GuardianController: IsShellEnvironmentAllowed changed from 1 to 0
04-25 22:09:18.735  2974  2974 I OpenGLRenderer: setStopped(1)
04-25 22:09:18.736  2974  2974 I OpenGLRenderer: setStopped(1): complete
04-25 22:09:18.736  2974  5874 D [UNSET] ShellApplication: Sending command: entity {"mr_object": null} to panelId: 37
04-25 22:09:18.736  2974  2974 W WindowOnBackDispatcher: sendCancelIfRunning: isInProgress=falsecallback=android.view.ViewRootImpl$$ExternalSyntheticLambda17@304f5b9
04-25 22:09:18.737  2974  3162 I [SEO] AnchorStateSystem: Setting pinning active false
04-25 22:09:18.737  2974  3162 I [SEO] AnchorStateSystem: Setting tethering active false
04-25 22:09:18.737  2974  3162 I Shell   : copresence: microphone state transition, unpaused
04-25 22:09:18.737  2974  3170 W .oculus.vrshell: Binder 0x7c7385f350 destroyed after setInheritRt before being parceled.
04-25 22:09:18.737  2974  3170 W .oculus.vrshell: Binder 0x7c13135ca0 destroyed after setInheritRt before being parceled.
04-25 22:09:18.737  2974  3170 W .oculus.vrshell: Binder 0x7c13131418 destroyed after setInheritRt before being parceled.
04-25 22:09:18.737  2974  3170 W .oculus.vrshell: Binder 0x7c125abb88 destroyed after setInheritRt before being parceled.
04-25 22:09:18.737  2974  5874 D IPCService: Sending command: entity {"portal": null}
04-25 22:09:18.738  1342  1360 V WindowManager: Sent Transition #333 createdAt=04-25 22:09:18.731 via request=TransitionRequestInfo { type = CLOSE, triggerTask = TaskInfo{userId=0 taskId=946 displayId=0 isRunning=false baseIntent=Intent { flg=0x10810100 cmp=com.oculus.vrshell/.FocusPlaceholderActivity } baseActivity=null topActivity=null origActivity=null realActivity=ComponentInfo{com.oculus.vrshell/com.oculus.vrshell.FocusPlaceholderActivity} numActivities=0 lastActiveTime=1217080 supportsMultiWindow=true resizeMode=1 isResizeable=true minWidth=-1 minHeight=-1 defaultMinSize=1 token=WCT{RemoteToken{b2e53e4 Task{cb73f9 #946 type=standard A=10020:com.oculus.vrshell:-339196040}}} topActivityType=1 pictureInPictureParams=null shouldDockBigOverlays=false launchIntoPipHostTaskId=-1 lastParentTaskIdBeforePip=-1 displayCutoutSafeInsets=null topActivityInfo=null launchCookies=[] telemetryLaunchIds=[] positionInParent=Point(0, 0) parentTaskId=6 isFocused=false isVisible=true isVisibleRequested=true isSleeping=false topActivityInSizeCompat=false topActivityEligibleForLetterboxEducation= false topActivityLetterboxed= false isFromDoubleTap= false topActivityLetterboxVerticalPosition= -1 topActivityLetterboxHorizontalPosition= -1 topActivityLetterboxWidth=-1 topActivityLetterboxHeight=-1 locusId=null displayAreaFeatureId=1 cameraCompatControlState=hidden}, remoteTransition = null, displayChange = null }
04-25 22:09:18.738  1342  1360 V WindowManager:     startWCT=WindowContainerTransaction { changes = {} hops = [] errorCallbackToken=null taskFragmentOrganizer=null }
04-25 22:09:18.738  1342  1360 V WindowManager:     info={id=333 t=CLOSE f=0x0 trk=0 r=[0@Point(0, 0)] c=[{WCT{RemoteToken{b2e53e4 Task{cb73f9 #946 type=standard A=10020:com.oculus.vrshell:-339196040}}} m=CLOSE f=NONE p=WCT{RemoteToken{d1a0691 Task{acf2fc2 #6 type=undefined}}} leash=Surface(name=Task=946)/@0x17a6976 sb=Rect(8256, 0 - 8257, 1) eb=Rect(8256, 0 - 8257, 1) d=0},{WCT{RemoteToken{d1a0691 Task{acf2fc2 #6 type=undefined}}} m=TO_BACK f=TRANSLUCENT leash=Surface(name=Task=6)/@0xc8fe8f9 sb=Rect(8256, 0 - 8257, 1) eb=Rect(8256, 0 - 8257, 1) eo=Point(8256, 0) d=0}]}
04-25 22:09:18.738  1342  1356 W InputManager-JNI: Input channel object 'ab4e813 com.oculus.vrshell/com.oculus.vrshell.FocusPlaceholderActivity (client)' was disposed without first being removed with the input manager!
04-25 22:09:18.739  2974  3162 I [SEO] LocalSpace: Reset - initialRawFromLocalPose: quat(0.122370, -0.740858, 0.257055, 0.608340), translation(1.409631, 0.215794, -1.426157)
04-25 22:09:18.739  2974  3162 I [SEO] LocalSpace: Applying VRAPI Reorient, the reorient reason is 3
04-25 22:09:18.739  1768  4080 I VrRuntimeService: RuntimeServiceSDKServer::RecenterLocalSpace
04-25 22:09:18.739  1768  4080 I VrRuntimeService: RuntimeServiceSDKServer::RecenterLocalSpace_Internal
04-25 22:09:18.739  1768  4080 I VrSpaceState: UpdateLocalSpace
04-25 22:09:18.739  1768  4080 I VrSpaceState: SetStateBatched in UpdateLocalSpace
04-25 22:09:18.739  1768  4080 I VrSpaceState: Recenter the position
04-25 22:09:18.739  1768  4080 I VrSpaceState: shouldRecenter the position: 0
04-25 22:09:18.739  1768  4080 I VrSpaceState: UpdateLocalSpace: Src: 2, Version: 81, VersionUser: 3, RawFloorHeightOld: -0.956960, RawFloorHeightNew: -0.956960
04-25 22:09:18.739  1768  4080 I VrRuntimeService: RuntimeServiceSDKServer::UpdateRSSDKSpaces_Internal
04-25 22:09:18.739  2974  4062 I VrRuntimeClient: WarpSwap: hasInhibitedLayer = 1, currentAudioInhibited = 0
04-25 22:09:18.739  2974  4062 W ScreenCaptureHelper: setInhibitAudioCapture: Inhibit audio capture. Init policy: 1
04-25 22:09:18.739  1342  6381 D CoreBackPreview: Window{ab4e813 u0 com.oculus.vrshell/com.oculus.vrshell.FocusPlaceholderActivity EXITING}: Setting back callback null
04-25 22:09:18.740  2974  5874 D [UNSET] ShellApplication: Sending command: entity {"portal": null} to panelId: 37
04-25 22:09:18.740  1342  1360 V WindowManager: Finish Transition #333: created at 04-25 22:09:18.731 collect-started=0.025ms request-sent=0.313ms started=4.061ms ready=4.037ms sent=6.269ms finished=8.53ms
04-25 22:09:18.741  2974  5746 D IPCService: Sending command: entity {"mr_object":{"parent":"#markupAnchor:curio_01","position":[0,1,0],"orientation":[0,0,0],"model":{"modelUri":"apk:\/\/com.oculus.systemux\/assets\/mr_object.clay","animLoopUri":"apk:\/\/com.oculus.systemux\/assets\/mr_object.clay\/Content\/anim\/curio_idle.fbx.joint1.anim.asset","animTransitionDuration":0.1},"inputInteractables":{"interactableInfos":[{"shapeType":"Sphere","positionOffset":[0,0.3,0],"radius":0.5,"interactionEventTypes":["Poke","Select"]}]},"emissiveFactorAnimation":{"material":"curio.fbx.lander_metal_pbr_mat.mat","start":1745604558,"end":1745604559,"startColor":[3,3,3],"endColor":[0,0,0]},"tintAnimation":{"material":"curio.fbx.lander_flame_outer_mat.mat","start":1745604558,"end":1745604559,"startColor":[4,4,4,1],"endColor":[1,1,1,1]},"locomotionObstacle":{"blockingDistance":0.5}}}
04-25 22:09:18.741  1768  2061 I ClientSurface: ClientSurface.setSecure: vr_compositor secure=1
04-25 22:09:18.743  2974  5746 D [UNSET] ShellApplication: Sending command: entity {"mr_object":{"parent":"#markupAnchor:curio_01","position":[0,1,0],"orientation":[0,0,0],"model":{"modelUri":"apk:\/\/com.oculus.systemux\/assets\/mr_object.clay","animLoopUri":"apk:\/\/com.oculus.systemux\/assets\/mr_object.clay\/Content\/anim\/curio_idle.fbx.joint1.anim.asset","animTransitionDuration":0.1},"inputInteractables":{"interactableInfos":[{"shapeType":"Sphere","positionOffset":[0,0.3,0],"radius":0.5,"interactionEventTypes":["Poke","Select"]}]},"emissiveFactorAnimation":{"material":"curio.fbx.lander_metal_pbr_mat.mat","start":1745604558,"end":1745604559,"startColor":[3,3,3],"endColor":[0,0,0]},"tintAnimation":{"material":"curio.fbx.lander_flame_outer_mat.mat","start":1745604558,"end":1745604559,"startColor":[4,4,4,1],"endColor":[1,1,1,1]},"locomotionObstacle":{"blockingDistance":0.5}}} to panelId: 37
04-25 22:09:18.743  1342  1342 I VolumetricTaskOrganizer: onTaskActivitiesDisappeared: TaskInfo{userId=0 taskId=6 displayId=0 isRunning=false baseIntent=Intent {  } baseActivity=null topActivity=null origActivity=null realActivity=null numActivities=0 lastActiveTime=13266 supportsMultiWindow=true resizeMode=2 isResizeable=true minWidth=-1 minHeight=-1 defaultMinSize=1 token=WCT{RemoteToken{d1a0691 Task{acf2fc2 #6 type=undefined}}} topActivityType=1 pictureInPictureParams=null shouldDockBigOverlays=false launchIntoPipHostTaskId=-1 lastParentTaskIdBeforePip=-1 displayCutoutSafeInsets=null topActivityInfo=null launchCookies=[] telemetryLaunchIds=[] positionInParent=Point(8256, 0) parentTaskId=-1 isFocused=false isVisible=false isVisibleRequested=false isSleeping=false topActivityInSizeCompat=false topActivityEligibleForLetterboxEducation= false topActivityLetterboxed= false isFromDoubleTap= false topActivityLetterboxVerticalPosition= -1 topActivityLetterboxHorizontalPosition= -1 topActivityLetterboxWidth=-1 topActivityLetterboxHeight=-1 locusId=null displayAreaFeatureId=1 cameraCompatControlState=hidden}
04-25 22:09:18.744  1342  1342 I VolumetricWindow: Releasing volumetric window, token: d1a0691, persistable token: 4dd833fb-e489-4a0c-bb6c-de41ef0d51fe
04-25 22:09:18.744  2974  5746 D IPCService: Sending command: entity {"mr_object":{"poseAnimation":{"startPosition":[0,1,0],"endPosition":[0,1,0],"start":1745604558,"end":1745604566,"startOrientation":[0,0,0],"endOrientation":[0,180,0],"animationCallbackId":"0"}}}
04-25 22:09:18.744  1342  1342 I DisplayDeviceRepository: Display device removed: DisplayDeviceInfo{"MirrorRoot for 4dd833fb-e489-4a0c-bb6c-de41ef0d51fe": uniqueId="virtual:android,1000,MirrorRoot for 4dd833fb-e489-4a0c-bb6c-de41ef0d51fe,0", 1 x 1, modeId 286, renderFrameRate 60.0, defaultModeId 286, supportedModes [{id=286, width=1, height=1, fps=60.0, alternativeRefreshRates=[], supportedHdrTypes=[]}], colorMode 0, supportedColorModes [0], hdrCapabilities null, allmSupported false, gameContentTypeSupported false, density 200, 200.0 x 200.0 dpi, appVsyncOff 0, presDeadline 16666666, touch NONE, rotation 0, type VIRTUAL, deviceProductInfo null, state OFF, committedState UNKNOWN, owner android (uid 1000), frameRateOverride , brightnessMinimum 0.0, brightnessMaximum 0.0, brightnessDefault 0.0, hdrSdrRatio NaN, FLAG_SECURE, FLAG_SUPPORTS_PROTECTED_BUFFERS, FLAG_OWN_CONTENT_ONLY, FLAG_DESTROY_CONTENT_ON_REMOVAL, installOrientation 0, displayShape DisplayShape{ spec=-1569981477 displayWidth=1 displayHeight=1 physicalPixelDisplaySizeRatio=1.0 rotation=0 offsetX=0 offsetY=0 scale=1.0}}
04-25 22:09:18.745  1342  1342 I LogicalDisplayMapper: Removing display: 123
04-25 22:09:18.745  1768  2061 I ScreenCaptureBase: GlobalInhibitState changed to 1
04-25 22:09:18.746  1342  1342 I VolumetricContainerPlacement: released: ContainerPlacement{mId=2, mBounds=Rect(8256, 0 - 12384, 2208)}
04-25 22:09:18.747  1342  1363 V WindowManager: Unknown focus tokens, dropping reportFocusChanged
04-25 22:09:18.747  1342  1342 W ActivityTaskManager: No component for base intent of task: Task{acf2fc2 #6 type=undefined}
04-25 22:09:18.747  1342  2294 I ImeTracker: com.oculus.vrshell:63d2e0e2: onRequestHide at ORIGIN_SERVER_HIDE_INPUT reason HIDE_SAME_WINDOW_FOCUSED_WITHOUT_EDITOR
04-25 22:09:18.748  1342  2294 I ImeTracker: com.oculus.vrshell:63d2e0e2: onCancelled at PHASE_SERVER_SHOULD_HIDE
04-25 22:09:18.748  1342  1363 I OculusWindowMgr: OWMS::focusChanged: windowRootTaskId: 2
04-25 22:09:18.748  1342  1363 I OculusWindowMgr: OWMS::focusChanged: getImmersiveTask returned
04-25 22:09:18.748  1342  1363 I OculusWindowMgr: OWMS::focusChanged: matchedTaskId: 2
04-25 22:09:18.748  2974  5746 D [UNSET] ShellApplication: Sending command: entity {"mr_object":{"poseAnimation":{"startPosition":[0,1,0],"endPosition":[0,1,0],"start":1745604558,"end":1745604566,"startOrientation":[0,0,0],"endOrientation":[0,180,0],"animationCallbackId":"0"}}} to panelId: 37
04-25 22:09:18.748  1768  1768 I ClientMgr: FocusedWindowChanged: uid = 10020 pid = 2974 clientWindow = 0x7c68e2fe60 clientWindowRefCount = 3
04-25 22:09:18.748  1768  1768 I ClientMgr: FocusedWindowChanged: top window's client already enabled
04-25 22:09:18.748  1768  1768 I ClientMgr: ClientMgr::SetFocusedClient: FocusedClient did not change: [com.oculus.vrshell:2974]
04-25 22:09:18.748  1768  1768 I ClientMgr: ClientMgr::SetFocusedClient: 2974:com.oculus.vrshell type 1
04-25 22:09:18.748  1768  1768 I ClientMgr: ClientMgr::SetFocusedClient: Updating focus state for all clients
04-25 22:09:18.748  1768  1768 I ClientMgr: ClientMgr::SetFocusedClient: Updating Focus State: 0 for Client: com.oculus.vrguardianservice:3792, Client DisplayId: -1
04-25 22:09:18.748  1768  1768 I ClientMgr: ClientMgr::SetFocusedClient: Updating Focus State: 1 for Client: com.oculus.vrshell:2974, Client DisplayId: -1
04-25 22:09:18.748  1768  1768 I ClientMgr: ClientMgr::SetFocusedClient: Updating Focus State: 0 for Client: /system_ext/bin/mrsystemservice:1083, Client DisplayId: 0
04-25 22:09:18.748  1768  1768 I ClientMgr: SetFocusedPackageName - packageName com.oculus.vrshell processName com.oculus.vrshell clientId 1005165173 clientPid 2974
04-25 22:09:18.748  1768  1768 I ServiceInputManager: ServiceInputManager::SetFocusedPackageName 'com.oculus.vrshell'
04-25 22:09:18.748  1768  1768 I TrexControllerServerPlugin: ControllerServerBase Set Focused client: com.oculus.vrshell:2974
04-25 22:09:18.749  1342  1363 D DisplayManagerService: Ignore redundant display event 123/3 to 10094/3917
04-25 22:09:18.749  1342  1497 W system_server: Binder 0xb400007c6b5d1a50 destroyed after setInheritRt before being parceled.
04-25 22:09:18.750  3833  3833 D MetaCam (ScreenshotSessionManager): current package is com.oculus.vrshell
04-25 22:09:18.750  2974  3162 I Shell   : ProgramFile::load path=shaders/mesh/MeshHolepunch.glfx dependencies=6, techniques=8 sampler=15 samplerStates=4 depthStates=6 stencilStates=3 blendStates=12 rasterStates=4
04-25 22:09:18.750  2974  3162 I Shell   : ProgramFile::load [vertex blob] path=shaders/mesh/MeshHolepunch.glfx vertexBlobIndex=0 vertexBlobSize=0
04-25 22:09:18.750  2974  3162 I Shell   : ProgramFile::load [vertex blob] path=shaders/mesh/MeshHolepunch.glfx vertexBlobIndex=1 vertexBlobSize=11076
04-25 22:09:18.750  2974  3162 I Shell   : ProgramFile::load [vertex blob] path=shaders/mesh/MeshHolepunch.glfx vertexBlobIndex=2 vertexBlobSize=11160
04-25 22:09:18.750  2974  3162 I Shell   : ProgramFile::load [geometry blob] path=shaders/mesh/MeshHolepunch.glfx geometryBlobIndex=0 geometryBlobSize=0
04-25 22:09:18.750  2974  3162 I Shell   : ProgramFile::load [fragment blob] path=shaders/mesh/MeshHolepunch.glfx fragmentBlobIndex=0 fragmentBlobSize=0
04-25 22:09:18.750  2974  3162 I Shell   : ProgramFile::load [fragment blob] path=shaders/mesh/MeshHolepunch.glfx fragmentBlobIndex=1 fragmentBlobSize=12028
04-25 22:09:18.750  2974  3162 I Shell   : ProgramFile::load [fragment blob] path=shaders/mesh/MeshHolepunch.glfx fragmentBlobIndex=2 fragmentBlobSize=17676
04-25 22:09:18.750  2974  3162 I Shell   : ProgramFile::load [fragment blob] path=shaders/mesh/MeshHolepunch.glfx fragmentBlobIndex=3 fragmentBlobSize=10312
04-25 22:09:18.750  2974  3162 I Shell   : ProgramFile::load [fragment blob] path=shaders/mesh/MeshHolepunch.glfx fragmentBlobIndex=4 fragmentBlobSize=18340
04-25 22:09:18.750  2974  3162 I Shell   : ProgramFile::load [fragment blob] path=shaders/mesh/MeshHolepunch.glfx fragmentBlobIndex=5 fragmentBlobSize=14504
04-25 22:09:18.750  2974  3162 I Shell   : ProgramFile::load [fragment blob] path=shaders/mesh/MeshHolepunch.glfx fragmentBlobIndex=6 fragmentBlobSize=15172
04-25 22:09:18.750  2974  3162 I Shell   : ProgramFile::load [fragment blob] path=shaders/mesh/MeshHolepunch.glfx fragmentBlobIndex=7 fragmentBlobSize=12028
04-25 22:09:18.750  2974  3162 I Shell   : ProgramFile::load [fragment blob] path=shaders/mesh/MeshHolepunch.glfx fragmentBlobIndex=8 fragmentBlobSize=17676
04-25 22:09:18.750  2974  3162 I Shell   : ProgramFile::load [fragment blob] path=shaders/mesh/MeshHolepunch.glfx fragmentBlobIndex=9 fragmentBlobSize=10312
04-25 22:09:18.750  2974  3162 I Shell   : ProgramFile::load [fragment blob] path=shaders/mesh/MeshHolepunch.glfx fragmentBlobIndex=10 fragmentBlobSize=18340
04-25 22:09:18.750  2974  3162 I Shell   : ProgramFile::load [fragment blob] path=shaders/mesh/MeshHolepunch.glfx fragmentBlobIndex=11 fragmentBlobSize=14504
04-25 22:09:18.750  2974  3162 I Shell   : ProgramFile::load [fragment blob] path=shaders/mesh/MeshHolepunch.glfx fragmentBlobIndex=12 fragmentBlobSize=15172
04-25 22:09:18.750  2974  3162 I Shell   : ProgramFile::load [compute blob] path=shaders/mesh/MeshHolepunch.glfx computeBlobIndex=0 computeBlobSize=0
04-25 22:09:18.750  2974  4062 I Shell   : ResMan::LoadAsync enqueueing job to main executor
04-25 22:09:18.750  2974  3562 I Shell   : Attempting to load program MeshShellEnvHolepunchGlassNoBlackBorder with index 1
04-25 22:09:18.750  2974  3562 I Shell   : Loading shader program MeshShellEnvHolepunchGlassNoBlackBorder
04-25 22:09:18.750  2974  3562 I Shell   : TechniqueVulkan::_getCompiledShaderStages programName=MeshShellEnvHolepunchGlassNoBlackBorder(WriteAlpha)
04-25 22:09:18.751  2974  3132 D [SEO] VolumetricWindowManagerClient: VolumetricWindow VolumetricWindowToken { scope = 1, token = android.os.BinderProxy@315d781, persistableToken = 4dd833fb-e489-4a0c-bb6c-de41ef0d51fe } removed with no record of the matching panel app.
04-25 22:09:18.751  1342  1360 V VolumetricContentMonitor: Removed VW VolumetricWindowToken { scope = 1, token = horizonos.view.VolumetricWindowToken$InternalVolumetricToken@3c9e5f2, persistableToken = 4dd833fb-e489-4a0c-bb6c-de41ef0d51fe }
04-25 22:09:18.751  2974  4062 I VrRuntimeClient: UpdateEvents: Detected LOCAL_FLOOR reference space update. Sending reference space update event
04-25 22:09:18.751  2974  4062 I VrRuntimeClient: UpdateEvents: Detected LOCAL reference space update. Sending reference space update event
04-25 22:09:18.751  2974  2974 D KeyboardInputMethodService: onFinishInput
04-25 22:09:18.751  2974  2974 D KeyboardInputMethodService: onStartInput
04-25 22:09:18.751  2974  3162 I [SEO] VrPlatformOpenXr: xrPollEvent: received XR_TYPE_EVENT_DATA_REFERENCE_SPACE_CHANGE_PENDING event
04-25 22:09:18.751  2974  3162 I [SEO] VrPlatformOpenXr: xrPollEvent: received XR_TYPE_EVENT_DATA_REFERENCE_SPACE_CHANGE_PENDING event
04-25 22:09:18.751  2974  3162 I [SEO] VrPlatformOpenXr: XR_TYPE_EVENT_DATA_REFERENCE_SPACE_CHANGE_PENDING : XR_REFERENCE_SPACE_TYPE_LOCAL
04-25 22:09:18.752  1342  1360 D VolumetricContentMonitor: Focused window changed to RunningWindowInfo{  pid: 2974, uid: 10020, userId: 0, windowToken: android.view.IWindow$Stub$Proxy@c099f14, windowId: com.android.server.wm.WindowState$WindowId@c0903bd, activity: RunningActivityInfo { rootTask: 2, uid: 10020, pid: 2974, userId: 0, activity: com.oculus.vrshell.HomeActivity, windowId: com.android.server.wm.WindowState$WindowId@c0903bd, state: ACTIVITY_STATE_VISIBLE}}, from android.view.IWindow$Stub$Proxy@4a1314e
04-25 22:09:18.753  1083 11816 I MRSS    : MIXEDREALITY: MrServiceController: VrFocusManager::Top Activity Changed, topActivity now is: 'com.oculus.vrshell', immersiveApp now is: 'com.oculus.vrshell'.
04-25 22:09:18.753  1083  1481 I VrRuntimeClient: UpdateEvents: Detected LOCAL_FLOOR reference space update. Sending reference space update event
04-25 22:09:18.753  1083  1481 I VrRuntimeClient: UpdateEvents: Detected LOCAL reference space update. Sending reference space update event
04-25 22:09:18.754  5605  5605 E [SUI] BackgroundActivityManager: CALL item not currently in display
04-25 22:09:18.755  2974  3162 I [SEO] LocalSpace: New Space: quat(-0.104285, -0.019563, -0.183335, 0.977308), translation(0.000000, 0.840466, 0.000000) - Prior Space: quat(-0.104285, -0.019563, -0.183335, 0.977308), translation(0.000000, 0.840466, 0.000000)
04-25 22:09:18.755  2974  3162 I [SEO] LocalSpace: Local space version from 74 to 75, local space user version from 3 to 3.
04-25 22:09:18.757  2974  3162 I [SEO] SystemUXControllerHelper: OnReorient(reason: 3)
04-25 22:09:18.757  2974  3162 I [SEO] TelemetryProvider: aui_reorient system 0 0 0 com.oculus.vrshell
04-25 22:09:18.758  2974  3162 I Shell   : AssetLoading releaseAssets packagePath=com.oculus.systemux/assets/mr_object.clay
04-25 22:09:18.758  2974  3162 I Shell   : AssetLoading releaseAssets packagePath=com.oculus.systemux/assets/mr_object.clay
04-25 22:09:18.758  2974  3162 I Shell   : AssetLoading releaseAssets packagePath=com.oculus.systemux/assets/mr_object.clay
04-25 22:09:18.759  1342  1356 I InputManager: Not applying additional properties for display 123 because the pointer is currently targeting display 0.
04-25 22:09:18.766  1083  1481 W PhaseSync: [PS_Ver_1_0] DiscardFrame is triggered, frame->Stage 2 FrameBeginIndex 519
04-25 22:09:18.766  1083  1481 I MRSS    : MIXEDREALITY: OpenXrSupport: OpenXR qualified success: xrBeginFrame(clientState_.session, &beginFrameDesc): XR_FRAME_DISCARDED
04-25 22:09:18.766  1083  1481 I MRSS    : MIXEDREALITY: MrServiceView: renderIteration: Sleeping the rendering thread for 500 ms since ID is off and no active PT layers.
04-25 22:09:18.768  2974  3162 I [SEO] DisplayStatePreservationController: Applied preserved state by adapter: taskBarPositionAdapter
04-25 22:09:18.768  2974  3162 I [SEO] DisplayStatePreservationController: Applied preserved state by adapter: keyboardPreservationAdapter
04-25 22:09:18.768  2974  3162 I [SEO] DisplayStatePreservationController: Applied preserved state by adapter: spatialPanelsPositionAdapter
04-25 22:09:18.791   851  1192 I SyncBossHAL: [info   ] syncboss_hal_powerstate.c(54): Reporting powerstate event: PROX_OFF
04-25 22:09:18.791   851  1192 D SensorService: Received Powerstate PROX_OFF
04-25 22:09:18.791  2974  3162 W Shell   : Component missing string property: animationCallbackId
04-25 22:09:18.792  1090  1297 I TrackingService: ActivityMonitor: prox_off event received
04-25 22:09:18.792  1342  1784 I VrPowerManagerService: transition from HEADSET_MOUNTED to HEADSET_UNMOUNTED
04-25 22:09:18.792  1083 11816 D SensorClientUtils: Received power state from VRPM: STANDBY
04-25 22:09:18.792  1083 11816 D PowerMonitor: Received transition request from RUNNING to STANDBY
04-25 22:09:18.792  1083 11816 D PowerMonitor: Skipping no-op transition from RUNNING to STANDBY
04-25 22:09:18.792  2974  2974 D [SEO] VrNotificationFeedManager: ScreenStateReceiver received broadcast: com.oculus.intent.action.MOUNT_STATE_CHANGED
04-25 22:09:18.792  2974  2974 D [SEO] VrNotificationFeedManager: ScreenStateReceiver mount state changed: false
04-25 22:09:18.792  1088  1740 D SensorClientUtils: Received power state from VRPM: STANDBY
04-25 22:09:18.792  1088  1740 D PowerMonitor: Received transition request from RUNNING to STANDBY
04-25 22:09:18.792  1088  1740 D PowerMonitor: Skipping no-op transition from RUNNING to STANDBY
04-25 22:09:18.792  1088  1740 D PowerMonitor: PowerGateProxy: apply power state 'Standby'
04-25 22:09:18.792   842   868 I SyncBossFW: 1213243 [info   ] {PROX}: Prox state: 0
04-25 22:09:18.793  1090  5737 D PowerMonitor: PowerGateDelegate: apply power state 'Standby'
04-25 22:09:18.793  1090  5737 D PowerMonitor: Received transition request from RUNNING to STANDBY
04-25 22:09:18.793  1090  5737 D PowerMonitor: Skipping no-op transition from RUNNING to STANDBY
04-25 22:09:18.793  2124  2210 D VrPowerManagerClient: Headset state: 2
04-25 22:09:18.793  2124  2210 V ControllerNotifications: Handling unmount notification
04-25 22:09:18.793  2119  2186 D SensorClientUtils: Received power state from VRPM: STANDBY
04-25 22:09:18.793  2119  2186 D PowerMonitor: Received transition request from RUNNING to STANDBY
04-25 22:09:18.793  1055  1471 D telemetry: Transitioning to session state: Active Session, Headset Not In Use, from state Active Session, Headset In Use (donned and screen on)
04-25 22:09:18.793  2119  2186 D PowerMonitor: Skipping no-op transition from RUNNING to STANDBY
04-25 22:09:18.793  2974  2974 V DeviceLifecycleManager: Got action: com.oculus.intent.action.MOUNT_STATE_CHANGED
04-25 22:09:18.793  2974  2974 V DeviceLifecycleManager: Mount state changed: isMounted = false
04-25 22:09:18.808     0     0 I init    : processing action (sys.hmt.mounted=0) from (/vendor/etc/init/hw/init.eureka.rc:15)
04-25 22:09:18.808     0     0 I init    : processing action (sys.hmt.mounted=0) from (/vendor/etc/init/hw/init.anorak.rc:439)
04-25 22:09:18.794  1768  2307 I UnifiedTelemetryWrapper: Stopped UT session don
04-25 22:09:18.795  2124  2526 I MicService.Privacy: Setting mic sensor privacy to true, reason: POWER_STATE, mounted: false, sensor lock engaged: false, setting: false, user: 0
04-25 22:09:18.795  1342  1369 I AS.AudioService: Setting mMicMuteFromPrivacyToggle to true
04-25 22:09:18.795  1342  1369 I AS.AudioService: Mic mute true, user=0
04-25 22:09:18.796  2974  3162 I Shell   : ResMan::LoadAsync enqueueing job to main executor
04-25 22:09:18.797  2974  3162 I Shell   : ResMan::LoadAsync enqueueing job to main executor
04-25 22:09:18.797  2974  3162 I Shell   : ResMan::LoadAsync enqueueing job to main executor
04-25 22:09:18.797  2974  3162 I Shell   : ResMan::LoadAsync enqueueing job to main executor
04-25 22:09:18.797  1342  2348 I Telecom : CallAudioRouteStateMachine: Message received: MUTE_EXTERNALLY_CHANGED=3004, arg1=0: CARSM.mCR->CARSM.pM_MUTE_EXTERNALLY_CHANGED@AIs
04-25 22:09:18.797  1342  2348 I Telecom : QuiescentSpeakerRoute: Processing message MUTE_EXTERNALLY_CHANGED: CARSM.mCR->CARSM.pM_MUTE_EXTERNALLY_CHANGED@AIs
04-25 22:09:18.797  2974  3162 I Shell   : ResMan::LoadAsync enqueueing job to main executor
04-25 22:09:18.797  2974  3162 I Shell   : ResMan::LoadAsync enqueueing job to main executor
04-25 22:09:18.797  2974  3162 I Shell   : ResMan::LoadAsync enqueueing job to main executor
04-25 22:09:18.797  2974  3162 I Shell   : ResMan::LoadAsync enqueueing job to main executor
04-25 22:09:18.797  2974  3162 I Shell   : ResMan::LoadAsync enqueueing job to main executor
04-25 22:09:18.797  2974  3162 I Shell   : ResMan::LoadAsync enqueueing job to main executor
04-25 22:09:18.797  2974  3162 I Shell   : ResMan::LoadAsync enqueueing job to main executor
04-25 22:09:18.797  2974  3162 I Shell   : ResMan::LoadAsync enqueueing job to main executor
04-25 22:09:18.798  2974  3162 I Shell   : ResMan::LoadAsync enqueueing job to main executor
04-25 22:09:18.798  2974  3162 I Shell   : ResMan::LoadAsync enqueueing job to main executor
04-25 22:09:18.798  2974  3162 I Shell   : ResMan::LoadAsync enqueueing job to main executor
04-25 22:09:18.798  2974  3162 I Shell   : ResMan::LoadAsync enqueueing job to main executor
04-25 22:09:18.798  2974  3162 I Shell   : ResMan::LoadAsync enqueueing job to main executor
04-25 22:09:18.798  2974  3162 I Shell   : ResMan::LoadAsync enqueueing job to main executor
04-25 22:09:18.798  2974  3162 I Shell   : ResMan::LoadAsync enqueueing job to main executor
04-25 22:09:18.799  2974  3162 I Shell   : ResMan::LoadAsync enqueueing job to main executor
04-25 22:09:18.799  2974  3162 I Shell   : ResMan::LoadAsync enqueueing job to main executor
04-25 22:09:18.799  2974  3162 I Shell   : ResMan::LoadAsync enqueueing job to main executor
04-25 22:09:18.799  2974  3162 I Shell   : ResMan::LoadAsync enqueueing job to main executor
04-25 22:09:18.799  2974  3162 I Shell   : ResMan::LoadAsync enqueueing job to main executor
04-25 22:09:18.799  2974  3162 I Shell   : ResMan::LoadAsync enqueueing job to main executor
04-25 22:09:18.799  2974  3162 I Shell   : ResMan::LoadAsync enqueueing job to main executor
04-25 22:09:18.799  2974  3162 I Shell   : ResMan::LoadAsync enqueueing job to main executor
04-25 22:09:18.799  2974  3162 I Shell   : ResMan::LoadAsync enqueueing job to main executor
04-25 22:09:18.799  2974  3162 I Shell   : AssetLoading starting loading packagePath=com.oculus.systemux/assets/mr_object.clay
04-25 22:09:18.799  2974  3162 V Clay    : AndroidStorageManager listAssets(path=com.oculus.systemux/assets/mr_object.clay/Content/anim)
04-25 22:09:18.799  2974  3162 W Shell   : Component missing bool property: depthPrepass
04-25 22:09:18.799  2974  3162 W Shell   : Component missing vec4 property: tint
04-25 22:09:18.799  2974  3162 W Shell   : Component missing string property: animationCallbackId
04-25 22:09:18.799  2974  3162 W Shell   : Component missing string property: animationCallbackId
04-25 22:09:18.799  2974  3162 W Shell   : Component missing vec3 property: startScale
04-25 22:09:18.799  2974  3162 W Shell   : Component missing vec3 property: endScale
04-25 22:09:18.799  2974  3162 I Shell   : ResMan : animation records unloaded: asset=0381f09108efac7c,cc93a0c510e1422e,baea0e3d69f5c1f5,0742d6e44b337561,abef6708e62a2c26,5ad1451b21f671a6,73b64239ac2b7b28,0381f09108efac7c,73b64239ac2b7b28,0381f09108efac7c,73b64239ac2b7b28,0381f09108efac7c,73b64239ac2b7b28,0381f09108efac7c,cc93a0c510e1422e,baea0e3d69f5c1f5,0742d6e44b337561,abef6708e62a2c26,5ad1451b21f671a6,73b64239ac2b7b28,0381f09108efac7c,cc93a0c510e1422e,baea0e3d69f5c1f5,0742d6e44b337561,abef6708e62a2c26,5ad1451b21f671a6,73b64239ac2b7b28,0381f09108efac7c,73b64239ac2b7b28,0381f09108efac7c,73b64239ac2b7b28,0381f09108efac7c,  data=0000007c16851700,0000007c125e1bc0,0000007c0508e800,0000007c12a5a280,0000007c166f1a00,0000007c125e1a40,0000007c131363c0,0000007c1312da00,0000007c125e1980,0000007c0508e740,0000007c1312dac0,0000007c13630600,0000007bf31c45c0,0000007bb1514c00,0000007c13630540,0000007b93e29a00,0000007b93e29b80,0000007c0508e200,0000007c28ca3e00,0000007c1312cc80,0000007c13569780,0000007c13006580,0000007c53be08c0,0000007bb1bb6ac0,0000007bb1721980,0000007c13b76800,0000007b9ac73c80,0000007bb1718780,0000007c12549880,0000007c12f34dc0,0000007c13614bc0,0000007c125e15c0,  dstryFlg=1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,  vec=0000007c13016000/0000007c130161e0/0000007c130161e0,0000007c13adcf90/0000007c13adcfc0/0000007c13adcfc0,0000007c04f668a0/0000007c04f66978/0000007c04f66978,0000007c73919800/0000007c73919878/0000007c73919878,0000007c65a42c00/0000007c65a43638/0000007c65a43638,0000007c12edf000/0000007c12edf7b0/0000007c12edf7b0,0000007c130ecc00/0000007c130ecde0/0000007c130ecde0,0000007c132d3200/0000007c132d33e0/0000007c132d33e0,0000007c132d3400/0000007c132d35e0/0000007c132d35e0,0000007c132d3600/0000007c132d37e0/0000007c132d37e0,0000007bf36f6600/0000007bf36f67e0/0000007bf36f67e0,0000007bf37e0200/0000007bf37e03e0/0000007bf37e03e0,0000007c13bfb400/0000007c13bfb5e0/0000007c13bfb5e0,0000007d167dbc00/0000007d167dbde0/0000007d167dbde0,0000007c3656fb70/0000007c3656fba0/0000007c3656fba0,0000007c04f630a0/0000007c04f63178/0000007c04f63178,0000007d167d8e80/0000007d167d8ef8/0000007d167d8ef8,0000007c36281000/0000007c36281a38/0000007c36281a38,0000007c52c87800/0000007c52c87fb0/0000007c52c87fb0,0000007bf319e000/0000007bf319e1e0/0000007bf319e1e0,0000007d167d0800/0000007d167d09e0/0000007d167d09e0,0000007c1333cc70/0000007c1333cca0/0000007c1333cca0,0000007c04f67e80/0000007c04f67f58/0000007c04f67f58,0000007c1258f280/0000007c1258f2f8/0000007c1258f2f8,0000007c12498c00/0000007c12499638/0000007c12499638,0000007b997f8000/0000007b997f87b0/0000007b997f87b0,0000007c133b8c00/0000007c133b8de0/0000007c133b8de0,0000007c52c89c00/0000007c52c89de0/0000007c52c89de0,0000007bf3329800/0000007bf33299e0/0000007bf33299e0,0000007c12658600/0000007c126587e0/0000007c126587e0,0000007d1662e600/0000007d1662e7e0/0000007d1662e7e0,0000007b999e6800/0000007b999e69e0/0000007b999e69e0,  dstry=0000000035dd44c9,00000000365d74c6,00000000365d74ca,00000000365d74d4,00000000365e3043,00000000365e3065,00000000365e30b9,00000000365e30bb,00000000379bf3f5,00000000379bf3fa,0000000037bf76e6,0000000037bf76e9,0000000037c219dd,0000000037c219e0,0000000037f4b207,0000000037f4b20c,0000000037f4b217,0000000037f4cc33,0000000037f4cc51,0000000037f4cdb3,0000000037f4cdb7,000000003b3394d0,000000003b3394d6,000000003b3394df,000000003b33f2d7,000000003b33f2f7,000000003b33f35b,000000003b33f35e,0000000047891b45,0000000047891b49,0000000047cc7f7b,0000000047cc7f7e,  unld=0000000035dd8ad5,00000000365d7540,00000000365d7544,00000000365d7550,0000000036c8989f,0000000036c898a3,0000000036c898a5,0000000036c898a7,00000000379c266f,00000000379c2673,0000000037bfc85f,0000000037bfc863,0000000037c26805,0000000037c26808,0000000037f4b2aa,0000000037f4b2af,0000000037f4b2ba,000000003845df10,000000003845df14,000000003845df17,000000003845df1a,000000003b339558,000000003b33955c,000000003b339567,000000003b812fd9,000000003b812fdd,000000003b812fe0,000000003b812fe2,00000000478967c9,00000000478967cd,0000000047cd2119,0000000047cd211c,
04-25 22:09:18.799  2974  3162 I Shell   : ResMan : skeleton records unloaded: asset=ce59eb5b21877373,ad1d546910d55e1c,34783a2bddc21bab,ce59eb5b21877373,ad1d546910d55e1c,34783a2bddc21bab,ce59eb5b21877373,ad1d546910d55e1c,34783a2bddc21bab,ce59eb5b21877373,6b99f4955119ac93,41c08b6ebf9a15fe,4b472f0ca38475a6,61e9a3be9d7d51b1,540ab81aec5fe1a5,ad1d546910d55e1c,34783a2bddc21bab,ce59eb5b21877373,6b99f4955119ac93,41c08b6ebf9a15fe,4b472f0ca38475a6,61e9a3be9d7d51b1,540ab81aec5fe1a5,ad1d546910d55e1c,34783a2bddc21bab,ce59eb5b21877373,ad1d546910d55e1c,34783a2bddc21bab,ce59eb5b21877373,ad1d546910d55e1c,34783a2bddc21bab,ce59eb5b21877373,  data=0000007c052d25e0,0000007bb1aa3ca0,0000007c1660d320,0000007c1660ff80,0000007c13a8a860,0000007c28e44420,0000007c1660eb80,0000007c1336f5e0,0000007c052d8960,0000007c052dc240,0000007b99ae5d40,0000007c28cf0bc0,0000007c13a8cca0,0000007c04fc7720,0000007c052d4520,0000007c052d5ce0,0000007c52ecfd80,0000007c05002680,0000007c13808080,0000007c13808b20,0000007c13d840e0,0000007c16611f60,0000007c05005e20,0000007c052d5920,0000007c04fc8e40,0000007c04fc9700,0000007c052d3e40,0000007c04cb3fe0,0000007c04cb4580,0000007ba7e4ef40,0000007bf38bf460,0000007bf38bf960,  dstryFlg=1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,  vec=0000007c136bfa00/0000007c136bfbe0/0000007c136bfbe0,0000007c13098400/0000007c130985e0/0000007c130985e0,0000007c130ece00/0000007c130ecfe0/0000007c130ecfe0,0000007c130ec000/0000007c130ec1e0/0000007c130ec1e0,0000007bf36f6400/0000007bf36f65e0/0000007bf36f65e0,0000007b9c740400/0000007b9c7405e0/0000007b9c7405e0,0000007d167d5600/0000007d167d57e0/0000007d167d57e0,0000007c13bfb200/0000007c13bfb3e0/0000007c13bfb3e0,0000007c12edd600/0000007c12edd7e0/0000007c12edd7e0,0000007c13098800/0000007c130989e0/0000007c130989e0,0000007c3656fba0/0000007c3656fbd0/0000007c3656fbd0,0000007d166eed60/0000007d166eee38/0000007d166eee38,0000007d167d8e00/0000007d167d8e78/0000007d167d8e78,0000007c65a43800/0000007c65a44238/0000007c65a44238,0000007c52eca800/0000007c52ecafb0/0000007c52ecafb0,0000007c130ec600/0000007c130ec7e0/0000007c130ec7e0,0000007d167daa00/0000007d167dabe0/0000007d167dabe0,0000007c65a33c00/0000007c65a33de0/0000007c65a33de0,0000007bf3845510/0000007bf3845540/0000007bf3845540,0000007c13ceac80/0000007c13cead58/0000007c13cead58,0000007d167d9900/0000007d167d9978/0000007d167d9978,0000007c73b1e400/0000007c73b1ee38/0000007c73b1ee38,0000007b99967000/0000007b999677b0/0000007b999677b0,0000007c364f1200/0000007c364f13e0/0000007c364f13e0,0000007c167d4400/0000007c167d45e0/0000007c167d45e0,0000007c050e4000/0000007c050e41e0/0000007c050e41e0,0000007c13373000/0000007c133731e0/0000007c133731e0,0000007c136cb200/0000007c136cb3e0/0000007c136cb3e0,0000007c125c2800/0000007c125c29e0/0000007c125c29e0,0000007d1662e400/0000007d1662e5e0/0000007d1662e5e0,0000007c1265d000/0000007c1265d1e0/0000007c1265d1e0,0000007c73b21e00/0000007c73b21fe0/0000007c73b21fe0,  dstry=00000000365e30ee,00000000379bf3f7,00000000379bf3fb,00000000379bf460,0000000037bf76e7,0000000037bf76ea,0000000037bf7707,0000000037c219dd,0000000037c219e1,0000000037c219fb,0000000037f4b208,0000000037f4b20d,0000000037f4b217,0000000037f4cc34,0000000037f4cc51,0000000037f4cdb5,0000000037f4cdb7,0000000037f4cff9,000000003b3394d1,000000003b3394d7,000000003b3394e0,000000003b33f2d8,000000003b33f2f8,000000003b33f35c,000000003b33f35f,000000003b33f37c,0000000047891b46,0000000047891b4a,0000000047891b72,0000000047cc7f7c,0000000047cc7f7f,0000000047cc7f9e,  unld=0000000036c898bf,00000000379c2672,00000000379c2674,00000000379c268c,0000000037bfc861,0000000037bfc865,0000000037bfc87b,0000000037c26807,0000000037c26809,0000000037c2681d,0000000037f4b2ac,0000000037f4b2b0,0000000037f4b2bc,000000003845df12,000000003845df15,000000003845df18,000000003845df1b,000000003845df37,000000003b33955a,000000003b33955d,000000003b339568,000000003b812fdc,000000003b812fde,000000003b812fe1,000000003b812fe3,000000003b812ffa,00000000478967cc,00000000478967ce,00000000478967e8,0000000047cd211b,0000000047cd211d,0000000047cd2132,
04-25 22:09:18.799  2974  3162 I Shell   : ResMan : skinnedmesh records unloaded: asset=eef55bd0115fe740,15836d16c62a44e4,7f25237c3559658a,98c675ea0a8323b8,cd759786744bb544,e787e6b35b16c649,881ca57dc7699740,f2b1e010903e09de,86176a61cabf829e,368152949d14a3d5,26e3706515d81c77,e9210540f91e5d93,23de1a14aa91265f,733399a505ec5218,56d7c8906a47955e,055b00856c2267dd,d22ba1d1c5d52c95,b571a775044e95c8,0b741971bec0f668,eef55bd0115fe740,15836d16c62a44e4,7f25237c3559658a,98c675ea0a8323b8,cd759786744bb544,e787e6b35b16c649,881ca57dc7699740,f2b1e010903e09de,86176a61cabf829e,368152949d14a3d5,26e3706515d81c77,e9210540f91e5d93,23de1a14aa91265f,  data=0000007d1660c800,0000007c125c2a00,0000007bf3329c00,0000007c13cf2e00,0000007c136cac00,0000007c1270aa00,0000007c36399200,0000007c36397c00,0000007c12658c00,0000007c125c2400,0000007c137aa800,0000007c125c2e00,0000007c36399800,0000007c13a3ec00,0000007d1662f800,0000007c1273c400,0000007c1270aa00,0000007c1265de00,0000007c13a3e800,0000007c1273c800,0000007c125c2a00,0000007c13ded000,0000007c12658800,0000007c13ded400,0000007d167d0000,0000007c1265d600,0000007c1270ae00,0000007b999e6a00,0000007c13a40000,0000007c12627a00,0000007c12658600,0000007c1265d200,  dstryFlg=1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,  vec=0000007d1660ca00/0000007d1660cbe0/0000007d1660cbe0,0000007c125c2c00/0000007c125c2de0/0000007c125c2de0,0000007bf3329e00/0000007bf3329fe0/0000007bf3329fe0,0000007c136cb000/0000007c136cb1e0/0000007c136cb1e0,0000007c136cae00/0000007c136cafe0/0000007c136cafe0,0000007c12ae6c00/0000007c12ae6de0/0000007c12ae6de0,0000007c36399000/0000007c363991e0/0000007c363991e0,0000007c36397600/0000007c363977e0/0000007c363977e0,0000007c12658e00/0000007c12658fe0/0000007c12658fe0,0000007c125c2600/0000007c125c27e0/0000007c125c27e0,0000007c13a57000/0000007c13a571e0/0000007c13a571e0,0000007c12627600/0000007c126277e0/0000007c126277e0,0000007c36399400/0000007c363995e0/0000007c363995e0,0000007c13a3ee00/0000007c13a3efe0/0000007c13a3efe0,0000007d1662fc00/0000007d1662fde0/0000007d1662fde0,0000007c1273c600/0000007c1273c7e0/0000007c1273c7e0,0000007c1270ac00/0000007c1270ade0/0000007c1270ade0,0000007c1270a800/0000007c1270a9e0/0000007c1270a9e0,0000007c13a3ea00/0000007c13a3ebe0/0000007c13a3ebe0,0000007c1273ca00/0000007c1273cbe0/0000007c1273cbe0,0000007c125c2c00/0000007c125c2de0/0000007c125c2de0,0000007c13ded200/0000007c13ded3e0/0000007c13ded3e0,0000007c12658a00/0000007c12658be0/0000007c12658be0,0000007c12627800/0000007c126279e0/0000007c126279e0,0000007d167d0c00/0000007d167d0de0/0000007d167d0de0,0000007c1265da00/0000007c1265dbe0/0000007c1265dbe0,0000007c1273c000/0000007c1273c1e0/0000007c1273c1e0,0000007b999e6c00/0000007b999e6de0/0000007b999e6de0,0000007c73b21c00/0000007c73b21de0/0000007c73b21de0,0000007c12627c00/0000007c12627de0/0000007c12627de0,0000007c1265dc00/0000007c1265dde0/0000007c1265dde0,0000007c1265d400/0000007c1265d5e0/0000007c1265d5e0,  dstry=0000000047891b59,0000000047891b5b,0000000047891b5d,0000000047891b5e,0000000047891b5f,0000000047891b61,0000000047891b63,0000000047891b66,0000000047891b68,0000000047891b69,0000000047891b6b,0000000047891b6e,0000000047891b70,0000000047cc7f83,0000000047cc7f86,0000000047cc7f88,0000000047cc7f89,0000000047cc7f8b,0000000047cc7f8d,0000000047cc7f8f,0000000047cc7f90,0000000047cc7f91,0000000047cc7f92,0000000047cc7f93,0000000047cc7f95,0000000047cc7f96,0000000047cc7f98,0000000047cc7f99,0000000047cc7f9a,0000000047cc7f9b,0000000047cc7f9c,0000000047cc7f9d,  unld=00000000478967d7,00000000478967d8,00000000478967da,00000000478967db,00000000478967dc,00000000478967de,00000000478967df,00000000478967e0,00000000478967e2,00000000478967e3,00000000478967e4,00000000478967e5,00000000478967e6,0000000047cd211f,0000000047cd2120,0000000047cd2121,0000000047cd2122,0000000047cd2123,0000000047cd2124,0000000047cd2125,0000000047cd2126,0000000047cd2127,0000000047cd2128,0000000047cd2129,0000000047cd212a,0000000047cd212b,0000000047cd212c,0000000047cd212d,0000000047cd212e,0000000047cd212f,0000000047cd2130,0000000047cd2131,
({}, {})
04-25 22:09:18.802  2974  4062 I VrRuntimeClient: UpdateEvents: HMT was UNmounted
04-25 22:09:18.802  2974  3561 I Shell   : ResMan::LoadAsync enqueueing job to main executor
04-25 22:09:18.803  2974  3561 W Shell   : Unable to find loader for Asset resource id: 1027948401568, path: ''.  File may not exist.
04-25 22:09:18.805  2974  3162 W Shell   : Failed to load asset asynchronously: type=Asset  id=1027948401568  path=
04-25 22:09:18.806  2974  3162 I [SEO] VrPlatformOpenXr: xrPollEvent: Unknown event
04-25 22:09:18.806  2974  3162 I [SEO] VrPlatformOpenXr: xrPollEvent: received XR_TYPE_EVENT_DATA_USER_PRESENCE_CHANGED_EXT event: isUserPresent 0
04-25 22:09:18.812  2974  3162 I [SEO] ShellApp: ResolveDeviceState: change from Awake to WaitingForSleep
04-25 22:09:18.812  2974  3162 I [SEO] ShellApp: ResolveDeviceState: isWakingOrUnlockingIntoWake : 0
04-25 22:09:18.812  2974  3162 I [SEO] ShellApp: ResolveDeviceState: interactionState : InputFocus
04-25 22:09:18.812  2974  3162 I [SEO] ShellFrameAnalytics: Shell Heartbeat focusedPanelTimeSpent: {"-1":97} visibleTimeSpent: {"2":13} inputTimeSpent: {"-1":"{\"focus_controller\":97}","2":"{\"visible_controller\":13}"} inputClickCount: {} mouseMoveTimeSpent: 0 mouseScrollTimeSpent: 0 physicalKeyboardConnectedTimeSpent: 0 physicalKeyboardTrackedTimeSpent: 0 quickActionsMenuActivations: 0 quickActionsMenuTimeSpent: 0 quickActionsMenuSessionLengths: [] quickActionsMenuSelections: {} handTrackingOptIn: 1 handTrackingAutoTransition: 1 directTouchTabletEnabled: 97
04-25 22:09:18.813  2974  3162 I [SEO] ShellApp: HMD Mount State Changed - unmounted - duration in prior state 0.082350s
04-25 22:09:18.814  2974  3162 I Shell   : AssetLoading done loading: refs=36  packagePath=com.oculus.systemux/assets/mr_object.clay
04-25 22:09:18.814  2974  3162 I Shell   : copresence: microphone state transition, paused
04-25 22:09:18.815  2974  4062 I VrRuntimeClient: WarpSwap: hasInhibitedLayer = 0, currentAudioInhibited = 1
04-25 22:09:18.815  2974  4062 W ScreenCaptureHelper: setInhibitAudioCapture: Restore audio capture. Init policy: 1
04-25 22:09:18.830  2974  3086 D [SEO] ShellService: setOverlayInputFocus : entrypoint - inputFocus=true
04-25 22:09:18.831  2974  2974 D [SEO] ShellService: setOverlayInputFocus(1) : Vwr(1)
04-25 22:09:18.833  1342  6381 I ActivityTaskManager: START u0 {flg=0x10000000 cmp=com.oculus.vrshell/.FocusPlaceholderActivity} with LAUNCH_SINGLE_INSTANCE from uid 10020 (BAL_ALLOW_ALLOWLISTED_COMPONENT) result code=0
04-25 22:09:18.833  2116  2116 I VrKeyguardViewMediator: handleSetOccluded, occluded: false, prevValue: false, mHiding: false
04-25 22:09:18.833  2116  2116 D VrLockscreenHost: setOccluded: false, was: false, force: false, window: null
04-25 22:09:18.833  2974  2974 D [SEO] ShellService: setOverlayInputFocus(1) : FocusPlaceholderActivity started
04-25 22:09:18.833  2974  2974 D [SEO] ShellService: setOverlayInputFocus : completed - inputFocus=true
04-25 22:09:18.834  1342  1361 I VolumetricWindowManagerServiceImpl: Handling SWMS response: launch into NEW_VOLUMETRIC_WINDOW VolumetricWindowPlacementResponse { volumetricToken = VolumetricWindowToken { scope = 1, token = horizonos.view.VolumetricWindowToken$InternalVolumetricToken@b076a7c, persistableToken = 125492bc-9b32-4167-aa7f-ab73515165fe }, pose = Pose { orientation = Vector4f { x = 0.0, y = 0.0, z = 0.0, w = 1.0 }, position = Vector3f { x = 0.0, y = 0.0, z = 0.0 } }, bounds = Extent3f { width = 0.0, height = 0.0, depth = 0.0 }, surfaceSize = 1x1, densityDpi = 200 } for launch request [ActivityInfo{c07d6f com.oculus.vrshell.FocusPlaceholderActivity}](vrdesktop seq: 1)
04-25 22:09:18.834  1342  1361 I VolumetricContainerPlacement: acquired ContainerPlacement{mId=2, mBounds=Rect(8256, 0 - 12384, 2208)}
04-25 22:09:18.836  1342  1361 E CellBroadcastUtils: getDefaultCellBroadcastReceiverPackageName: no package found
04-25 22:09:18.837  1768  2061 I ClientSurface: ClientSurface.setSecure: vr_compositor secure=0
04-25 22:09:18.838   840   887 D perfservice: [POWERHINT] Received LAUNCH hint launchStr: LAUNCH
04-25 22:09:18.840  1768  2061 I ScreenCaptureBase: GlobalInhibitState changed to 0
04-25 22:09:18.842  1342  1361 I ActivityTaskManager: START u0 {flg=0x10810100 cmp=com.oculus.vrshell/.FocusPlaceholderActivity} with LAUNCH_SINGLE_INSTANCE from uid 10020 (realCallingUid=1000) (BAL_ALLOW_ALLOWLISTED_COMPONENT) result code=0
04-25 22:09:18.844  1342  1361 I DisplayDeviceRepository: Display device added: DisplayDeviceInfo{"MirrorRoot for 125492bc-9b32-4167-aa7f-ab73515165fe": uniqueId="virtual:android,1000,MirrorRoot for 125492bc-9b32-4167-aa7f-ab73515165fe,0", 1 x 1, modeId 290, renderFrameRate 60.0, defaultModeId 290, supportedModes [{id=290, width=1, height=1, fps=60.0, alternativeRefreshRates=[], supportedHdrTypes=[]}], colorMode 0, supportedColorModes [0], hdrCapabilities null, allmSupported false, gameContentTypeSupported false, density 200, 200.0 x 200.0 dpi, appVsyncOff 0, presDeadline 16666666, touch NONE, rotation 0, type VIRTUAL, deviceProductInfo null, state OFF, committedState UNKNOWN, owner android (uid 1000), frameRateOverride , brightnessMinimum 0.0, brightnessMaximum 0.0, brightnessDefault 0.0, hdrSdrRatio NaN, FLAG_SECURE, FLAG_SUPPORTS_PROTECTED_BUFFERS, FLAG_OWN_CONTENT_ONLY, FLAG_DESTROY_CONTENT_ON_REMOVAL, installOrientation 0, displayShape DisplayShape{ spec=-1569981477 displayWidth=1 displayHeight=1 physicalPixelDisplaySizeRatio=1.0 rotation=0 offsetX=0 offsetY=0 scale=1.0}}
04-25 22:09:18.845  1342  1361 I LogicalDisplayMapper: Applying layout: [{dispId: 0(ON), displayGroupName: , addr: {port=146, model=0x40446da74f6028}, mThermalBrightnessThrottlingMapId: null, mRefreshRateZoneId: null, mLeadDisplayId: -1, mThermalRefreshRateThrottlingMapId: null}], Previous layout: [{dispId: 0(ON), displayGroupName: , addr: {port=146, model=0x40446da74f6028}, mThermalBrightnessThrottlingMapId: null, mRefreshRateZoneId: null, mLeadDisplayId: -1, mThermalRefreshRateThrottlingMapId: null}]
04-25 22:09:18.845  1342  1361 I LogicalDisplayMapper: Setting new display group 0 for display 125, from previous group: null
04-25 22:09:18.845  1342  1361 I LogicalDisplayMapper: Adding new display: 125: DisplayInfo{"MirrorRoot for 125492bc-9b32-4167-aa7f-ab73515165fe", displayId 125, displayGroupId 0, FLAG_SECURE, FLAG_SUPPORTS_PROTECTED_BUFFERS, real 1 x 1, largest app 1 x 1, smallest app 1 x 1, appVsyncOff 0, presDeadline 16666666, mode 29060.0, defaultMode 290, modes [{id=290, width=1, height=1, fps=60.0, alternativeRefreshRates=[], supportedHdrTypes=[]}], hdrCapabilities null, userDisabledHdrTypes [], minimalPostProcessingSupported false, rotation 0, state OFF, committedState UNKNOWN, type VIRTUAL, uniqueId "virtual:android,1000,MirrorRoot for 125492bc-9b32-4167-aa7f-ab73515165fe,0", app 1 x 1, density 200 (200.0 x 200.0) dpi, layerStack 125, colorMode 0, supportedColorModes [0], deviceProductInfo null, owner android (uid 1000), removeMode 1, refreshRateOverride 0.0, brightnessMinimum 0.0, brightnessMaximum 0.0, brightnessDefault 0.0, installOrientation ROTATION_0, layoutLimitedRefreshRate null, hdrSdrRatio not_available, thermalRefreshRateThrottling {}, thermalBrightnessThrottlingDataId default}
04-25 22:09:18.846  1342  1361 W BrightnessThrottler: DeviceConfig ThermalBrightnessThrottlingData is null
04-25 22:09:18.846  1342  1361 W DisplayPowerController2[125]: Screen brightness nits configuration is unavailable; falling back
04-25 22:09:18.846  1342  1363 D DisplayManagerService: Ignore redundant display event 125/1 to 10094/3917
04-25 22:09:18.848  1342  1388 V DisplayPowerController2[125]: Brightness [0.0] reason changing to: 'manual', previous reason: '0'.
04-25 22:09:18.848  1342  1388 I DisplayPowerController2[125]: BrightnessEvent: disp=125, physDisp=virtual:android,1000,MirrorRoot for 125492bc-9b32-4167-aa7f-ab73515165fe,0, brt=0.0, initBrt=NaN, rcmdBrt=NaN, preBrt=NaN, lux=0.0, preLux=0.0, hbmMax=1.0, hbmMode=off, rbcStrength=-1, thrmMax=1.0, powerFactor=1.0, wasShortTermModelActive=false, flags=, reason=manual, autoBrightness=false, strategy=InvalidBrightnessStrategy
04-25 22:09:18.854  1342  1361 I WindowManager: Override config changes=20005df8 {0.0 ?mcc0mnc ?localeList ?layoutDir sw1dp w1dp h1dp 200dpi smll port ?uimode ?night -touch -keyb/v/h -nav/h winConfig={ mBounds=Rect(0, 0 - 1, 1) mAppBounds=Rect(0, 0 - 1, 1) mMaxBounds=Rect(0, 0 - 1, 1) mDisplayRotation=ROTATION_0 mWindowingMode=fullscreen mDisplayWindowingMode=fullscreen mActivityType=undefined mAlwaysOnTop=undefined mRotation=ROTATION_0} ?fontWeightAdjustment} for displayId=125
04-25 22:09:18.856  1342  1361 I VolumetricWindowManagerServiceImpl: onTaskActivitiesAppeared: baseActivity = null, addVolumetricWindow = VolumetricWindowInfo { volumetricWindowToken = VolumetricWindowToken { scope = 1, token = horizonos.view.VolumetricWindowToken$InternalVolumetricToken@b076a7c, persistableToken = 125492bc-9b32-4167-aa7f-ab73515165fe }, type = 1, bounds = Extent3f { width = 0.0, height = 0.0, depth = 0.0 }, pose = Pose { orientation = Vector4f { x = 0.0, y = 0.0, z = 0.0, w = 1.0 }, position = Vector3f { x = 0.0, y = 0.0, z = 0.0 } }, windowSize2d = Extent2i { width = 1, height = 1 }, visible = false, persistable = false, windowFlags = 0, priorityHintInfo = PriorityHintInfo { windowPpd = 0.0, viewAngleSine = -1.0, visiblePercent = 1.0, distanceFromUser = 0.0 }, presentation = VolumetricWindowPresentationInfo { controlBarMenu = null } }
04-25 22:09:18.857  1342  1363 D DisplayManagerService: Ignore redundant display event 125/2 to 10094/3917
04-25 22:09:18.857  1342  1363 D DisplayManagerService: Ignore redundant display event 125/2 to 10094/3917
04-25 22:09:18.857  1342  1363 D DisplayManagerService: Ignore redundant display event 125/2 to 10111/4235
04-25 22:09:18.857  1342  6380 D CoreBackPreview: Window{815d326 u0 Splash Screen com.oculus.vrshell}: Setting back callback OnBackInvokedCallbackInfo{mCallback=android.window.IOnBackInvokedCallback$Stub$Proxy@4d05e29, mPriority=0, mIsAnimationCallback=false}
04-25 22:09:18.858  1342  1363 D DisplayManagerService: Ignore redundant display event 125/2 to 10122/4352
04-25 22:09:18.858  1342  1363 D DisplayManagerService: Ignore redundant display event 125/2 to 10094/3917
04-25 22:09:18.860  1342  1361 V WindowManager: Queueing transition: TransitionRecord{e7ddc86 id=-1 type=SLEEP flags=0x0}
04-25 22:09:18.860  1342  1361 V WindowManager: Content Recording: Display 125 state is now (1), so update recording?
04-25 22:09:18.860  2974  2974 I VrosSpatialAudio: init with android.os.BinderProxy@ab4c301
04-25 22:09:18.861  2974  2974 D FocusPlaceholderActivity: onCreate() - window.setContentView
04-25 22:09:18.861  4517  4584 E OpenGLRenderer: Unable to match the desired swap behavior.
04-25 22:09:18.861  4517  4579 I OpenGLRenderer: setStopped(0)
04-25 22:09:18.861  4517  4579 I OpenGLRenderer: setStopped(0): complete
04-25 22:09:18.861  4517  4579 I OpenGLRenderer: setStopped(0)
04-25 22:09:18.862  3792  4046 I VrRuntimeClient: UpdateEvents: Detected LOCAL_FLOOR reference space update. Sending reference space update event
04-25 22:09:18.862  3792  4046 I VrRuntimeClient: UpdateEvents: Detected LOCAL reference space update. Sending reference space update event
04-25 22:09:18.862  4517  4579 I OpenGLRenderer: setStopped(0): complete
04-25 22:09:18.865  1342  1360 V WindowManager: Sent Transition #335 createdAt=04-25 22:09:18.836 via request=TransitionRequestInfo { type = OPEN, triggerTask = TaskInfo{userId=0 taskId=947 displayId=0 isRunning=true baseIntent=Intent { flg=0x10810100 cmp=com.oculus.vrshell/.FocusPlaceholderActivity } baseActivity=ComponentInfo{com.oculus.vrshell/com.oculus.vrshell.FocusPlaceholderActivity} topActivity=ComponentInfo{com.oculus.vrshell/com.oculus.vrshell.FocusPlaceholderActivity} origActivity=null realActivity=ComponentInfo{com.oculus.vrshell/com.oculus.vrshell.FocusPlaceholderActivity} numActivities=1 lastActiveTime=1221509 supportsMultiWindow=true resizeMode=1 isResizeable=true minWidth=-1 minHeight=-1 defaultMinSize=1 token=WCT{RemoteToken{9f3d2e3 Task{401818b #947 type=standard A=10020:com.oculus.vrshell:-339196040}}} topActivityType=1 pictureInPictureParams=null shouldDockBigOverlays=false launchIntoPipHostTaskId=-1 lastParentTaskIdBeforePip=-1 displayCutoutSafeInsets=null topActivityInfo=ActivityInfo{5a424e0 com.oculus.vrshell.FocusPlaceholderActivity} launchCookies=[] telemetryLaunchIds=[] positionInParent=Point(0, 0) parentTaskId=6 isFocused=false isVisible=false isVisibleRequested=true isSleeping=false topActivityInSizeCompat=false topActivityEligibleForLetterboxEducation= false topActivityLetterboxed= false isFromDoubleTap= false topActivityLetterboxVerticalPosition= -1 topActivityLetterboxHorizontalPosition= -1 topActivityLetterboxWidth=-1 topActivityLetterboxHeight=-1 locusId=null displayAreaFeatureId=1 cameraCompatControlState=hidden}, remoteTransition = null, displayChange = null }
04-25 22:09:18.865  1342  1360 V WindowManager:     startWCT=WindowContainerTransaction { changes = {} hops = [] errorCallbackToken=null taskFragmentOrganizer=null }
04-25 22:09:18.865  1342  1360 V WindowManager:     info={id=335 t=OPEN f=0x0 trk=0 r=[0@Point(0, 0)] c=[{WCT{RemoteToken{9f3d2e3 Task{401818b #947 type=standard A=10020:com.oculus.vrshell:-339196040}}} m=OPEN f=NO_ANIMATION|MOVE_TO_TOP p=WCT{RemoteToken{d1a0691 Task{acf2fc2 #6 type=undefined}}} leash=Surface(name=Task=947)/@0x1617a9d sb=Rect(8256, 0 - 8257, 1) eb=Rect(8256, 0 - 8257, 1) d=0},{WCT{RemoteToken{d1a0691 Task{acf2fc2 #6 type=undefined}}} m=TO_FRONT f=TRANSLUCENT|NO_ANIMATION|MOVE_TO_TOP leash=Surface(name=Task=6)/@0xc8fe8f9 sb=Rect(8256, 0 - 8257, 1) eb=Rect(8256, 0 - 8257, 1) eo=Point(8256, 0) d=0}]}
04-25 22:09:18.865  1342  1360 V WindowManager: Sent Transition #336 createdAt=04-25 22:09:18.860 via request=TransitionRequestInfo { type = SLEEP, triggerTask = null, remoteTransition = null, displayChange = null }
04-25 22:09:18.865  1342  1360 V WindowManager:     startWCT=null
04-25 22:09:18.865  1342  1360 V WindowManager:     info={id=336 t=SLEEP f=0x0 trk=0 r=[] c=[]}
04-25 22:09:18.866  1342  1360 V WindowManager: Finish Transition #335: created at 04-25 22:09:18.836 collect-started=0.036ms request-sent=4.53ms started=7.232ms ready=7.214ms sent=28.594ms finished=30.499ms
04-25 22:09:18.868  1342  1342 W Transition: Transition already started id=336 state=2
04-25 22:09:18.868  1342  1360 V WindowManager: Finish Transition #336: created at 04-25 22:09:18.860 collect-started=4.657ms request-sent=4.885ms started=4.909ms ready=4.908ms sent=5.001ms finished=8.185ms
04-25 22:09:18.868  2974  2974 I [SEO] ShellSpatialWindowManagerService: onVolumetricWindowCreated has invoked on VolumetricWindowToken { scope = 1, token = android.os.BinderProxy@f83c394, persistableToken = 125492bc-9b32-4167-aa7f-ab73515165fe }
04-25 22:09:18.870  1342  6380 D CoreBackPreview: Window{81df65e u0 com.oculus.vrshell/com.oculus.vrshell.FocusPlaceholderActivity}: Setting back callback OnBackInvokedCallbackInfo{mCallback=android.window.IOnBackInvokedCallback$Stub$Proxy@1d6af0c, mPriority=0, mIsAnimationCallback=false}
04-25 22:09:18.872  2974  3170 E OpenGLRenderer: Unable to match the desired swap behavior.
04-25 22:09:18.872  2974  2974 I OpenGLRenderer: setStopped(0)
04-25 22:09:18.872  2974  2974 I OpenGLRenderer: setStopped(0): complete
04-25 22:09:18.872  2974  2974 I OpenGLRenderer: setStopped(0)
04-25 22:09:18.873  2974  2974 I OpenGLRenderer: setStopped(0): complete
04-25 22:09:18.874  1342  1360 I ActivityTaskManager: Displayed com.oculus.vrshell/.FocusPlaceholderActivity for user 0: +39ms
04-25 22:09:18.878  1342  1713 E libcrcs : Failed to parse PSI from some avg10=0.00 avg60=0.00 avg300=0.01 total=5154322
04-25 22:09:18.878  1342  1713 E libcrcs : Failed to parse PSI from full avg10=0.00 avg60=0.00 avg300=0.00 total=2788473
04-25 22:09:18.878  1342  1713 E libcrcs : Failed to parse PSI from some avg10=7.91 avg60=4.21 avg300=3.79 total=56993122
04-25 22:09:18.879  1342  1713 E libcrcs : Failed to parse PSI from some avg10=0.00 avg60=0.00 avg300=0.00 total=148079
04-25 22:09:18.879  1342  1713 E libcrcs : Failed to parse PSI from full avg10=0.00 avg60=0.00 avg300=0.00 total=27952
04-25 22:09:18.884  1342  1363 I OculusWindowMgr: OWMS::focusChanged: windowRootTaskId: 6
04-25 22:09:18.884  1342  1363 I OculusWindowMgr: OWMS::focusChanged: getImmersiveTask returned
04-25 22:09:18.884  1342  1363 I OculusWindowMgr: OWMS::focusChanged: mFocusPlaceholderActivity found in Task.
04-25 22:09:18.884  1768  1768 I ClientMgr: FocusedWindowChanged: uid = 10020 pid = 2974 clientWindow = 0x7cf960b430 clientWindowRefCount = 2
04-25 22:09:18.884  1768  1768 I ClientMgr: FocusedWindowChanged: falling back to client with same uid: 10020, and pid: 2974
04-25 22:09:18.884  1768  1768 I ClientMgr: ClientMgr::SetFocusedClient: FocusedClient did not change: [com.oculus.vrshell:2974]
04-25 22:09:18.884  1768  1768 I ClientMgr: ClientMgr::SetFocusedClient: 2974:com.oculus.vrshell type 1
04-25 22:09:18.884  1768  1768 I ClientMgr: ClientMgr::SetFocusedClient: Updating focus state for all clients
04-25 22:09:18.884  1768  1768 I ClientMgr: ClientMgr::SetFocusedClient: Updating Focus State: 0 for Client: com.oculus.vrguardianservice:3792, Client DisplayId: -1
04-25 22:09:18.884  1768  1768 I ClientMgr: ClientMgr::SetFocusedClient: Updating Focus State: 1 for Client: com.oculus.vrshell:2974, Client DisplayId: -1
04-25 22:09:18.884  1768  1768 I ClientMgr: ClientMgr::SetFocusedClient: Updating Focus State: 0 for Client: /system_ext/bin/mrsystemservice:1083, Client DisplayId: 0
04-25 22:09:18.884  1768  1768 I ClientMgr: SetFocusedPackageName - packageName com.oculus.vrshell processName com.oculus.vrshell clientId 1005165173 clientPid 2974
04-25 22:09:18.884  1768  1768 I ServiceInputManager: ServiceInputManager::SetFocusedPackageName 'com.oculus.vrshell'
04-25 22:09:18.884  1768  1768 I TrexControllerServerPlugin: ControllerServerBase Set Focused client: com.oculus.vrshell:2974
04-25 22:09:18.885  3833  3833 D MetaCam (ScreenshotSessionManager): current package is com.oculus.vrshell
04-25 22:09:18.885  1342  1360 D VolumetricContentMonitor: Focused window changed to RunningWindowInfo{  pid: 2974, uid: 10020, userId: 0, windowToken: android.view.IWindow$Stub$Proxy@1066055, windowId: com.android.server.wm.WindowState$WindowId@7cf1a6a, activity: RunningActivityInfo { rootTask: 6, uid: 10020, pid: 2974, userId: 0, activity: com.oculus.vrshell.FocusPlaceholderActivity, windowId: com.android.server.wm.WindowState$WindowId@7cf1a6a, state: ACTIVITY_STATE_VISIBLE}}, from android.view.IWindow$Stub$Proxy@c099f14
04-25 22:09:18.887  1083 11816 I MRSS    : MIXEDREALITY: MrServiceController: VrFocusManager::Top Activity Changed, topActivity now is: 'com.oculus.vrshell', immersiveApp now is: 'com.oculus.vrshell'.
04-25 22:09:18.887  5605  5605 E [SUI] BackgroundActivityManager: CALL item not currently in display
04-25 22:09:18.898  1083  1481 W PhaseSync: [PS_Ver_1_0] DiscardFrame is triggered, frame->Stage 2 FrameBeginIndex 520
04-25 22:09:18.898  1083  1481 I MRSS    : MIXEDREALITY: OpenXrSupport: OpenXR qualified success: xrBeginFrame(clientState_.session, &beginFrameDesc): XR_FRAME_DISCARDED
04-25 22:09:18.899  1083  1481 I MRSS    : MIXEDREALITY: MrServiceView: renderIteration: Sleeping the rendering thread for 500 ms since ID is off and no active PT layers.
04-25 22:09:18.933  1090  1871 I Tracking: INPUT_TRACKING: ControllerInHandClassifier Right (0x588f630ca2fcd77f): Transition from InHand -> OutOfHand due to VerticalCollision (-0.00371 -> 34.4).
04-25 22:09:18.935  1090  1871 D TrackingService: Collision detected for device=588f630ca2fcd77f: valid=1, timestamp=1221.598448, magnitude=39.33, duration=0.0020140000
04-25 22:09:18.969  1090 30279 I Tracking: CONSTELLATION_Right: [Throttled count=8] P+C Right: Not reinitializing 6DoF filter yet (no valid pose from vision).
04-25 22:09:18.969  1090  1871 I Tracking: INPUT_TRACKING:ControllerMotionModel: [Right] t = 1213.417110000 [sec], behavior transition: None (=0) -> NearHeadMoving (=2)
04-25 22:09:18.970  2119  2358 D ControllerManagement: RemoteService::recordControllerStatusUpdate: device 588f630ca2fcd77f: status: CONNECTED_ACTIVE tracking: ORIENTATION, errors: --
04-25 22:09:18.970  2124  2210 I ControllerNotifications: Skipping input notifications for device 0 (state=ConnectedBatteryNormal) (mShuttingDown?: false, mNotificationsEnabled?: false, mHandTrackingEnabled?: false, mMultimodalityEnabled?: false)
04-25 22:09:18.974   846  1262 I VD628x  : Flicker results: freq 100.000000, max 99.921387, dcr 41.830616, pct 0.047649, idx 0.004563, conf 0.956321, snr -6.080499, sus 1
04-25 22:09:19.020  1342  1943 E system_server: BinderClientNDK: Failed to locate expected service type oculus.internal.tracking.IEyeTrackingService/default
04-25 22:09:19.055  5605  5885 W OVRLibrary: null cursor received for query content://com.oculus.ocms.library/apps/com.oculus.vrshell
04-25 22:09:19.055  5605  5792 W OVRLibrary: null cursor received for query content://com.oculus.ocms.library/apps/com.oculus.guardian
04-25 22:09:19.056  5605  5882 W OVRLibrary: null cursor received for query content://com.oculus.ocms.library/apps/com.oculus.vrshell
04-25 22:09:19.056  5605 19851 W OVRLibrary: null cursor received for query content://com.oculus.ocms.library/apps/com.oculus.systemdriver
({}, {})
04-25 22:09:19.088  2974  4451 I VrApi   : FPS=72/72,Prd=34ms,Tear=0,Early=2,Stale=0,Stale2/5/10/max=0/0/0/0,VSnc=1,Lat=-1,Fov=3,CPU4/GPU=3/3,1651/492MHz,OC=3F,TA=0/0/0,SP=N/N/N,Mem=3196MHz,Free=3390MB,PLS=0,Temp=41.0C/0.0C,TW=1.10ms,App=0.00ms,GD=0.82ms,CPU&GPU=13.70ms,LCnt=2(DR91,LM2),GPU%=0.32,CPU%=0.38(W0.43),DSF=1.00,CFL=12.36/16.82,ICFLp95=15.58,LD=0,SF=1.00,LP=1,DVFS=0
04-25 22:09:19.162  4517  4584 W m.meta.systemui: Binder 0xb400007d09277090 destroyed after setInheritRt before being parceled.
04-25 22:09:19.165  4517  4579 W WindowOnBackDispatcher: sendCancelIfRunning: isInProgress=falsecallback=android.view.ViewRootImpl$$ExternalSyntheticLambda17@8ab1e53
04-25 22:09:19.165  1342  6381 D CoreBackPreview: Window{815d326 u0 Splash Screen com.oculus.vrshell EXITING}: Setting back callback null
04-25 22:09:19.165  1342  6380 W InputManager-JNI: Input channel object '815d326 Splash Screen com.oculus.vrshell (client)' was disposed without first being removed with the input manager!
04-25 22:09:19.175  4517  9094 W m.meta.systemui: Binder 0xb400007d0931b840 destroyed after setInheritRt before being parceled.
04-25 22:09:19.176  4517  9094 W m.meta.systemui: Binder 0xb400007d3092ab88 destroyed after setInheritRt before being parceled.
04-25 22:09:19.176  4517  9094 W m.meta.systemui: Binder 0xb400007d0931b7e8 destroyed after setInheritRt before being parceled.
04-25 22:09:19.191  1768  1768 I VrRuntimeService: nativeScreenStateChanged to on
04-25 22:09:19.191  1768  1768 I VrRuntimeService: Previous running state: RUNNING
04-25 22:09:19.192  3512  3512 W ResourceDownloadHMDMountReceiver: This user doesn't use Assistant at all. Not warming up Assistant.
04-25 22:09:19.193  3792  4046 I SpatialShiftLogger: [SpatialShift][Telemetry][FloorHeight]: [ event: HEADSET_DOFF, eventReason: [n/a], numGuardians: 0 hasActiveSafetyBound: 0 activeSafetyBound: n/a, stageFloorHeight: -0.95696, guardianFloorHeight: -0.972511, isSlamFloorHeightValid: 1 slamFloorHeight: -0.950788 guardianSlamDiff:-0.021723 guardianStageDiff: -0.0155509 ]
04-25 22:09:19.193  3792  4046 I GuardianTelemetryUtil: ~~currrentDonSessionId: 0
04-25 22:09:19.193  3833  3833 D MetaCam (LivestreamDeviceLifecycleObserver): Headset is mounted. Resume livestream.
04-25 22:09:19.193  3833  3833 D MetaCamNative (device_lifecycle_observer): OnDeviceMountedStateChange(is_mounted = 1)
04-25 22:09:19.193  3833  3833 D MetaCam (MountStateReceiver): Received ACTION_MOUNT_STATE_CHANGED intent
04-25 22:09:19.193  3833  3833 D MetaCam (LivestreamDeviceLifecycleObserver): Headset is not mounted. Pause livestream.
04-25 22:09:19.194  3833  3995 D MetaCam (LivestreamSessionStateMachine): LivestreamSessionStateMachine taking com.oculus.metacam.livestreaming.LivestreamSessionAction$HeadsetMounted@cc8c8e action on current state com.oculus.metacam.livestreaming.WaitingForNetwork@d3cd054
04-25 22:09:19.194  3833  3996 D MetaCam (EventBus): Publish the event MountStateEvent
04-25 22:09:19.194  3833  3995 E AnalyticsEvent: could not set key=livestream_session_id value=null
04-25 22:09:19.194  3833  3995 E AnalyticsEvent: could not set key=livestream_video_id value=null
04-25 22:09:19.194  3833  3995 E AnalyticsEvent: could not set key=livestream_broadcast_id value=null
04-25 22:09:19.194  3833  3995 E AnalyticsEvent: could not set key=foreground_package_name value=null
04-25 22:09:19.194  3833  3995 E AnalyticsEvent: could not set key=type value=null
04-25 22:09:19.194  3833  3995 E AnalyticsEvent: could not set key=position value=null
04-25 22:09:19.194  3833  3995 E AnalyticsEvent: could not set key=current_mic_muted value=null
04-25 22:09:19.194  3833  3848 I .oculus.metacam: Method exceeds compiler instruction limit: 18046 in java.lang.Object kotlin.coroutines.jvm.internal.BaseContinuationImpl.invokeSuspend(java.lang.Object)
04-25 22:09:19.194  3833  3902 D MetaCam (LivestreamSessionStateMachine): LivestreamSessionStateMachine taking com.oculus.metacam.livestreaming.LivestreamSessionAction$HeadsetUnmounted@dbf4caf action on current state com.oculus.metacam.livestreaming.WaitingForNetwork@d3cd054
04-25 22:09:19.194  3833  3902 E AnalyticsEvent: could not set key=livestream_session_id value=null
04-25 22:09:19.194  3833  3902 E AnalyticsEvent: could not set key=livestream_video_id value=null
04-25 22:09:19.194  3833  3902 E AnalyticsEvent: could not set key=livestream_broadcast_id value=null
04-25 22:09:19.194  3833  3902 E AnalyticsEvent: could not set key=foreground_package_name value=null
04-25 22:09:19.194  3833  3902 E AnalyticsEvent: could not set key=type value=null
04-25 22:09:19.194  3833  3902 E AnalyticsEvent: could not set key=position value=null
04-25 22:09:19.194  3833  3902 E AnalyticsEvent: could not set key=current_mic_muted value=null
04-25 22:09:19.195  3833  3833 D MetaCamNative (device_lifecycle_observer): OnDeviceMountedStateChange(is_mounted = 0)
04-25 22:09:19.195  3833  3833 D MetaCam (MountStateReceiver): Received ACTION_MOUNT_STATE_CHANGED intent
04-25 22:09:19.195  3833  3994 D MetaCam (EventBus): Publish the event MountStateEvent
04-25 22:09:19.205  3992 31157 E [SP] GraphQLExecutor: LatestPartyInviteNotificationQuery - Request failed
04-25 22:09:19.206  3992 31158 E [SP] GraphQLExecutor: LatestAppInviteNotificationQuery - Request failed
04-25 22:09:19.340  6399 28384 W OVRLibrary: null cursor received for query content://com.oculus.ocms.library/apps/com.oculus.systemdriver
04-25 22:09:19.399  1083  1481 I MRSS    : MIXEDREALITY: GlFramebufferFactory: framebuffer pool clearing the cache
04-25 22:09:19.399  1083  1481 I OpenXR_GPUMemTrace: Swapchain deletion destroy id=320, idx=0
04-25 22:09:19.399  1083  1481 I OpenXR_GPUMemTrace: Swapchain deletion destroy id=320, idx=1
04-25 22:09:19.399  1083  1481 I OpenXR_GPUMemTrace: Swapchain deletion destroy id=320, idx=2
({}, {})
04-25 22:09:19.399  1768  2341 I VrRuntimeService: DestroySwapChain: swapchain=320
04-25 22:09:19.400  1083  1481 I MRSS    : MIXEDREALITY: MrServiceView: renderIteration: Hibernating the rendering thread since ID is off and no active PT layers.
04-25 22:09:19.409  1090  5717 W Tracking: ImuIntegration: Accel axis 1 saturated for 0.07999999076128006 sec at timestamp 1213.849800000 [sec].
04-25 22:09:19.409  1090  5717 W Tracking: ImuIntegration: Collisions detected in 4 samples.
04-25 22:09:19.453  1768  2061 V CompositorUtil: TryDelete: Deleting 1 swapchains
04-25 22:09:19.453  1768  2061 V Context : QueryVersion() - Context Version: 3.2
04-25 22:09:19.453  1768  2061 V CompositorUtil: SwapChainDeferDeletion: destroying texture swap chain id=320 ptr=0x7d1709dc00, client id=1
04-25 22:09:19.454  1768  2061 I SwapChain_Vulkan_Foveated: CreateTextureSwapChainFoveationVulkan: Destroyed foveation resources
04-25 22:09:19.482  1083  1529 I MRSS    : BOLT:HexagonTelemetry: Global DSP utilization: 9.09% (454 / 5003 ms), Scalar Util: 67.50%, HVX Util: 51.03%, HMX Util: 10.94%, DMA Active: 0.00%, Freq: 787.2 MHz
04-25 22:09:19.482  1083  1529 I MRSS    : boltlib: Invoking logging callbacks. onAbort=false
04-25 22:09:19.482  1083  1529 I MRSS    : boltlib: Loaded BoltNN delegates and inference counts in the past 5 secs:
04-25 22:09:19.569  1090  5717 I Tracking: VioSubmapHelpers: Initializing Submap id = 0, version = 783831558 relative to Submap id = 1, version = 1794611049.
({}, {})
04-25 22:09:19.701   846  1262 I VD628x  : Flicker results: freq 100.000000, max 99.997139, dcr 41.363647, pct 0.047649, idx 0.004563, conf 0.982755, snr -6.080499, sus 1
04-25 22:09:19.743  1090  1871 I Tracking: INPUT_TRACKING: ControllerInHandClassifier Right (0x588f630ca2fcd77f): Transition from OutOfHand -> OutOfHand due to Static motion and no button pressed recently.
04-25 22:09:19.814  1090  5717 W Tracking: VEGA:HM:MAP_TRACKING: Validation failed on Submap id = 0, version = 783831558
04-25 22:09:19.823  1090  5717 I Tracking: Vega: [INSIGHTS] Mode=DescriptorVega,6DoF,Agg=48s,Inl=10,30,49,Mem=150.7,FrameDur=7.0,24.4,PoseLat=1.5,2.4,CamImuLat=10.3,30.3,Jit=0.1,0.4,Drops(Cam=0,IMU=0),MeanIr=3640.6,Exp(4.6ms,g=2,I=59)(4.6ms,g=2,I=62)(4.1ms,g=3,I=60)(4.4ms,g=2,I=61)(0.0ms,g=0)(0.0ms,g=0),NonInertEnv=?
04-25 22:09:19.891  1342  1713 E libcrcs : Failed to parse PSI from some avg10=0.00 avg60=0.00 avg300=0.01 total=5156201
04-25 22:09:19.891  1342  1713 E libcrcs : Failed to parse PSI from full avg10=0.00 avg60=0.00 avg300=0.00 total=2790072
04-25 22:09:19.891  1342  1713 E libcrcs : Failed to parse PSI from some avg10=7.38 avg60=4.23 avg300=3.80 total=57034601
04-25 22:09:19.892  1342  1713 E libcrcs : Failed to parse PSI from some avg10=0.00 avg60=0.00 avg300=0.00 total=148079
04-25 22:09:19.892  1342  1713 E libcrcs : Failed to parse PSI from full avg10=0.00 avg60=0.00 avg300=0.00 total=27952
04-25 22:09:19.967  1090  1623 I Tracking: BOLT:HexagonTelemetry: Global DSP utilization: 9.96% (498 / 5002 ms), Scalar Util: 69.89%, HVX Util: 51.86%, HMX Util: 10.51%, DMA Active: 0.00%, Freq: 787.2 MHz
04-25 22:09:19.967  1090  1623 I Tracking: boltlib: Invoking logging callbacks. onAbort=false
04-25 22:09:19.967  1090  1623 I Tracking: boltlib: Loaded BoltNN delegates and inference counts in the past 5 secs: GraphModule:0, dpe_encoder_multihead_fusion_boltnn_delegate:0, dpe_encoder_mg_head_boltnn_delegate:0, dpe_encoder_fusion_boltnn_delegate:0, dpe_encoder_image_backbone_boltnn_delegate:0, WrapModel:0, a2e_boltnn_delegate:0, dpr_process_body_delegate:7, controller_inference:34, dd_boltnn_delegate_batch_256:4, dd_boltnn_delegate_batch_128:0, dd_boltnn_delegate_batch_64:1, dd_boltnn_delegate_batch_32:0, dd_boltnn_delegate_batch_256:21, dd_boltnn_delegate_batch_128:1, dd_boltnn_delegate_batch_64:0, dd_boltnn_delegate_batch_32:0, semantic_keypoints_boltnn_delegate:4
({}, {})
04-25 22:09:20.021  1342  1943 E system_server: BinderClientNDK: Failed to locate expected service type oculus.internal.tracking.IEyeTrackingService/default
04-25 22:09:20.057   822  4254 E PAL: ResourceManager: voteSleepMonitor: 1758: ioctl device is not open
04-25 22:09:20.058   822  4254 I PAL: SessionAlsaUtils: close: 691: No need to free device metadata as device is still active
04-25 22:09:20.060   822  4254 I PAL: ResourceManager: freeFrontEndIds: 6773: stream type 2, freeing 110
04-25 22:09:20.060   822  4254 I PAL: Device: close: 497: Enter. deviceCount 3 for device id 3 (PAL_DEVICE_OUT_SPEAKER)
04-25 22:09:20.060   822  4254 I PAL: Device: close: 511: Exit. deviceCount 2 for device id 3 (PAL_DEVICE_OUT_SPEAKER), exit status 0
04-25 22:09:20.060   822  4254 I PAL: ResourceManager: GetConcurrencyInfo: 5024: stream type 2 Tx conc 0, Rx conc 1, concurrency allowed
04-25 22:09:20.060   822  4254 I PAL: ResourceManager: GetConcurrencyInfo: 5024: stream type 2 Tx conc 0, Rx conc 1, concurrency allowed
04-25 22:09:20.060   822  4254 I PAL: ResourceManager: GetConcurrencyInfo: 5024: stream type 2 Tx conc 0, Rx conc 1, concurrency allowed
04-25 22:09:20.060   822  4254 I PAL: ResourceManager: deregisterStream: 3266: stream type 2
04-25 22:09:20.060   822  4254 I PAL: ResourceManager: compareSharedBEStreamDevAttr: 7090: switchStreams is 0
04-25 22:09:20.060   869  1593 D WindowEngineStream: Duration of previous spatial state for session(369): {
04-25 22:09:20.060   869  1593 D WindowEngineStream:     "duration_ms": 3166,
04-25 22:09:20.060   869  1593 D WindowEngineStream:     "package": "com.oculus.metacam",
04-25 22:09:20.060   869  1593 D WindowEngineStream:     "session": 369,
04-25 22:09:20.060   869  1593 D WindowEngineStream:     "state": {
04-25 22:09:20.060   869  1593 D WindowEngineStream:         "has_volumetric_token": true,
04-25 22:09:20.060   869  1593 D WindowEngineStream:         "has_window_metadata": false,
04-25 22:09:20.060   869  1593 D WindowEngineStream:         "is_spatialized": false,
04-25 22:09:20.060   869  1593 D WindowEngineStream:         "is_visible": false,
04-25 22:09:20.060   869  1593 D WindowEngineStream:         "settings_spatialization_strategy": "window-locked",
04-25 22:09:20.060   869  1593 D WindowEngineStream:         "spatial_settings": {
04-25 22:09:20.060   869  1593 D WindowEngineStream:             "global spatial preference": true,
04-25 22:09:20.060   869  1593 D WindowEngineStream:             "in shell env": true,
04-25 22:09:20.060   869  1593 D WindowEngineStream:             "package spatial preference": true
04-25 22:09:20.060   869  1593 D WindowEngineStream:         }
04-25 22:09:20.060   869  1593 D WindowEngineStream:     },
04-25 22:09:20.060   869  1593 D WindowEngineStream:     "state_start_time": 1745604556894
04-25 22:09:20.060   869  1593 D WindowEngineStream: }
04-25 22:09:20.061   869  1597 W audioserver: Binder 0xb40000730061f978 destroyed after setInheritRt before being parceled.
04-25 22:09:20.061   869  1597 W audioserver: Binder 0xb40000730060f598 destroyed after setInheritRt before being parceled.
04-25 22:09:20.061   869  1597 W audioserver: Binder 0xb40000730061f9e8 destroyed after setInheritRt before being parceled.
04-25 22:09:20.061   869  1597 D audioserver: FGS Logger Transaction failed
04-25 22:09:20.061   869  1597 D audioserver: -129
04-25 22:09:20.062  5605 19851 W OVRLibrary: null cursor received for query content://com.oculus.ocms.library/apps/com.oculus.guardian
04-25 22:09:20.062  5605  5885 W OVRLibrary: null cursor received for query content://com.oculus.ocms.library/apps/com.oculus.vrshell
04-25 22:09:20.063  5605 30339 W OVRLibrary: null cursor received for query content://com.oculus.ocms.library/apps/com.oculus.systemdriver
04-25 22:09:20.063  5605  5882 W OVRLibrary: null cursor received for query content://com.oculus.ocms.library/apps/com.oculus.vrshell
04-25 22:09:20.088  2974  4451 I VrApi   : FPS=70/72,Prd=18ms,Tear=0,Early=13,Stale=4,Stale2/5/10/max=1/0/0/4,VSnc=1,Lat=-1,Fov=3,CPU4/GPU=3/2,1651/456MHz,OC=3F,TA=0/0/0,SP=N/N/N,Mem=2736MHz,Free=3390MB,PLS=0,Temp=41.0C/0.0C,TW=0.86ms,App=0.00ms,GD=0.00ms,CPU&GPU=15.73ms,LCnt=1(DR91,LM0),GPU%=0.08,CPU%=0.27(W0.30),DSF=1.00,CFL=11.09/16.76,ICFLp95=15.54,LD=0,SF=1.00,LP=1,DVFS=0
04-25 22:09:20.095  2602  6073 W msys    : W[N mqtt_channel]_channel_update_state(551)=>MQTT State changed to: MQTTConnecting
04-25 22:09:20.095  2602  6073 I Xplat MQTT: : Status is MQTTConnecting
04-25 22:09:20.208  1342  1395 I AppsFilter: interaction: PackageSetting{96e8737 com.oculus.sdk.xrcontrollers/10149} -> PackageSetting{6203dff com.oculus.socialplatform/10065} BLOCKED
04-25 22:09:20.212  1342  1369 W Searchables: No global search activity found
04-25 22:09:20.214  2080  2080 V MediaPlayerList: mPackageChangedBroadcastReceiver: action: android.intent.action.PACKAGE_CHANGED
04-25 22:09:20.214  2080  2080 V MediaPlayerList: mPackageChangedBroadcastReceiver: action: android.intent.action.PACKAGE_CHANGED
04-25 22:09:20.214  3206  5283 I PermissionControllerServiceImpl: Updating user sensitive for uid 10065
04-25 22:09:20.214  1342  1498 I InputReader: Reconfiguring input devices, changes=KEYBOARD_LAYOUTS
04-25 22:09:20.214  1090  5717 W Tracking: VEGA:HM:MAP_TRACKING: Validation failed on Submap id = 0, version = 783831558
04-25 22:09:20.215  1342  1369 W VoiceInteractionManager: no available voice recognition services found for user 0
04-25 22:09:20.271  1083 31018 I MRSS    : EvaController: Sessions: CvpPreprocessor(DSP)(Running) with 4 handles: WARP(7),PYRAMID(7),WARP(7),PYRAMID(7),;
({}, {})
04-25 22:09:20.344  6399 28384 W OVRLibrary: null cursor received for query content://com.oculus.ocms.library/apps/com.oculus.systemdriver
04-25 22:09:20.427   846  1262 I VD628x  : Flicker results: freq 100.000000, max 99.995041, dcr 41.363647, pct 0.047649, idx 0.005854, conf 0.982755, snr -6.165943, sus 1
04-25 22:09:20.562  1083 31020 I MRSS    : DEPTH: DepthSystem: onPause: starting asynchronous core deactivation. Active client ids: 'NONE'
({}, {})
04-25 22:09:20.563  1083 31020 I MRSS    : DepthComputeCapabilityBase: onStop.
04-25 22:09:20.563  1083 31192 I MRSS    : MlStereoDisparityComputer: deactivate: succeed.
04-25 22:09:20.563  1083 31192 I MRSS    : DEPTH: DepthSystemCoreImpl: Core deactivated
04-25 22:09:20.583     0     0 I msm_cvp : pwr: cvp (eva) powered on
04-25 22:09:20.614  1090  5717 W Tracking: VEGA:HM:MAP_TRACKING: Validation failed on Submap id = 0, version = 783831558
04-25 22:09:20.689  1090  1871 I Tracking: CONSTELLATION:IMU_STATUS: 0x588f630ca2fcd77f (R): [#Samples=2483] 1215.137s | [FusionTracked=0.656, OutputTracked=0.656, OutputValid=1.000] [AccelSat=0.000, GyroSat=0.000] [AccelMinMeanMax: X=-1.455|3.745|30.896, Y=-5.152|0.554|20.447, Z=-36.903|-7.360|15.184] [GyroMinMeanMax: X=-1.990|-0.166|1.466, Y=-2.687|0.116|2.488, Z=-2.621|0.050|1.643] [AccelStuck: X=0|0.000, Y=0|0.000, Z=0|0.000] [GyroStuck: X=3|-1.389, Y=4|0.283, Z=3|-0.439]  [Current time=1215.137s, last log time=1210.136s]
04-25 22:09:20.716     0     0 I msm_cvp : warn: Failed flush DSP frame retried 62
04-25 22:09:20.717     0     0 I synx    : info: synx_client_destroy: 1352: [sess :19] session removed cvp-kernel-client
04-25 22:09:20.717     0     0 I msm_cvp : sess: Closed cvp instance: 0000000000000000 session_id = 0
04-25 22:09:20.717     0     0 I         : Info: adsprpc (8210): 499: 499: cvp-dsp-thread: fastrpc_driver_unregister: Un-registering fastrpc driver qcom,fastcv0 with handle 1083
04-25 22:09:20.703  1083 31192 I MRSS    : BoltSDK::Soc: [EVENT] CvpDspAllocator: Hexagon ref count changed from 8 to 7
04-25 22:09:20.703  1083 31192 I MRSS    : BoltSDK::Soc: [EVENT] CvpPreprocessor: Hexagon ref count changed from 7 to 6
04-25 22:09:20.703  1083 31192 I MRSS    : DEPTH: CvpPreprocessorDsp: Deactivated
04-25 22:09:20.711  5605  5605 I MMAControls: isMMAAccount: false
04-25 22:09:20.891  1342  1713 E libcrcs : Failed to parse PSI from some avg10=0.00 avg60=0.00 avg300=0.01 total=5156201
04-25 22:09:20.891  1342  1713 E libcrcs : Failed to parse PSI from full avg10=0.00 avg60=0.00 avg300=0.00 total=2790072
04-25 22:09:20.891  1342  1713 E libcrcs : Failed to parse PSI from some avg10=7.38 avg60=4.23 avg300=3.80 total=57064894
({}, {})
04-25 22:09:20.893  1342  1713 E libcrcs : Failed to parse PSI from some avg10=0.00 avg60=0.00 avg300=0.00 total=148079
04-25 22:09:20.893  1342  1713 E libcrcs : Failed to parse PSI from full avg10=0.00 avg60=0.00 avg300=0.00 total=27952
04-25 22:09:21.009  1090 30279 I Tracking: CONSTELLATION_Right: [Throttled count=75] P+C Right: Not reinitializing 6DoF filter yet (no valid pose from vision).
04-25 22:09:21.021  1342  1943 E system_server: BinderClientNDK: Failed to locate expected service type oculus.internal.tracking.IEyeTrackingService/default
04-25 22:09:21.027  1090 30279 I Tracking: CONSTELLATION:VISION_STATUS: 0x588f630ca2fcd77f (R): [#Samples=63] 1215.470s | BlobDetection:[#BlobsDetected=(10.78, 143.13)]Matching:[MatchSuccess=0.444, MatchCost=(0.00289, 0.00000), #MatchedBlobs=(10.25, 130.13), #MatchedLeds=(3.62, 15.41), RematchM=0.000, SkipRefine=0.000] MatchMethod:[Prox=0.460, ConstBrute=0.000, AttiBrute=0.000, UnconstBrute=0.000] MatchResets[AnomBlobSize=0.016, SharedMatch=0.000, DenseReacq=0.000] | Fusion:[FusionError=0.000, ImuFromCamMs=(-1213989.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]  [Current time=1215.470s, last log time=1210.430s]
04-25 22:09:21.032   842   868 E SyncBossFW: 1215480 [error  ] {BSP }: Mag interrupt pin LOW in drdy handler
04-25 22:09:21.069  5605  5882 W OVRLibrary: null cursor received for query content://com.oculus.ocms.library/apps/com.oculus.guardian
04-25 22:09:21.069  5605 19851 W OVRLibrary: null cursor received for query content://com.oculus.ocms.library/apps/com.oculus.vrshell
04-25 22:09:21.071  5605 30339 W OVRLibrary: null cursor received for query content://com.oculus.ocms.library/apps/com.oculus.vrshell
04-25 22:09:21.071  5605  5885 W OVRLibrary: null cursor received for query content://com.oculus.ocms.library/apps/com.oculus.systemdriver
04-25 22:09:21.154   846  1262 I VD628x  : Flicker results: freq 100.000000, max 100.025414, dcr 41.363647, pct 0.046961, idx 0.004563, conf 0.982755, snr -6.307598, sus 1
({}, {})
04-25 22:09:21.254   822  4254 E PAL: ResourceManager: voteSleepMonitor: 1758: ioctl device is not open
04-25 22:09:21.255   822  4254 I PAL: SessionAlsaUtils: close: 691: No need to free device metadata as device is still active
04-25 22:09:21.256   822  4254 I PAL: ResourceManager: freeFrontEndIds: 6773: stream type 1, freeing 102
04-25 22:09:21.256   822  4254 I PAL: Device: close: 497: Enter. deviceCount 2 for device id 3 (PAL_DEVICE_OUT_SPEAKER)
04-25 22:09:21.256   822  4254 I PAL: Device: close: 511: Exit. deviceCount 1 for device id 3 (PAL_DEVICE_OUT_SPEAKER), exit status 0
04-25 22:09:21.256   822  4254 I PAL: ResourceManager: GetConcurrencyInfo: 5024: stream type 1 Tx conc 0, Rx conc 0, concurrency allowed
04-25 22:09:21.256   822  4254 I PAL: ResourceManager: GetConcurrencyInfo: 5024: stream type 1 Tx conc 0, Rx conc 0, concurrency allowed
04-25 22:09:21.256   822  4254 I PAL: ResourceManager: GetConcurrencyInfo: 5024: stream type 1 Tx conc 0, Rx conc 0, concurrency allowed
04-25 22:09:21.256   822  4254 I PAL: ResourceManager: deregisterStream: 3266: stream type 1
04-25 22:09:21.256   822  4254 I PAL: ResourceManager: compareSharedBEStreamDevAttr: 7090: switchStreams is 0
04-25 22:09:21.261  2063  2063 W DnsResolver: resNetworkResult:android.system.ErrnoException: resNetworkResult failed: ETIMEDOUT (Connection timed out)
04-25 22:09:21.261  2063 30882 D NetworkMonitor/103: PROBE_DNS www.google.com 10030ms FAIL in type ADDRCONFIG android.net.DnsResolver$DnsException: android.system.ErrnoException: resNetworkResult failed: ETIMEDOUT (Connection timed out)
04-25 22:09:21.263  2063  2063 W DnsResolver: resNetworkResult:android.system.ErrnoException: resNetworkResult failed: ETIMEDOUT (Connection timed out)
04-25 22:09:21.263  2063 30883 D NetworkMonitor/103: PROBE_DNS connectivitycheck.gstatic.com 10033ms FAIL in type ADDRCONFIG android.net.DnsResolver$DnsException: android.system.ErrnoException: resNetworkResult failed: ETIMEDOUT (Connection timed out)
04-25 22:09:21.305  2602  4873 W DGW     : W[operator():147] DgwRetriableNetworkLayer unable to connect after 10 seconds
04-25 22:09:21.349  6399 28384 W OVRLibrary: null cursor received for query content://com.oculus.ocms.library/apps/com.oculus.systemdriver
04-25 22:09:21.482  2602  4874 W DGW     : W[operator():147] DgwRetriableNetworkLayer unable to connect after 10 seconds
({}, {})
04-25 22:09:21.588  1090  1871 D TrackingService: 588f630ca2fcd77f: isControllerInHand(1222.26s): 0 ctEnabled: 1 btnPress(1219.52s): 0 *btnOrTpdTouch(1220.41s): 0 *btnOrTpdProx(1220.47s): 0 static(1222.26s): 0 thbstkOffCenter(176.40s): 0
04-25 22:09:21.588  1090  1871 D TrackingService: [HANDTRACKING] Controller 588f630ca2fcd77f in hand: N, valid: Y
04-25 22:09:21.588  2119  2358 D ControllerManagement: RemoteService::recordControllerStatusUpdate: device 588f630ca2fcd77f: status: CONNECTED_ACTIVE tracking: ORIENTATION, errors: --
04-25 22:09:21.600  1768  2307 I ControllerHandsTransitionObserver: HANDTRACKING going to hand mode since there is no controller detected in hand
04-25 22:09:21.600  1768  2307 I ControllerHandsTransitionObserver: HANDTRACKING Setting pref store signal to Hands expecting response from VROS
04-25 22:09:21.606  1768  2307 W InputPreferenceStoreListener: HANDTRACKING SetMultimodalityEnabled to 0 but it is already 0
04-25 22:09:21.607  1088  1740 I TrackingFidelityService: Received hand/controller update: hand_tracking_enabled
04-25 22:09:21.607  1768  2307 I ServiceInputManager: HANDTRACKING processed new decision from ModalityTransitionsPolicyManager
04-25 22:09:21.607  1768  1954 I ClockLimits: ClockLimitsSettingsCallback: khandTrackingEnabled updated to 1
04-25 22:09:21.608  1768  2336 I VrRuntimeService: [HANDTRACKING] Input preference 'HandTrackingEnabled' changed from true to true
04-25 22:09:21.608  1088  1740 I TrackingFidelityService: checkMultimodalFeature: Attempt made to enable multimodal hands+controllers when not supported by app
04-25 22:09:21.608  1768  2336 I VrRuntimeService: Input preference 'multimodal_hands_and_controllers_enabled' changed from 0 to 0
04-25 22:09:21.609  2119  2186 D ControllerManagement: onHandTrackingStateChanged
04-25 22:09:21.609  1090  5737 I Tracking: SelftrackedHeadsetSide: onSuspend
04-25 22:09:21.610  1088  1740 W TrackingFidelityService: Fidelity data undefined in catalog for enabling feature hands
04-25 22:09:21.610  1088  1740 W TrackingFidelityService: Fidelity data undefined in catalog for enabling feature hands
04-25 22:09:21.610  1088  1740 W TrackingFidelityService: Fidelity data undefined in catalog for enabling feature hands
04-25 22:09:21.610  1088  1740 W TrackingFidelityService: Fidelity data undefined in catalog for enabling feature hands
04-25 22:09:21.610  1088  1740 I TrackingFidelityService: Request(s)={hands:3,constellation:0,controller_selftracked:0,keyboard:0,scene_reconstruction:0,}, Mux mode idx=8, name=lowLightHands, score=-960
04-25 22:09:21.610  1088  1740 I TrackingFidelityService: beginTransition[worldTracking]: lowLightController(1)->lowLightHands(8)
04-25 22:09:21.610  1088  1740 I TrackingFidelityService: _applyMuxModeLockedInternal: Applying camera mux mode lowLightHands via requestFeaturesWithFidelitiesLocked
04-25 22:09:21.610   851   851 I SensorService: [worldTracking] Setting camera mux mode to 8
04-25 22:09:21.610   851   851 I SensorService: updating CameraStream mux states
04-25 22:09:21.611  1090  1297 I SensorDataTransforms: [iot:iot] Staging mux mode update for mode 8 (sequence ID 149)
04-25 22:09:21.611  1090  1297 I SensorDataTransforms: [iot:iot] Signaling start of the mode transition
04-25 22:09:21.611  1090  1297 I TrackingService: [iot:iot] Start configuration update starting
04-25 22:09:21.611  1090  1297 I TrackingService: [iot:iot] Start configuration update done
04-25 22:09:21.611  1090  1297 I SensorDataTransforms: [iot:iot] Mux mode update succesfully staged
04-25 22:09:21.611  1090  1297 D TrackingService: [iot:iot]Queue mux mode update notification: lowLightHands.
04-25 22:09:21.611  1090  1720 D TrackingService: [iot]start onMuxStateUpdate. ModeId: lowLightController -> lowLightHands
04-25 22:09:21.611  1090  1720 D TrackingService: [iot][iot]handleMuxModeNotification started for muxMode[worldTracking: lowLightHands]
04-25 22:09:21.611  1090  1720 D TrackingService: [iot][iot]handleMuxModeNotification finished for muxMode[worldTracking: lowLightHands]
04-25 22:09:21.611  1090  1720 D TrackingService: [iot]end onMuxStateUpdate. ModeId: lowLightController -> lowLightHands
04-25 22:09:21.611  1090  1720 I TrackingService: [                         iot:iot] Mux Mode Transition from sensor service: lowLightController -> lowLightHands transition time: 0 ms
04-25 22:09:21.611  1090  1250 I SensorDataTransforms: [hand:hand] Staging mux mode update for mode 8 (sequence ID 149)
04-25 22:09:21.611  1090  1297 I SensorDataTransforms: [iobt:iobt] Staging mux mode update for mode 8 (sequence ID 149)
04-25 22:09:21.611  1090  1297 I SensorDataTransforms: [iobt:iobt] Mux mode update succesfully staged
04-25 22:09:21.611  1090  1250 I SensorDataTransforms: [hand:hand] Mux mode update succesfully staged
04-25 22:09:21.611  1090  1297 D TrackingService: [iobt:iobt]Queue mux mode update notification: lowLightHands.
04-25 22:09:21.611  1090  1250 D TrackingService: [hand:hand]Queue mux mode update notification: lowLightHands.
04-25 22:09:21.611  1090  1250 I SensorDataTransforms: [constellation:constellation] Staging mux mode update for mode 8 (sequence ID 149)
04-25 22:09:21.611  1090  1250 I SensorDataTransforms: [constellation:constellation] Signaling start of the mode transition
04-25 22:09:21.611  1090  1250 I TrackingService: [constellation:constellation] Start configuration update starting
04-25 22:09:21.611  1090  1726 D TrackingService: [iobt]start onMuxStateUpdate. ModeId: lowLightController -> lowLightHands
04-25 22:09:21.611  1090  1250 I TrackingService: [constellation:constellation] Start configuration update done
04-25 22:09:21.611  1090  1726 D TrackingService: [iobt][iobt]handleMuxModeNotification started for muxMode[worldTracking: lowLightHands]
04-25 22:09:21.611  1090  1250 I SensorDataTransforms: [constellation:constellation] Mux mode update succesfully staged
04-25 22:09:21.611  1090  1250 D TrackingService: [constellation:constellation]Queue mux mode update notification: lowLightHands.
04-25 22:09:21.611  1090  1726 D TrackingService: [iobt][iobt]handleMuxModeNotification finished for muxMode[worldTracking: lowLightHands]
04-25 22:09:21.611  1090  1726 D TrackingService: [iobt]end onMuxStateUpdate. ModeId: lowLightController -> lowLightHands
04-25 22:09:21.611  1090  1726 I TrackingService: [                       iobt:iobt] Mux Mode Transition from sensor service: lowLightController -> lowLightHands transition time: 0 ms
04-25 22:09:21.611  1090  1724 D TrackingService: [hand]start onMuxStateUpdate. ModeId: lowLightController -> lowLightHands
04-25 22:09:21.611  1090  1724 D TrackingService: [HANDTRACKINGGLUE] handleMuxModeNotification
04-25 22:09:21.611  1090  1724 D TrackingService: [HANDTRACKINGGLUE] handleStateChange muxMode=lowLightHands
04-25 22:09:21.611  1090  1724 D TrackingService: [HANDTRACKINGGLUE] startCapability
04-25 22:09:21.611  1090  1724 D TrackingService: [HANDTRACKINGGLUE] resumeCapability
04-25 22:09:21.611  1090  1723 D TrackingService: [constellation]start onMuxStateUpdate. ModeId: lowLightController -> lowLightHands
04-25 22:09:21.611  1090  1297 I SensorDataTransforms: [object:object] Staging mux mode update for mode 8 (sequence ID 149)
04-25 22:09:21.611  1090  1723 D TrackingService: [constellation][constellation]handleMuxModeNotification started for muxMode[worldTracking: lowLightHands]
04-25 22:09:21.611  1090  1297 I SensorDataTransforms: [object:object] Mux mode update succesfully staged
04-25 22:09:21.611   851   851 I SensorService: ending update to CameraStream mux states
04-25 22:09:21.611  1090  1297 D TrackingService: [object:object]Queue mux mode update notification: lowLightHands.
04-25 22:09:21.611   851   851 I FrameSetCollator: [worldTracking] Updated frame set period to 20000000, with activeSlots [[T,F,T,F,]]
04-25 22:09:21.611   851   851 I FrameSetDispatcher: [worldTracking] Client pid=1090 (IOT): Updated CameraMuxMode: 8, FrameTypes: GENERIC_SLOT_0 GENERIC_SLOT_2
04-25 22:09:21.611   851   851 I FrameSetDispatcher: [worldTracking] Client pid=1090 (ExpCtl): Updated CameraMuxMode: 8, FrameTypes: GENERIC_SLOT_0 GENERIC_SLOT_2
04-25 22:09:21.611   851   851 I FrameSetDispatcher: [worldTracking] Client pid=1090 (Cnst): Updated CameraMuxMode: 8, FrameTypes:
04-25 22:09:21.611  1090  1742 I SensorDataTransforms: [constellation:ambient_controller] Staging mux mode update for mode 8 (sequence ID 149)
04-25 22:09:21.611   851   851 I FrameSetDispatcher: [worldTracking] Client pid=1090 (Cnst): Updated CameraMuxMode: 8, FrameTypes:
04-25 22:09:21.611  1090  1729 D TrackingService: [object]start onMuxStateUpdate. ModeId: lowLightController -> lowLightHands
04-25 22:09:21.611   851   851 I FrameSetDispatcher: [worldTracking] Latest MuxModeSettings: 8
04-25 22:09:21.611  1090  1742 I SensorDataTransforms: [constellation:ambient_controller] Signaling start of the mode transition
04-25 22:09:21.611   851   851 I FrameSetDispatcher: [worldTracking] purpose: projector_assisted_depth, frame types:
04-25 22:09:21.611  1090  1729 D TrackingService: [object][object]handleMuxModeNotification started for muxMode[worldTracking: lowLightHands]
04-25 22:09:21.611   851   851 I FrameSetDispatcher: [worldTracking] purpose: mr_monochrome, frame types: GENERIC_SLOT_0 GENERIC_SLOT_2
04-25 22:09:21.611  1090  1742 I TrackingService: [constellation:ambient_controller] Start configuration update starting
04-25 22:09:21.611   851   851 I FrameSetDispatcher: [worldTracking] purpose: object, frame types:
04-25 22:09:21.611  1090  1742 I TrackingService: [constellation:ambient_controller] Start configuration update done
04-25 22:09:21.611   851   851 I FrameSetDispatcher: [worldTracking] purpose: iobt, frame types: GENERIC_SLOT_0 GENERIC_SLOT_2
04-25 22:09:21.611   851   851 I FrameSetDispatcher: [worldTracking] purpose: constellation, frame types:
04-25 22:09:21.611  1090  1729 D TrackingService: [object][object]handleMuxModeNotification finished for muxMode[worldTracking: lowLightHands]
04-25 22:09:21.611   851   851 I FrameSetDispatcher: [worldTracking] purpose: ambient_controller, frame types:
04-25 22:09:21.611   851   851 I FrameSetDispatcher: [worldTracking] purpose: active_slots, frame types: GENERIC_SLOT_0 GENERIC_SLOT_2
04-25 22:09:21.611   851   851 I FrameSetDispatcher: [worldTracking] purpose: hand, frame types: GENERIC_SLOT_0 GENERIC_SLOT_2
04-25 22:09:21.611  1090  1742 I SensorDataTransforms: [constellation:ambient_controller] Mux mode update succesfully staged
04-25 22:09:21.611  1090  1729 D TrackingService: [object]end onMuxStateUpdate. ModeId: lowLightController -> lowLightHands
04-25 22:09:21.611   851   851 I FrameSetDispatcher: [worldTracking] purpose: iot, frame types: GENERIC_SLOT_0 GENERIC_SLOT_2
04-25 22:09:21.611  1090  1729 I TrackingService: [                   object:object] Mux Mode Transition from sensor service: lowLightController -> lowLightHands transition time: 0 ms
04-25 22:09:21.611  1090  1742 D TrackingService: [constellation:ambient_controller]Queue mux mode update notification: lowLightHands.
04-25 22:09:21.611   851   851 I SensorService: !!! cameraSetIndex: 0: camera set: 1, num_slots: 4, shutter_period_us: 20000
04-25 22:09:21.611   851   851 I SensorService: !!! cameraSetIndex: 1: camera set: 2, num_slots: 4, shutter_period_us: 20000
04-25 22:09:21.611  1090  1248 D SensorControl: onMuxStateUpdate started for modeIdentifier[8]
04-25 22:09:21.611  1090  1248 I SensorDataTransforms: [ExpCtl:active_slots] Staging mux mode update for mode 8 (sequence ID 149)
04-25 22:09:21.611  1090  1248 I SensorDataTransforms: [ExpCtl:active_slots] Signaling start of the mode transition
04-25 22:09:21.611  1090  1248 I SensorDataTransforms: [ExpCtl:active_slots] Mux mode update succesfully staged
04-25 22:09:21.611  1090  1248 D SensorControl: onMuxStateUpdate ended for modeIdentifier[8]
04-25 22:09:21.611   851  9133 D CameraStream: [constellation] Client [Cnst(1090)] attempting stop
04-25 22:09:21.611  1083  1431 I SensorDataTransforms: [CameraStreamControl:mr_monochrome] Staging mux mode update for mode 8 (sequence ID 149)
04-25 22:09:21.611   851  9133 I FrameSetDispatcher: [worldTracking] Client pid=1090 (Cnst): Client thread stopping
04-25 22:09:21.611  1083  1431 I SensorDataTransforms: [CameraStreamControl:mr_monochrome] Signaling start of the mode transition
04-25 22:09:21.611  1083  1431 I SensorDataTransforms: [CameraStreamControl:mr_monochrome] Mux mode update succesfully staged
04-25 22:09:21.611   851  1296 D CameraStream: [hand] Client [Hand(1090)] attempting start
04-25 22:09:21.611  1083  1431 I SensorDataTransforms: [CameraStreamControl:projector_assisted_depth] Staging mux mode update for mode 8 (sequence ID 149)
04-25 22:09:21.612  1083  1431 I SensorDataTransforms: [CameraStreamControl:projector_assisted_depth] Mux mode update succesfully staged
04-25 22:09:21.612   851  9133 I CameraClientManager: [Cnst(1090)][constellation] client stopped (estimated 0 started clients remaining)
04-25 22:09:21.612   851  9133 I CameraClientManager: [[Cnst(1090)][constellation]] definitely stopping stream
04-25 22:09:21.612   851  9133 D NiceCameraSystem: CameraSystem stopping cameras for purpose constellation
04-25 22:09:21.612   851  1296 I FrameSetDispatcher: [worldTracking] Client pid=1090 (Hand): Client thread[WT:Hand] started
04-25 22:09:21.612   851  1296 I CameraClientManager: [Hand(1090)][hand] client started (estimated 1 started clients total)
04-25 22:09:21.612   851  1296 I CameraClientManager: [[Hand(1090)][hand]] definitely starting stream
04-25 22:09:21.618   851   851 D SensorService: Configured syncboss nice config: [{camera_set: 1, num_slots: 4, [ { tag: 128, default_exposure_us: 65535, default_gain: 65535, capabilities: 0, slot_length: 0, }, { tag: 129, default_exposure_us: 5000, default_gain: 48, capabilities: 8, slot_length: 0, }, { tag: 130, default_exposure_us: 65535, default_gain: 65535, capabilities: 0, slot_length: 0, }, { tag: 131, default_exposure_us: 5000, default_gain: 48, capabilities: 8, slot_length: 0, }, ] shutter_period_us: 20000, }, {camera_set: 2, num_slots: 4, [ { tag: 128, default_exposure_us: 65535, default_gain: 65535, capabilities: 0, slot_length: 0, }, { tag: 129, default_exposure_us: 5000, default_gain: 48, capabilities: 8, slot_length: 0, }, { tag: 130, default_exposure_us: 65535, default_gain: 65535, capabilities: 0, slot_length: 0, }, { tag: 131, default_exposure_us: 5000, default_gain: 48, capabilities: 8, slot_length: 0, }, ] shutter_period_us: 20000, }, ]
04-25 22:09:21.618   851  1296 I FrameSetCollator: [worldTracking] Updated frame set period to 20000000, with activeSlots [[T,F,T,F,]]
04-25 22:09:21.618   851  1296 I CameraClientManager: [[Hand(1090)][hand]] stream start completed
04-25 22:09:21.618   851  1296 D CameraStream: [hand] Client [Hand(1090)]: start complete
04-25 22:09:21.618   851  9133 I CameraClientManager: [[Cnst(1090)][constellation]] stream stop completed
04-25 22:09:21.618  1088  1740 I TrackingFidelityService: Received hand/controller update: multimodal_hands_and_controllers_enabled
04-25 22:09:21.618   851  9133 D CameraStream: [constellation] Client [Cnst(1090)]: stop complete
04-25 22:09:21.618   851  9133 D CameraStream: [ambient_controller] Client [Cnst(1090)] attempting stop
04-25 22:09:21.618   851  9133 I FrameSetDispatcher: [worldTracking] Client pid=1090 (Cnst): Client thread stopping
04-25 22:09:21.618   851  9133 I CameraClientManager: [Cnst(1090)][ambient_controller] client stopped (estimated 0 started clients remaining)
04-25 22:09:21.618   851  9133 I CameraClientManager: [[Cnst(1090)][ambient_controller]] definitely stopping stream
04-25 22:09:21.618   851  9133 D NiceCameraSystem: CameraSystem stopping cameras for purpose ambient_controller
04-25 22:09:21.618   851  9133 I CameraClientManager: [[Cnst(1090)][ambient_controller]] stream stop completed
04-25 22:09:21.618   851  9133 D CameraStream: [ambient_controller] Client [Cnst(1090)]: stop complete
04-25 22:09:21.618  1088  1740 I TrackingFidelityService: checkMultimodalFeature: Attempt made to enable multimodal hands+controllers when not supported by app
04-25 22:09:21.618  1088  1740 W TrackingFidelityService: Fidelity data undefined in catalog for enabling feature hands
04-25 22:09:21.618  1088  1740 W TrackingFidelityService: Fidelity data undefined in catalog for enabling feature hands
04-25 22:09:21.618  1088  1740 W TrackingFidelityService: Fidelity data undefined in catalog for enabling feature hands
04-25 22:09:21.618  1088  1740 W TrackingFidelityService: Fidelity data undefined in catalog for enabling feature hands
04-25 22:09:21.618  1088  1740 I TrackingFidelityService: Request(s)={hands:3,constellation:0,controller_selftracked:0,keyboard:0,scene_reconstruction:0,}, Mux mode idx=8, name=lowLightHands, score=-960
04-25 22:09:21.618  1088  1740 I TrackingFidelityService: beginTransition[worldTracking]: lowLightController(1)->lowLightHands(8)
04-25 22:09:21.618  1088  1740 I TrackingFidelityService: _applyMuxModeLockedInternal: Mode lowLightHands already set. Returning.
04-25 22:09:21.618  1088  1740 I TrackingFidelityService: endTransition[worldTracking]: lowLightController(1)->lowLightHands(8), seqId=-1, succeeded=true
04-25 22:09:21.618  1090  1723 I Tracking: INPUT_TRACKING_ENGINE: InputTrackingEngine::onSuspend
04-25 22:09:21.619  1090  1723 I Tracking: INPUT_TRACKING: Pipeline suspend()
04-25 22:09:21.619  1090  1723 I Tracking: INPUT_TRACKING_ENGINE: InputTrackingEngine::onSuspend(): Done.
04-25 22:09:21.619  1090  1723 D TrackingService: constellation: Joining stream thread: CtrllrCam
04-25 22:09:21.619  1090  1723 D TrackingService: constellation: stopCameras finished
04-25 22:09:21.619  1090  1723 D TrackingService: [constellation][constellation]handleMuxModeNotification finished for muxMode[worldTracking: lowLightHands]
04-25 22:09:21.619  1090  1723 D TrackingService: [constellation]end onMuxStateUpdate. ModeId: lowLightController -> lowLightHands
04-25 22:09:21.619  1090  1723 I TrackingService: [     constellation:constellation] Mux Mode Transition from sensor service: lowLightController -> lowLightHands transition time: 8 ms
04-25 22:09:21.619  1090  1723 D TrackingService: [ambient_controller]start onMuxStateUpdate. ModeId: lowLightHands -> lowLightHands
04-25 22:09:21.619  1090  1723 D TrackingService: [constellation][ambient_controller]handleMuxModeNotification started for muxMode[worldTracking: lowLightHands]
04-25 22:09:21.619  1090  1723 E TrackingService: Resetting MuxMode lowLightHands which is already set
04-25 22:09:21.619  1090  1723 D TrackingService: [ambient_controller]end onMuxStateUpdate. ModeId: lowLightHands -> lowLightHands
04-25 22:09:21.619  1090  1723 I TrackingService: [constellation:ambient_controller] Mux Mode Transition from sensor service: lowLightHands -> lowLightHands transition time: 0 ms
04-25 22:09:21.624   842   868 I SyncBossFW: 1216064 [info   ] {NICE}: nice_set_frame_rate called on same period of 20000us
04-25 22:09:21.624   842   868 I SyncBossFW: 1216064 [info   ] {NCM }: No slot with constellation capability found.
04-25 22:09:21.624   842   868 I SyncBossFW: 1216067 [info   ] {NICE}: nice_set_frame_rate called on same period of 20000us
04-25 22:09:21.624   842   868 I SyncBossFW: 1216067 [info   ] {NCM }: No slot with projector capability found.
04-25 22:09:21.624   842   868 I SyncBossFW: 1216067 [info   ] {NCM }: Stopping projector
04-25 22:09:21.627  1090  1724 I Tracking: NIMBLE:HTEngineUtil: Loaded user profile path: /system/etc/handtracking/runtime/common/defaultProfile.msgpack
04-25 22:09:21.643  1090  1724 I Tracking: NIMBLE:UnifiedHandBodyTracking:ExposureControl: [Exposure] Settings: Algorithm nimble Mode low_exposure, initial exposure 0.004000000189989805, initial gain 4
04-25 22:09:21.643  1090  1724 I Tracking: NIMBLE:UnifiedHandBodyTracking:ExposureControl: [Exposure] Initializing hand auto exposure algorithm
04-25 22:09:21.643  1090  1724 I Tracking: NIMBLE:ExposureControl: Initializing exposure control unit for 4 cameras
04-25 22:09:21.661  1090  1724 I Tracking: NIMBLE:DPE_HandTracker: No user profile is provided, use the generic profile
04-25 22:09:21.662  1090  1724 I Tracking: NIMBLE:DPE_HandTracker: Using Single-View Detector
04-25 22:09:21.662  1090  1724 I Tracking: NIMBLE:DPE_HandTracker: Using optimized resampling with modelTypes: 2G0YC5ZG9B02T5_0=4, 2G0YC5ZG9B02T5_1=4, 2G0YC5ZG9B02T5_2=4, 2G0YC5ZG9B02T5_3=4,
04-25 22:09:21.662  1090  1724 I Tracking: NIMBLE:UnifiedHandBodyEngine: Initialized Hand Tracking Pipeline
04-25 22:09:21.662  1090  1724 D TrackingService: [hand]end onMuxStateUpdate. ModeId: lowLightController -> lowLightHands
04-25 22:09:21.662  1090  1724 I TrackingService: [                       hand:hand] Mux Mode Transition from sensor service: lowLightController -> lowLightHands transition time: 51 ms
04-25 22:09:21.662  1768  2307 I OSHandDataQuerier: OSSDKTRACKING HANDTRACKING HANDS ENABLED
04-25 22:09:21.662  1768  2307 I ServiceInputManager: HANDTRACKING Control Data last: 2 new: 1
04-25 22:09:21.662  1768  2307 I ServiceInputManager: HANDTRACKING Tracking Service reports hands ENABLED
04-25 22:09:21.662  1768  2307 I ControllerHandsTransitionObserver: HANDTRACKING New VROS control status: hands enabled
04-25 22:09:21.663  1768  2307 I ModalityTransitionsPolicyManager: HANDTRACKING: Switching to Hands only mode        HANDS
04-25 22:09:21.688   851  1276 I FrameSetDispatcher: [worldTracking] 1 older Mux Mode settings are removed.
04-25 22:09:21.688  1088  1160 I TrackingFidelityService: endTransition[worldTracking]: lowLightController(1)->lowLightHands(8), seqId=149, succeeded=true
04-25 22:09:21.688   851  5744 I FrameSetDispatcher: [worldTracking] Client pid=1090 (ExpCtl): Remove old Mux Mode 1: frame types: GENERIC_SLOT_0 GENERIC_SLOT_1 GENERIC_SLOT_2
04-25 22:09:21.688   851  5576 I FrameSetDispatcher: [worldTracking] Client pid=1090 (IOT): Remove old Mux Mode 1: frame types: GENERIC_SLOT_0 GENERIC_SLOT_2
04-25 22:09:21.688   851 31229 I FrameSetDispatcher: [worldTracking] Client pid=1090 (Hand): Remove old Mux Mode 1: frame types:
04-25 22:09:21.688  1090 31230 D SensorDataTransforms: [hand:hand] Erased 1 stale entries from the staged mux states.
04-25 22:09:21.689  1090 31230 I TrackingService: [hand:hand] Configuration update starting
04-25 22:09:21.689  1090  5717 I TrackingService: [iot:iot] Configuration update starting
04-25 22:09:21.689  1090 31230 I Tracking: NIMBLE:UnifiedHandBodyEngine: setCameraConfigurationHand() cameraIndex 0
04-25 22:09:21.689  1090  5717 I TrackingService: [iot:iot] Configuration update done
04-25 22:09:21.689  1090  5717 I TrackingService: [iot:iot] Finish configuration update starting
04-25 22:09:21.689  1090  5717 I TrackingService: [iot:iot] Finish configuration update done
04-25 22:09:21.689  1090 31230 I Tracking: NIMBLE:CameraHandler: setCameraConfiguration() cameraIndex 0
04-25 22:09:21.689  1090 31230 I Tracking: NIMBLE:UnifiedHandBodyEngine: setCameraConfigurationHand() cameraIndex 1
04-25 22:09:21.689  1090 31230 I Tracking: NIMBLE:CameraHandler: setCameraConfiguration() cameraIndex 1
04-25 22:09:21.689  1090 31230 I Tracking: NIMBLE:UnifiedHandBodyEngine: setCameraConfigurationHand() cameraIndex 2
04-25 22:09:21.689  1090 31230 I Tracking: NIMBLE:CameraHandler: setCameraConfiguration() cameraIndex 2
04-25 22:09:21.689  1090 31230 I Tracking: NIMBLE:UnifiedHandBodyEngine: setCameraConfigurationHand() cameraIndex 3
04-25 22:09:21.689  1090 31230 I Tracking: NIMBLE:CameraHandler: setCameraConfiguration() cameraIndex 3
04-25 22:09:21.689  1090 31230 I TrackingService: [hand:hand] Configuration update done
04-25 22:09:21.689  1090 31230 I TrackingService: [hand:hand] Finish configuration update starting
04-25 22:09:21.689  1090 31230 I Tracking: NIMBLE:UnifiedHandBodyEngine: finishConfigurationUpdateHand()
04-25 22:09:21.689  1090 31230 I Tracking: NIMBLE:CameraHandler: currentImageSensorConfigurations_ size is 4 in finishConfigurationUpdate()
04-25 22:09:21.689  1090 31230 I Tracking: NIMBLE:CameraHandler: converting currentImageSensorConfigurations_ to engine camera config. numCameras_ 4, currentImageSensorConfigurations_ size 4
04-25 22:09:21.690  1090  5745 I SensorControl: Mapping slotdIdx[0] to controlPurposeName[iotExposureControl]
04-25 22:09:21.690  1090  5745 I SensorControl: Mapping slotdIdx[2] to controlPurposeName[iotExposureControl]
04-25 22:09:21.690  1090  5745 I SensorControl: configureExposureAlgorithm: purpose=iotExposureControl
04-25 22:09:21.690  1090  5745 I SensorControl:   camera=0, exposure=[0.000043, 0.013000]  gain=[1.000000, 15.937500]
04-25 22:09:21.690  1090  5745 I SensorControl:   camera=1, exposure=[0.000043, 0.013000]  gain=[1.000000, 15.937500]
04-25 22:09:21.690  1090  5745 I SensorControl:   camera=2, exposure=[0.000043, 0.013000]  gain=[1.000000, 15.937500]
04-25 22:09:21.690  1090  5745 I SensorControl:   camera=3, exposure=[0.000043, 0.013000]  gain=[1.000000, 15.937500]
04-25 22:09:21.691  1090 31230 I TrackingService: [hand:hand] Finish configuration update done
04-25 22:09:21.692  1090 31230 I Tracking: NIMBLE:UnifiedHandBodyTracking:Personalization: startIfNeeded(): Success
04-25 22:09:21.694  1090 31230 I Tracking: TRACKING: MrHandClient: [Throttled count=103] Hand PT mitigation - ptActive: false, dataUpToDate: false, connected: true, fidelityClientInitialized: true
04-25 22:09:21.697  1768  2307 I ServiceInputManager: HANDTRACKING hand 0 status set to OutOfView
04-25 22:09:21.697  1768  2307 I ServiceInputManager: HANDTRACKING hand 0 connected
04-25 22:09:21.697  1768  2307 I ServiceInputManager: HANDTRACKING hand 1 status set to OutOfView
04-25 22:09:21.697  1768  2307 I ServiceInputManager: HANDTRACKING hand 1 connected
04-25 22:09:21.705  1090 31230 I Tracking: NIMBLE:UnifiedHandBodyTracking:ExposureControl: [Throttled count=99] FMM: enabled: true; active: false
04-25 22:09:21.705  1090 31230 I Tracking: NIMBLE:UnifiedHandBodyTracking:ExposureControl: [Frame     1 camera 0] [HT] has_hand =  NO, irradiance_proxy =  11058.0, intensity =  102.6, exposure =  4.638ms, gain =    2
04-25 22:09:21.705  1090 31230 I Tracking: NIMBLE:UnifiedHandBodyEngine: Pipeline Latency P50: 13.98ms; Capture Latency P50: 13.53ms [arrival:10.77ms|engine:0.47ms|process:2.29ms]
04-25 22:09:21.705  1090 31230 I Tracking: NIMBLE:UnifiedHandBodyEngine: Pipeline Latency P95: 13.98ms; Capture Latency P95: 13.53ms [arrival:10.77ms|engine:0.47ms|process:2.29ms]
04-25 22:09:21.705  1090 31230 I Tracking: NIMBLE:UnifiedHandBodyEngine: Hand Tracking Processed FPS = 24.97 Hz / Camera FPS = 24.97 Hz
({}, {})
04-25 22:09:21.828  1038  1876 D BufferPoolAccessor2.0: bufferpool2 0xf5e4ac18 : 0(0 size) total buffers - 0(0 size) used buffers - 34/42 (recycle/alloc) - 8/76 (fetch/transfer)
04-25 22:09:21.828  1038  1876 D BufferPoolAccessor2.0: evictor expired: 1, evicted: 1
04-25 22:09:21.880   846  1262 I VD628x  : Flicker results: freq 100.000000, max 99.905334, dcr 41.363647, pct 0.044901, idx 0.005242, conf 0.964605, snr -6.307598, sus 1
04-25 22:09:21.904  1342  1713 E libcrcs : Failed to parse PSI from some avg10=0.00 avg60=0.00 avg300=0.01 total=5157601
04-25 22:09:21.904  1342  1713 E libcrcs : Failed to parse PSI from full avg10=0.00 avg60=0.00 avg300=0.00 total=2791347
04-25 22:09:21.904  1342  1713 E libcrcs : Failed to parse PSI from some avg10=6.41 avg60=4.16 avg300=3.78 total=57097660
04-25 22:09:21.906  1342  1713 E libcrcs : Failed to parse PSI from some avg10=0.00 avg60=0.00 avg300=0.00 total=148079
04-25 22:09:21.906  1342  1713 E libcrcs : Failed to parse PSI from full avg10=0.00 avg60=0.00 avg300=0.00 total=27952
04-25 22:09:22.021  1342  1943 E system_server: BinderClientNDK: Failed to locate expected service type oculus.internal.tracking.IEyeTrackingService/default
04-25 22:09:22.076  5605  5882 W OVRLibrary: null cursor received for query content://com.oculus.ocms.library/apps/com.oculus.vrshell
({}, {})
04-25 22:09:22.076  5605 30339 W OVRLibrary: null cursor received for query content://com.oculus.ocms.library/apps/com.oculus.vrshell
04-25 22:09:22.077  5605  5885 W OVRLibrary: null cursor received for query content://com.oculus.ocms.library/apps/com.oculus.guardian
04-25 22:09:22.077  5605 30340 W OVRLibrary: null cursor received for query content://com.oculus.ocms.library/apps/com.oculus.systemdriver
04-25 22:09:22.106  2640  2746 D PickerSyncController: syncAllMedia
04-25 22:09:22.107  2640  2746 D PickerSyncController: syncAllMediaFromProvider() LOCAL, auth=com.android.providers.media.photopicker, retry=true
04-25 22:09:22.107  2640  2746 D PickerSyncController: getSyncRequestParams() LOCAL, auth=com.android.providers.media.photopicker
04-25 22:09:22.109  2640  2746 D PickerSyncController:    Latest ID/Gen=1407:fb31b669-c800-4d98-bbd4-694182f45d21:external_primary/1040
04-25 22:09:22.109  2640  2746 D PickerSyncController:    Cached ID/Gen=1407:fb31b669-c800-4d98-bbd4-694182f45d21:external_primary/1033
04-25 22:09:22.109  2640  2746 D PickerSyncController:    RESULT=SyncRequestParams{type=MEDIA_INCREMENTAL, gen=1033, latest=Bundle[{last_media_sync_generation=1040, media_collection_id=1407:fb31b669-c800-4d98-bbd4-694182f45d21:external_primary}]}
04-25 22:09:22.109  2640  2746 I PickerSyncController: Executing SyncAdd. isLocal: true. authority: com.android.providers.media.photopicker
04-25 22:09:22.110  2640  2746 I PickerSyncController: Paged sync successful. QueryArgs: Bundle[{android.provider.extra.SYNC_GENERATION=1033}]. Result count: 1. Cursor count: 1
04-25 22:09:22.111  2640  2746 I PickerSyncController: Executing SyncRemove. isLocal: true. authority: com.android.providers.media.photopicker
04-25 22:09:22.112  2640  2746 I PickerSyncController: Paged sync successful. QueryArgs: Bundle[{android.provider.extra.SYNC_GENERATION=1033}]. Result count: 0. Cursor count: 0
04-25 22:09:22.112  2640  2746 D PickerSyncController: syncAllMediaFromProvider() CLOUD, auth=null, retry=true
04-25 22:09:22.112  2640  2746 D PickerSyncController: getSyncRequestParams() CLOUD, auth=null
04-25 22:09:22.112  2640  2746 D PickerSyncController:    RESULT=SyncRequestParams{type=MEDIA_RESET, gen=0, latest=null}
04-25 22:09:22.112  2640  2746 I PickerSyncController: Executing SyncReset. isLocal: false. authority: null
04-25 22:09:22.112  2640  2746 I PickerSyncController: SyncReset. isLocal:false. authority: null. result count: 0
04-25 22:09:22.112  2640  2746 D PickerSyncController: Ignoring cache media info for null authority with bundle: null
04-25 22:09:22.112  2640  2746 I PickerSyncController: Executing SyncAlbumReset. isLocal: true. authority: com.android.providers.media.photopicker. albumId: null
04-25 22:09:22.112  2640  2746 I PickerSyncController: Successfully executed SyncResetAlbum. authority: com.android.providers.media.photopicker. albumId: null. Result count: 0
04-25 22:09:22.112  2640  2746 I PickerSyncController: Executing SyncAlbumReset. isLocal: false. authority: null. albumId: null
04-25 22:09:22.112  2640  2746 I PickerSyncController: Successfully executed SyncResetAlbum. authority: null. albumId: null. Result count: 0
04-25 22:09:22.191  2974  2974 D NotificationTimedDismissalHelper: 5.0 seconds have elapsed. Dismissing toast
04-25 22:09:22.192  2974  2974 D [SEO] ToastLayerView: hideNotificationIfDisplaying(0|com.oculus.metacam|-1734665533|cameraroll|10050)
04-25 22:09:22.192  2974  2974 V [SEO] ToastLayerView: hideNotification() + children 1
04-25 22:09:22.192  2974  2974 V [SEO] ToastLayerView: Tag is 0|com.oculus.metacam|-1734665533|cameraroll|10050 and key is 0|com.oculus.metacam|-1734665533|cameraroll|10050
04-25 22:09:22.192  2974  2974 V [SEO] ToastLayerView: Applying close animation
04-25 22:09:22.193  2974  2974 V ToastsPanelApp: Scheduling a toast layer close
04-25 22:09:22.303  1090  1871 W Tracking: CONSTELLATION: P+C Right: Lost velocity tracking, waiting for 6DoF reset. [#VisionUp=345, #RelUp=1, VelNorm=0.003, CurTimeDur=4.00s, DurThresh=4.00s]
04-25 22:09:22.354  6399 31220 W OVRLibrary: null cursor received for query content://com.oculus.ocms.library/apps/com.oculus.systemdriver
04-25 22:09:22.356  2974  2974 V [SEO] ToastLayerView: Removing view 0|com.oculus.metacam|-1734665533|cameraroll|10050
04-25 22:09:22.356  2974  2974 V [SEO] ToastLayerView: Removing close lock
04-25 22:09:22.357  2974  2974 V ToastsPanelApp: ToastLayer Hide requested
04-25 22:09:22.358  2974  2974 V ToastsPanelApp: ToastLayer is closeable
04-25 22:09:22.358  2974  2974 D NotificationTimedDismissalHelper: NotificationTimedDismissalHelper's CoroutineScope was cancelled because the NotificationView was detached.
04-25 22:09:22.358  2974  2974 W WindowOnBackDispatcher: sendCancelIfRunning: isInProgress=falsecallback=android.view.ViewRootImpl$$ExternalSyntheticLambda17@de80a4b
04-25 22:09:22.359  1342  2294 D CoreBackPreview: Window{fd900c1 u0 com.oculus.android_panel_app.AndroidPanelLayer-com.oculus.vrshell-#main}: Setting back callback null
04-25 22:09:22.359  2974  3170 W .oculus.vrshell: Binder 0x7d092776d0 destroyed after setInheritRt before being parceled.
04-25 22:09:22.359  1342  2294 W InputManager-JNI: Input channel object 'fd900c1 com.oculus.android_panel_app.AndroidPanelLayer-com.oculus.vrshell-#main (client)' was disposed without first being removed with the input manager!
04-25 22:09:22.361  1342  2294 I DisplayDeviceRepository: Display device removed: DisplayDeviceInfo{"com.oculus.android_panel_app.AndroidPanelLayer-com.oculus.vrshell-#main": uniqueId="virtual:com.oculus.vrshell,10020,com.oculus.android_panel_app.AndroidPanelLayer-com.oculus.vrshell-#main,0", 460 x 110, modeId 289, renderFrameRate 60.0, defaultModeId 289, supportedModes [{id=289, width=460, height=110, fps=60.0, alternativeRefreshRates=[], supportedHdrTypes=[]}], colorMode 0, supportedColorModes [0], hdrCapabilities null, allmSupported false, gameContentTypeSupported false, density 200, 200.0 x 200.0 dpi, appVsyncOff 0, presDeadline 16666666, touch NONE, rotation 0, type VIRTUAL, deviceProductInfo null, state ON, committedState UNKNOWN, owner com.oculus.vrshell (uid 10020), frameRateOverride , brightnessMinimum 0.0, brightnessMaximum 0.0, brightnessDefault 0.0, hdrSdrRatio NaN, FLAG_PRIVATE, FLAG_NEVER_BLANK, FLAG_OWN_CONTENT_ONLY, installOrientation 0, displayShape DisplayShape{ spec=1305280667 displayWidth=460 displayHeight=110 physicalPixelDisplaySizeRatio=1.0 rotation=0 offsetX=0 offsetY=0 scale=1.0}}
04-25 22:09:22.362  1342  2294 I LogicalDisplayMapper: Removing display: 124
04-25 22:09:22.363  2974  2974 I AndroidPanelApp: HideLayer(#main, shape: 1, shouldMinimizeLayer: true)
04-25 22:09:22.363  1342  1363 D DisplayManagerService: Ignore redundant display event 124/3 to 10094/3917
04-25 22:09:22.364  1342  1497 W system_server: Binder 0xb400007c6b6bc450 destroyed after setInheritRt before being parceled.
04-25 22:09:22.370  1342  6380 I InputManager: Not applying additional properties for display 124 because the pointer is currently targeting display 0.
04-25 22:09:22.370  1169  1169 W surfaceflinger: Binder 0xb40000775b844730 destroyed after setInheritRt before being parceled.
04-25 22:09:22.370  1169  1169 W surfaceflinger: Binder 0xb40000775b843238 destroyed after setInheritRt before being parceled.
04-25 22:09:22.371  1169  1169 W surfaceflinger: Binder 0xb40000775b8e0208 destroyed after setInheritRt before being parceled.
04-25 22:09:22.371  1169  1169 W surfaceflinger: Binder 0xb40000775b99f010 destroyed after setInheritRt before being parceled.
04-25 22:09:22.384  2974  5874 W .oculus.vrshell: Binder 0x7c134b5e20 destroyed after setInheritRt before being parceled.
04-25 22:09:22.384  2974  5874 W .oculus.vrshell: Binder 0x7c13a17148 destroyed after setInheritRt before being parceled.
04-25 22:09:22.384  2974  5874 W .oculus.vrshell: Binder 0x7c134b5738 destroyed after setInheritRt before being parceled.
({}, {})
04-25 22:09:22.425  3833  3833 D MetaCam (DeviceLifecycleManager): onCapabilitiesChanged(...). Does not have NET_CAPABILITY_VALIDATED.

[jborbik]

Hi @MarSaKi, thanks for looking into this!
Based on your screenshot it looks like the APK was updated to the new version even though an error is reported. So that is fine for now and can be improved later.
Does the output which you have pasted come from changed "reader.py"?

[MarSaKi]

Hi @Jendker, yes the output is reported after uncomment the line you mentioned:

Thanks,

[hosh0425]

@MarSaKi were you able to get the poses with this new APK? I am facing same APK installation Failed error

[meijie-jesse]

Thanks for reporting! Unfortunately I don't have Oculus Quest 3 to test the APK myself and would need your help to wrap things up. If you are willing to help me with testing, here are the steps:感谢您的报告！很遗憾，我没有 Oculus Quest 3 来亲自测试 APK，需要您帮忙完成测试。如果您愿意帮助我测试，请按照以下步骤操作：

Initial step:  初始步骤：

1. Connect the oculus quest as you would do it before for DROID像以前连接 DROID 一样连接 Oculus Quest
2. Run original APK with "python3 oculus_reader/reader.py” and check how the interface looks like使用“python3 oculus_reader/reader.py”运行原始 APK 并检查界面是什么样子的

To install the new APK:要安装新的 APK：

1. Replace "teleop-debug.apk” in the locally cloned repo with the one from this link: https://drive.google.com/file/d/1Fg3lsOmtAh7GwQocDAKgV7JZEr7CivMv/view?usp=sharing将本地克隆的 repo 中的“teleop-debug.apk”替换为以下链接中的“teleop-debug.apk”： https://drive.google.com/file/d/1Fg3lsOmtAh7GwQocDAKgV7JZEr7CivMv/view?usp =sharing
2. Run “python3 oculus_reader/install.py --reinstall” to install the new APK运行“python3 oculus_reader/install.py --reinstall”来安装新的 APK
3. Run "python3 oculus_reader/reader.py” and wear the helmet. Verify that the interface changed which means that the new APK has been installed successfully.运行“python3 oculus_reader/reader.py”，并戴上头盔，查看界面是否有变化，如果变化则说明新的 APK 安装成功。

To test the new APK:要测试新的 APK：

1. Run “python3 oculus_reader/reader.py”运行“python3 oculus_reader/reader.py”
2. Try to press the buttons and move the controllers in front of headset尝试按下按钮并将控制器移到耳机前面
3. Check the printed output for the following:
   检查打印输出是否包含以下内容：
   • do the poses change as expected?姿势是否按预期发生变化？
   • if you press the buttons on controller, can you see them in the printed output?如果您按下控制器上的按钮，您可以在打印输出中看到它们吗？

In case of any issues:如有任何问题：

1. Add the line in the file at this position:

     [oculus_reader/oculus_reader/reader.py](https://github.com/rail-berkeley/oculus_reader/blob/c52b4b6193879b6cf32a1db1bd5727652626f00d/oculus_reader/reader.py#L189)
   
   
        Line 189
     in
     [c52b4b6](/rail-berkeley/oculus_reader/commit/c52b4b6193879b6cf32a1db1bd5727652626f00d)
   
   
   
   
   
       
         
          data = self.extract_data(line) 
   

   . Content: “print(line)”在文件的这个位置添加以下行：
   内容：“print(line)”

2. Try to press the buttons and move the controllers in front of headset尝试按下按钮并将控制器移到耳机前面

3. Report the issues to me including the printed output from “reader.py". I’ll try to get to it as soon as I can.向我报告问题，包括“reader.py”的打印输出。我会尽快处理。

Thanks!  谢谢！

hi Jendker！Thanks for your great work!
I can successfully install the new APK, and launch the APK.
But there were problems:

Exception in thread Thread-1 (shell):
Traceback (most recent call last):
File "/home/robint/isaacsim4.2/kit/python/lib/python3.10/threading.py", line 1016, in _bootstrap_inner
self.run()
File "/home/robint/isaacsim4.2/kit/python/lib/python3.10/threading.py", line 953, in run
self._target(*self._args, **self._kwargs)
File "/home/robint/isaacsim4.2/kit/python/lib/python3.10/site-packages/ppadb/command/transport/init.py", line 26, in shell
handler(conn)
File "/home/robint/Desktop/Lr1ArmSim/IsaacSim/Motion/oculus_reader/oculus_reader/reader.py", line 194, in read_logcat_by_line
transforms, buttons = OculusReader.process_data(data)
File "/home/robint/Desktop/Lr1ArmSim/IsaacSim/Motion/oculus_reader/oculus_reader/reader.py", line 170, in process_data
buttons = parse_buttons(buttons_string)
File "/home/robint/isaacsim4.2/kit/python/lib/python3.10/site-packages/oculus_reader/buttons_parser.py", line 30, in parse_buttons
value = tuple([float(x) for x in split_elem[1:]])
File "/home/robint/isaacsim4.2/kit/python/lib/python3.10/site-packages/oculus_reader/buttons_parser.py", line 30, in
value = tuple([float(x) for x in split_elem[1:]])
ValueError: could not convert string to float: '0.000000L'

And here is the logs:

logs.txt

By the way, I found something known:

1. In the new version APK, hand tracking seems to be disabled(Even if I don't pick up the handle, the headset can track the position of my hand), which was available in the old version.
2. Pressing the buttons on the handle sometimes causes vibrations, but it seems that there is no fixed mapping between the vibrations and the buttons, it is random.

[jborbik]

Hi @meijie-jesse, thank you for sharing! Thanks to your feedback I found the bug. Please replace the APK from this link and give it another go: https://drive.google.com/file/d/17iIzHUHXtzlwmAXW5QfGqwRpsePxq_MA/view?usp=share_link

Besides, did you have to follow any additional steps to install the APK? It may be helpful to others who run into installation issues.

To your comments:

1. Is there any specific use for the hand visible? Supporting the hand tracking is definitely possible but I'd like to focus on the remote controllers because I don't have any Oculus Quest to test and debug the code myself at the moment.
2. Thank you for reporting this! I'll get rid of vibrations in the final version of APK.

Please let me know how it goes and if you run any into any other issues with the new APK.

[hosh0425]

@meijie-jesse what is the software version of your quest 3? Both these new APKs fail to install on my quest 3s, v76.1025

[Xjiebao]

So I would like to ask if the current problem has been solved？I also want to perform some teleoperations with quest3

[jborbik]

Hi @Xjiebao!

I am still waiting for the feedback whether the new version for Oculus Quest 3 works as expected. Can you follow this post here and reply with your findings? That will help me resolve the issue. Thanks!