Since #986 (and maybe v65 on quest) apps do not wakeup anymore.

[mvvvv]

Description

When you turn the headset off (power button) then turn it back on (power button again) the application does not restart. First black screen with 3 small dots then screen: "do you want to close the app or wait ?".

Platform / Environment

rust binding. Linux 24.04 gnome wayland + Quest 2 v65

Logs or exception details

05-15 09:38:57.003 18443 18508 I VrApi   : FPS=72/72,Prd=34ms,Tear=0,Early=0,Stale=0,Stale2/5/10/max=0/0/0/0,VSnc=1,Lat=-1,Fov=0,CPU4/GPU=2/3,1171/490MHz,OC=FF,TA=0/0/70,SP=N/N/F,Mem=1353MHz,Free=1854MB,PLS=0,Temp=28.7C/0.0C,TW=1.58ms,App=8.49ms,GD=0.55ms,CPU&GPU=12.46ms,LCnt=2(DR72,LM2),GPU%=0.78,CPU%=0.20(W0.32),DSF=1.00,CFL=11.57/16.94,ICFLp95=14.78,LD=0,SF=1.50,LP=0,DVFS=0
05-15 09:38:57.598 18443 18443 I OpenXR  : nativeOnActivityPaused
05-15 09:38:57.598 18443 18443 D android_activity::act..: Pause: 0x7a673cf640
05-15 09:38:57.604 18443 18467 I OpenXR  : PostSessionStateChange: XR_SESSION_STATE_FOCUSED -> XR_SESSION_STATE_VISIBLE
05-15 09:38:57.606 18443 18467 I Deque   : AddEventToQueue called. eventInfo.type: 18, eventInfo.next: 1
05-15 09:38:57.606 18443 18467 I OpenXR  : PostSessionStateChange: XR_SESSION_STATE_VISIBLE -> XR_SESSION_STATE_SYNCHRONIZED
05-15 09:38:57.606 18443 18467 I Deque   : AddEventToQueue called. eventInfo.type: 18, eventInfo.next: 1
05-15 09:38:57.606 18443 18467 I OpenXR  : PostSessionStateChange: XR_SESSION_STATE_SYNCHRONIZED -> XR_SESSION_STATE_STOPPING
05-15 09:38:57.607 18443 18467 I Deque   : AddEventToQueue called. eventInfo.type: 18, eventInfo.next: 1
05-15 09:38:57.607 18443 18467 I OpenXR  : ------------ xrEndSession [start] -----------
05-15 09:38:57.607 18443 18467 I XrRuntimeUtils: XrRuntimeUtils::EndSession
05-15 09:38:57.662 18443 18467 I Sensor  : OSSDKTRACKING Shutdown tracking
05-15 09:38:57.662 18443 18467 I LogTimer: CPUTIME: VrRuntimeClient_EndSession took 41.2818 ms
05-15 09:38:57.663 18443 18467 I InputDeviceManager: ovrInputDeviceManager::~ovrInputDeviceManager
05-15 09:38:57.663 18443 18467 I OSHandDataQuerier: OSSDKTRACKING shutting down HandsTracker
05-15 09:38:57.663 18443 18467 I OSHandDataQuerier: OSSDKTRACKING shutting down HandsTracker
05-15 09:38:57.663 18443 18467 D OsSdk   : TrackingServiceConnection dtor
05-15 09:38:57.664 18443 18467 D OsSdk   : TrackingServiceConnection unregistered
05-15 09:38:57.664 18443 18467 I InputHooks_Internal: Disabling Native Input Hooks
05-15 09:38:57.673 18443 18507 I ClientInputThread_Internal: InputThreadRun ShutdownRequest received
05-15 09:38:57.674 18443 18467 I ClientInputTrackedKeyboardManager: TRACKEDKEYBOARD ClearTrackedObjectManager called on client
05-15 09:38:57.680 18443 18510 I FenceChecker: FenceChecker::MainLoop - ended
05-15 09:38:57.680 18443 18467 I VrRuntimeClient: PhaseSync Instance deleted
05-15 09:38:57.680 18443 18467 I OpenXR  : PostSessionStateChange: XR_SESSION_STATE_STOPPING -> XR_SESSION_STATE_IDLE
05-15 09:38:57.681 18443 18467 I Deque   : AddEventToQueue called. eventInfo.type: 18, eventInfo.next: 1
05-15 09:38:57.681 18443 18467 I OpenXR  : ------------ xrEndSession [end] -----------
05-15 09:38:57.681 18443 18467 I VrRuntimeClient: Client has gained focus.
05-15 09:38:57.693 18443 18443 D android_activity::act..: NativeWindowDestroyed: 0x7a673cf640 -- 0x7a48a74810
05-15 09:38:57.717 18443 18467 D StereoKit: Sleeping until OpenXR session wakes
05-15 09:38:57.718 18443 18467 W Telemetry: [OpenXR] Unsupported threadType; 2
05-15 09:39:00.950 18443 18467 I Deque   : AddEventToQueue called. eventInfo.type: 40, eventInfo.next: 1
05-15 09:39:00.950 18443 18467 D StereoKit: Created local floor app space at an offset of (0,0,0) (0,0,0,1)
05-15 09:39:00.950 18443 18467 D StereoKit: Bounds updated: 3.6x3.4 at (0.3,0,-2) (0,0.95,0,-0.3)
05-15 09:39:01.551 18443 18467 I VrRuntimeClient: Client has lost focus.
05-15 09:39:07.582 18443 18449 I inding_templat: Thread[3,tid=18449,WaitingInMainSignalCatcherLoop,Thread*=0x7a6726a400,peer=0x12f000d8,"Signal Catcher"]: reacting to signal 3
05-15 09:39:07.582 18443 18449 I inding_templat: 
05-15 09:39:07.729 18443 18449 I inding_templat: Wrote stack traces to tombstoned

Logs are super helpful, please include them if they're at all relevant!

_If you have an exception, details about that would also be essential._

[maluoi]

Hmmm, I'm unable to reproduce this one on my Quest 3 or Quest Pro! However, it is interesting that you're getting these ones while sleeping:

05-15 09:39:00.950 18443 18467 D StereoKit: Created local floor app space at an offset of (0,0,0) (0,0,0,1)
05-15 09:39:00.950 18443 18467 D StereoKit: Bounds updated: 3.6x3.4 at (0.3,0,-2) (0,0.95,0,-0.3)

I haven't seen these logs in my own tests, and it feels kinda weird to be receiving these with no session available.

Does this happen 100% of the time for you? And how about sleep duration, does this happen after a few seconds, or a few minutes or both? Are you moving the device to a new room while sleeping, causing it to relocate while waking? Etc.

And also, do you know if this is specific to the MSAA commit, or did it start a little earlier, like around here? ( #986 )

[mvvvv]

Yep all the time with the 3 apk I'm working on.

05-15 18:58:31.572 10819 10824 I com.mv81.giza: Thread[4,tid=10824,WaitingInMainSignalCatcherLoop,Thread*=0x7a6732a000,peer=0x12ec07b0,"Signal Catcher"]: reacting to signal 3
05-15 18:58:31.572 10819 10824 I com.mv81.giza: 
05-15 18:58:32.468 10819 10824 I com.mv81.giza: Wrote stack traces to tombstoned
05-15 18:58:37.394 10819 10844 I Deque   : AddEventToQueue called. eventInfo.type: 40, eventInfo.next: 1
05-15 18:58:37.394 10819 10844 D StereoKit: Bounds updated: 3.6x3.4 at (-0.9,-2,-2) (0,0.82,0,-0.57)
05-15 18:58:37.494 10819 10844 I VrRuntimeClient: Client has lost focus.
05-15 18:58:37.644 10819 10844 I Deque   : AddEventToQueue called. eventInfo.type: 40, eventInfo.next: 1
05-15 18:58:37.645 10819 10844 D StereoKit: Bounds updated: 3.6x3.4 at (-0.9,-2,-2) (0,0.82,0,-0.57)

and nothing more.

I'm not 100% sure about when it starts. But I can't believe I missed it in the last 10 days. If you don't have this problem I should check with previous commit.

[mvvvv]

My fault, It's not while the headset is sleeping but at its wake-up. Last log line before sleep is Telemetry: [OpenXR] Unsupported threadType; 2

Something new is v65 too.

[mvvvv]

I changed the title of this issue as #986 is indeed the cause and perhaps the new v65 on Quest but I can no longer verify this.