1768776 - Intermittent org.mozilla.geckoview.test.GeckoViewTest#setTabActiveKeepsTabAtHighPriority | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms

Status: RESOLVED FIXED

(GeckoView :: General, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=377642769&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/f8SQaT8yR6Oh2CMipetgyQ/runs/0/artifacts/public/logs/live_backing.log

---

[task 2022-05-11T03:38:00.914Z] 03:38:00     INFO -  TEST-START | org.mozilla.geckoview.test.GeckoViewTest#setTabActiveKeepsTabAtHighPriority
[task 2022-05-11T03:38:32.962Z] 03:38:32     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=903
[task 2022-05-11T03:38:32.962Z] 03:38:32     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2022-05-11T03:38:32.962Z] 03:38:32     INFO -  org.mozilla.geckoview.test | Error in setTabActiveKeepsTabAtHighPriority(org.mozilla.geckoview.test.GeckoViewTest):
[task 2022-05-11T03:38:32.962Z] 03:38:32     INFO -  org.mozilla.geckoview.test | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-05-11T03:38:32.962Z] 03:38:32     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-05-11T03:38:32.962Z] 03:38:32     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-05-11T03:38:32.962Z] 03:38:32     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-05-11T03:38:32.962Z] 03:38:32     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-05-11T03:38:32.963Z] 03:38:32     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.GeckoViewTest.waitUntilContentProcessPriority(GeckoViewTest.kt:85)
[task 2022-05-11T03:38:32.965Z] 03:38:32     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.GeckoViewTest.setTabActiveKeepsTabAtHighPriority$lambda-12(GeckoViewTest.kt:146)
[task 2022-05-11T03:38:32.966Z] 03:38:32     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.GeckoViewTest.$r8$lambda$ikq4DvYOtZ_ZIvxWdkokLGMxdmk(GeckoViewTest.kt)
[task 2022-05-11T03:38:32.966Z] 03:38:32     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.GeckoViewTest$$ExternalSyntheticLambda3.perform(Unknown Source)
[task 2022-05-11T03:38:32.966Z] 03:38:32     INFO -  org.mozilla.geckoview.test | 	at androidx.test.core.app.ActivityScenario.lambda$onActivity$2$ActivityScenario(ActivityScenario.java:660)
[task 2022-05-11T03:38:32.966Z] 03:38:32     INFO -  org.mozilla.geckoview.test | 	at androidx.test.core.app.ActivityScenario$$Lambda$4.run(ActivityScenario.java:652)
[task 2022-05-11T03:38:32.966Z] 03:38:32     INFO -  org.mozilla.geckoview.test | 	at androidx.test.core.app.ActivityScenario.onActivity(ActivityScenario.java:670)
[task 2022-05-11T03:38:32.966Z] 03:38:32     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.GeckoViewTest.setTabActiveKeepsTabAtHighPriority(GeckoViewTest.kt:130)
[task 2022-05-11T03:38:32.966Z] 03:38:32     INFO -  org.mozilla.geckoview.test |
[task 2022-05-11T03:38:32.966Z] 03:38:32     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2022-05-11T03:38:32.966Z] 03:38:32     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=setTabActiveKeepsTabAtHighPriority
[task 2022-05-11T03:38:32.966Z] 03:38:32     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.GeckoViewTest
[task 2022-05-11T03:38:32.966Z] 03:38:32     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-05-11T03:38:32.966Z] 03:38:32     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-05-11T03:38:32.966Z] 03:38:32     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-05-11T03:38:32.966Z] 03:38:32     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-05-11T03:38:32.966Z] 03:38:32     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-05-11T03:38:32.966Z] 03:38:32     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.GeckoViewTest.waitUntilContentProcessPriority(GeckoViewTest.kt:85)
[task 2022-05-11T03:38:32.966Z] 03:38:32     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.GeckoViewTest.setTabActiveKeepsTabAtHighPriority$lambda-12(GeckoViewTest.kt:146)
[task 2022-05-11T03:38:32.966Z] 03:38:32     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.GeckoViewTest.$r8$lambda$ikq4DvYOtZ_ZIvxWdkokLGMxdmk(GeckoViewTest.kt)
[task 2022-05-11T03:38:32.966Z] 03:38:32     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.GeckoViewTest$$ExternalSyntheticLambda3.perform(Unknown Source)
[task 2022-05-11T03:38:32.966Z] 03:38:32     INFO -  org.mozilla.geckoview.test | 	at androidx.test.core.app.ActivityScenario.lambda$onActivity$2$ActivityScenario(ActivityScenario.java:660)
[task 2022-05-11T03:38:32.966Z] 03:38:32     INFO -  org.mozilla.geckoview.test | 	at androidx.test.core.app.ActivityScenario$$Lambda$4.run(ActivityScenario.java:652)
[task 2022-05-11T03:38:32.966Z] 03:38:32     INFO -  org.mozilla.geckoview.test | 	at androidx.test.core.app.ActivityScenario.onActivity(ActivityScenario.java:670)
[task 2022-05-11T03:38:32.966Z] 03:38:32     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.GeckoViewTest.setTabActiveKeepsTabAtHighPriority(GeckoViewTest.kt:130)
[task 2022-05-11T03:38:32.966Z] 03:38:32     INFO -  org.mozilla.geckoview.test |
[task 2022-05-11T03:38:32.966Z] 03:38:32     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=317
[task 2022-05-11T03:38:32.966Z] 03:38:32     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: -2
[task 2022-05-11T03:38:32.966Z] 03:38:32     INFO -  Printing logcat for test:
[task 2022-05-11T03:38:33.009Z] 03:38:33     INFO -  05-11 03:38:00.656 E/GeckoSessionTestRule( 3414): test_start 1f0befec-3ff2-40ff-89cf-b127eb38b1ec setTabActiveKeepsTabAtHighPriority(org.mozilla.geckoview.test.GeckoViewTest)
[task 2022-05-11T03:38:33.009Z] 03:38:33     INFO -  05-11 03:38:00.656 E/GeckoSessionTestRule( 3414): before prepareStatement setTabActiveKeepsTabAtHighPriority(org.mozilla.geckoview.test.GeckoViewTest)
[task 2022-05-11T03:38:33.009Z] 03:38:33     INFO -  05-11 03:38:00.658 I/WindowManager( 1659): Destroying surface Surface(name=Starting org.mozilla.geckoview.test) called by com.android.server.wm.WindowStateAnimator.destroySurface:2014 com.android.server.wm.WindowStateAnimator.destroySurfaceLocked:881 com.android.server.wm.WindowState.removeLocked:1449 com.android.server.wm.WindowManagerService.removeWindowInnerLocked:2478 com.android.server.wm.WindowManagerService.removeWindowLocked:2436 com.android.server.wm.WindowManagerService.removeWindowLocked:2305 com.android.server.wm.WindowManagerService.removeWindow:2300 com.android.server.wm.Session.remove:193
[task 2022-05-11T03:38:33.010Z] 03:38:33     INFO -  05-11 03:38:00.660 D/GeckoViewStartup( 3414): onEvent GeckoView:StorageDelegate:Attached
[task 2022-05-11T03:38:33.010Z] 03:38:33     INFO -  05-11 03:38:00.660 I/Gecko   ( 3414): nsWindow[0x72c8a7a33900]::Create 0x0 [0 0 1 1]
[task 2022-05-11T03:38:33.010Z] 03:38:33     INFO -  05-11 03:38:00.667 D/EGL_emulation( 3414): eglMakeCurrent: 0x72c8db75bbc0: ver 3 0 (tinfo 0x72c8db6975a0)
[task 2022-05-11T03:38:33.010Z] 03:38:33     INFO -  05-11 03:38:00.668 D/EGL_emulation( 2137): eglMakeCurrent: 0x72c8db4818a0: ver 3 0 (tinfo 0x72c8db495a00)
[task 2022-05-11T03:38:33.010Z] 03:38:33     INFO -  05-11 03:38:00.676 I/WindowManager( 1659): Destroying surface Surface(name=com.android.launcher3/com.android.launcher3.Launcher) called by com.android.server.wm.WindowStateAnimator.destroySurface:2014 com.android.server.wm.WindowStateAnimator.destroySurfaceLocked:881 com.android.server.wm.WindowState.destroyOrSaveSurface:2073 com.android.server.wm.WindowManagerService.tryStartExitingAnimation:3017 com.android.server.wm.WindowManagerService.relayoutWindow:2897 com.android.server.wm.Session.relayout:215 android.view.IWindowSession$Stub.onTransact:286 com.android.server.wm.Session.onTransact:136
[task 2022-05-11T03:38:33.011Z] 03:38:33     INFO -  05-11 03:38:00.682 I/ActivityManager( 1659): Displayed org.mozilla.geckoview.test/.GeckoViewTestActivity: +102ms (total +111ms)
[task 2022-05-11T03:38:33.011Z] 03:38:33     INFO -  05-11 03:38:00.694 D/GeckoViewNavigation( 3414): sessionContextId=null
[task 2022-05-11T03:38:33.011Z] 03:38:33     INFO -  05-11 03:38:00.698 D/LifecycleMonitor( 3414): Lifecycle status change: androidx.test.core.app.InstrumentationActivityInvoker$BootstrapActivity@36bd41f in: STOPPED
[task 2022-05-11T03:38:33.011Z] 03:38:33     INFO -  05-11 03:38:00.698 V/ActivityScenario( 3414): Activity lifecycle changed event received but ignored because the intent does not match. startActivityIntent=Intent { act=android.intent.action.MAIN cat=[android.intent.category.LAUNCHER] cmp=org.mozilla.geckoview.test/.GeckoViewTestActivity }, activity.getIntent()=Intent { act=android.intent.action.MAIN cat=[android.intent.category.LAUNCHER] flg=0x10008000 cmp=org.mozilla.geckoview.test/androidx.test.core.app.InstrumentationActivityInvoker$BootstrapActivity (has extras) }, activity=androidx.test.core.app.InstrumentationActivityInvoker$BootstrapActivity@36bd41f
[task 2022-05-11T03:38:33.012Z] 03:38:33     INFO -  05-11 03:38:00.710 E/eglCodecCommon( 3488): glUtilsParamSize: unknow param 0x00008caa
[task 2022-05-11T03:38:33.012Z] 03:38:33     INFO -  05-11 03:38:00.747 W/webrender::device::gl( 3488): Missing optimized shader source for gpu_cache_update
[task 2022-05-11T03:38:33.012Z] 03:38:33     INFO -  05-11 03:38:00.755 E/eglCodecCommon( 3488): glUtilsParamSize: unknow param 0x00008741
[task 2022-05-11T03:38:33.012Z] 03:38:33     INFO -  05-11 03:38:00.762 D/GeckoViewModule( 3414): registerListener ["GeckoViewContent:ExitFullScreen","GeckoView:ClearMatches","GeckoView:DisplayMatches","GeckoView:FindInPage","GeckoView:RestoreState","GeckoView:ScrollBy","GeckoView:ScrollTo","GeckoView:SetActive","GeckoView:SetFocused","GeckoView:SetPriorityHint","GeckoView:UpdateInitData","GeckoView:ZoomToInput"]
[task 2022-05-11T03:38:33.012Z] 03:38:33     INFO -  05-11 03:38:00.763 D/GeckoViewSelectionActionDelegate[C]( 6516): handleEvent: visibilitychange
[task 2022-05-11T03:38:33.012Z] 03:38:33     INFO -  05-11 03:38:00.763 D/GeckoViewNavigation( 3414): onInit
[task 2022-05-11T03:38:33.012Z] 03:38:33     INFO -  05-11 03:38:00.764 D/GeckoViewModule( 3414): registerListener ["GeckoView:GoBack","GeckoView:GoForward","GeckoView:GotoHistoryIndex","GeckoView:LoadUri","GeckoView:Reload","GeckoView:Stop","GeckoView:PurgeHistory"]
[task 2022-05-11T03:38:33.012Z] 03:38:33     INFO -  05-11 03:38:00.764 D/GeckoViewNavigation( 3414): onEnable
[task 2022-05-11T03:38:33.012Z] 03:38:33     INFO -  05-11 03:38:00.765 D/GeckoViewProcessHangMonitor( 3414): onInit
[task 2022-05-11T03:38:33.012Z] 03:38:33     INFO -  05-11 03:38:00.765 D/GeckoViewProcessHangMonitor( 3414): onEnable
[task 2022-05-11T03:38:33.013Z] 03:38:33     INFO -  05-11 03:38:00.765 D/GeckoViewModule( 3414): registerListener ["GeckoView:HangReportStop","GeckoView:HangReportWait"]
[task 2022-05-11T03:38:33.013Z] 03:38:33     INFO -  05-11 03:38:00.766 D/GeckoViewProgress( 3414): onEnable
[task 2022-05-11T03:38:33.013Z] 03:38:33     INFO -  05-11 03:38:00.768 D/GeckoViewModule( 3414): registerListener GeckoView:FlushSessionState
[task 2022-05-11T03:38:33.013Z] 03:38:33     INFO -  05-11 03:38:00.769 D/GeckoViewSettings( 3414): onInit
[task 2022-05-11T03:38:33.013Z] 03:38:33     INFO -  05-11 03:38:00.769 D/GeckoViewModule( 3414): registerListener ["GeckoView:GetUserAgent"]
[task 2022-05-11T03:38:33.013Z] 03:38:33     INFO -  05-11 03:38:00.770 D/GeckoViewSettings( 3414): onSettingsUpdate: {"chromeUri":null,"screenId":0,"userAgentOverride":null,"allowJavascript":true,"userAgentMode":0,"viewportMode":0,"useTrackingProtection":false,"suspendMediaWhenInactive":false,"usePrivateMode":false,"unsafeSessionContextId":null,"displayMode":0,"sessionContextId":null,"fullAccessibilityTree":false,"isPopup":false}
[task 2022-05-11T03:38:33.013Z] 03:38:33     INFO -  05-11 03:38:00.772 D/GeckoViewModule( 3414): registerListener ["GeckoView:WebExtension:SetTabActive"]
[task 2022-05-11T03:38:33.013Z] 03:38:33     INFO -  05-11 03:38:00.772 D/GeckoViewSettings[C]( 6516): receiveMessage: SettingsUpdate
[task 2022-05-11T03:38:33.014Z] 03:38:33     INFO -  05-11 03:38:00.773 D/GeckoViewModule( 3414): registerListener ["ContentBlocking:RequestLog"]
[task 2022-05-11T03:38:33.014Z] 03:38:33     INFO -  05-11 03:38:00.774 D/GeckoViewMediaControl( 3414): onEnable
[task 2022-05-11T03:38:33.014Z] 03:38:33     INFO -  05-11 03:38:00.775 D/GeckoViewModule( 3414): registerListener ["GeckoView:MediaSession:Play","GeckoView:MediaSession:Pause","GeckoView:MediaSession:Stop","GeckoView:MediaSession:NextTrack","GeckoView:MediaSession:PrevTrack","GeckoView:MediaSession:SeekForward","GeckoView:MediaSession:SeekBackward","GeckoView:MediaSession:SkipAd","GeckoView:MediaSession:SeekTo","GeckoView:MediaSession:MuteAudio"]
[task 2022-05-11T03:38:33.014Z] 03:38:33     INFO -  05-11 03:38:00.776 D/GeckoSession( 3414): handleMessage GeckoView:PageStart uri=about:blank
[task 2022-05-11T03:38:33.014Z] 03:38:33     INFO -  05-11 03:38:00.776 E/GeckoSessionTestRule( 3414): method: public abstract void org.mozilla.geckoview.GeckoSession$ProgressDelegate.onPageStart(org.mozilla.geckoview.GeckoSession,java.lang.String)
[task 2022-05-11T03:38:33.014Z] 03:38:33     INFO -  05-11 03:38:00.776 D/GeckoSession( 3414): handleMessage GeckoView:LocationChange uri=about:blank
[task 2022-05-11T03:38:33.015Z] 03:38:33     INFO -  05-11 03:38:00.776 E/GeckoSessionTestRule( 3414): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onLocationChange(org.mozilla.geckoview.GeckoSession,java.lang.String,java.util.List)
[task 2022-05-11T03:38:33.015Z] 03:38:33     INFO -  05-11 03:38:00.776 E/GeckoSessionTestRule( 3414): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onCanGoBack(org.mozilla.geckoview.GeckoSession,boolean)
[task 2022-05-11T03:38:33.015Z] 03:38:33     INFO -  05-11 03:38:00.776 E/GeckoSessionTestRule( 3414): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onCanGoForward(org.mozilla.geckoview.GeckoSession,boolean)
[task 2022-05-11T03:38:33.015Z] 03:38:33     INFO -  05-11 03:38:00.776 D/GeckoSession( 3414): handleMessage GeckoView:PageStop uri=null
[task 2022-05-11T03:38:33.015Z] 03:38:33     INFO -  05-11 03:38:00.776 E/GeckoSessionTestRule( 3414): method: public abstract void org.mozilla.geckoview.GeckoSession$ProgressDelegate.onPageStop(org.mozilla.geckoview.GeckoSession,boolean)
[task 2022-05-11T03:38:33.015Z] 03:38:33     INFO -  05-11 03:38:00.776 E/GeckoSessionTestRule( 3414): after prepareStatement
[task 2022-05-11T03:38:33.016Z] 03:38:33     INFO -  05-11 03:38:00.781 I/GeckoSession( 3414): zerdatime 226680 - chrome startup finished
[task 2022-05-11T03:38:33.016Z] 03:38:33     INFO -  05-11 03:38:00.782 I/Gecko   ( 3414): nsWindow[0x72c8a7a33900]::Show 1
[task 2022-05-11T03:38:33.016Z] 03:38:33     INFO -  05-11 03:38:00.784 D/EGL_emulation( 3488): eglMakeCurrent: 0x72c8e327e600: ver 3 0 (tinfo 0x72c8d8b04f60)
[task 2022-05-11T03:38:33.016Z] 03:38:33     INFO -  05-11 03:38:00.784 W/GeckoEditableChild( 6516): No editable parent
[task 2022-05-11T03:38:33.016Z] 03:38:33     INFO -  05-11 03:38:00.785 D/GeckoViewXUL( 3414): receiveMessage GeckoView:ContentModuleLoaded {"module":"SessionStateAggregator"}
[task 2022-05-11T03:38:33.016Z] 03:38:33     INFO -  05-11 03:38:00.786 I/Gecko   ( 3414): nsWindow[0x72c8a7a33900]::Resize [0.000000 144.000000 0.000000 0.000000] (repaint 0)
[task 2022-05-11T03:38:33.016Z] 03:38:33     INFO -  05-11 03:38:00.787 I/Gecko   ( 3414): nsWindow[0x72c8a7a33900]::Resize [0.000000 144.000000 800.000000 1136.000000] (repaint 0)
[task 2022-05-11T03:38:33.016Z] 03:38:33     INFO -  05-11 03:38:00.787 I/Gecko   ( 3414): nsWindow: 0x72c8a7a33900 OnSizeChanged [800 1136]
[task 2022-05-11T03:38:33.017Z] 03:38:33     INFO -  05-11 03:38:00.788 D/GeckoViewModule( 3414): dispatch GeckoView:SetActive, data={"active":true}
[task 2022-05-11T03:38:33.017Z] 03:38:33     INFO -  05-11 03:38:00.788 D/GeckoViewContent( 3414): onEvent: event=GeckoView:SetActive, data={"active":true}
[task 2022-05-11T03:38:33.017Z] 03:38:33     INFO -  05-11 03:38:00.789 D/GeckoViewModule( 3414): dispatch GeckoView:SetFocused, data={"focused":true}
[task 2022-05-11T03:38:33.017Z] 03:38:33     INFO -  05-11 03:38:00.789 D/GeckoViewContent( 3414): onEvent: event=GeckoView:SetFocused, data={"focused":true}
[task 2022-05-11T03:38:33.017Z] 03:38:33     INFO -  05-11 03:38:00.790 D/GeckoViewConsole( 3414): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"8c11af73-2b49-4392-9541-081d4a8d773c","args":{"oldPrefs":{},"newPrefs":{"dom.ipc.processPriorityManager.backgroundGracePeriodMS":0,"dom.ipc.processPriorityManager.backgroundPerceivableGracePeriodMS":0}},"type":"SetPrefs"}}
[task 2022-05-11T03:38:33.017Z] 03:38:33     INFO -  05-11 03:38:00.799 I/Gecko   ( 3414): nsWindow[0x72c8aa5c3d00]::Create 0x0 [0 0 1 1]
[task 2022-05-11T03:38:33.017Z] 03:38:33     INFO -  05-11 03:38:00.819 E/eglCodecCommon( 3488): glUtilsParamSize: unknow param 0x00008caa
[task 2022-05-11T03:38:33.017Z] 03:38:33     INFO -  05-11 03:38:00.823 D/GeckoViewNavigation( 3414): sessionContextId=null
[task 2022-05-11T03:38:33.018Z] 03:38:33     INFO -  05-11 03:38:00.834 I/art     ( 6678): Late-enabling -Xcheck:jni
[task 2022-05-11T03:38:33.018Z] 03:38:33     INFO -  05-11 03:38:00.834 W/art     ( 6678): Unexpected CPU variant for X86 using defaults: x86_64
[task 2022-05-11T03:38:33.018Z] 03:38:33     INFO -  05-11 03:38:00.840 I/ActivityManager( 1659): Start proc 6678:org.mozilla.geckoview.test:tab29/u0a62 for service org.mozilla.geckoview.test/org.mozilla.gecko.process.GeckoChildProcessServices$tab29
[task 2022-05-11T03:38:33.018Z] 03:38:33     INFO -  05-11 03:38:00.841 D/ServiceAllocator( 3414): org.mozilla.gecko.process.GeckoChildProcessServices$tab29 updateBindings: FOREGROUND priority, 0 importance, 3 successful binds, 0 failed binds, 0 successful unbinds
[task 2022-05-11T03:38:33.018Z] 03:38:33     INFO -  05-11 03:38:00.851 I/MultiDex( 6678): VM with version 2.1.0 has multidex support
[task 2022-05-11T03:38:33.018Z] 03:38:33     INFO -  05-11 03:38:00.851 I/MultiDex( 6678): Installing application
[task 2022-05-11T03:38:33.019Z] 03:38:33     INFO -  05-11 03:38:00.851 I/MultiDex( 6678): VM has multidex support, MultiDex support library is disabled.
[task 2022-05-11T03:38:33.019Z] 03:38:33     INFO -  05-11 03:38:00.852 I/ServiceChildProcess( 6678): onCreate
[task 2022-05-11T03:38:33.019Z] 03:38:33     INFO -  05-11 03:38:00.853 D/GeckoThread( 6678): State changed to LAUNCHED
[task 2022-05-11T03:38:33.019Z] 03:38:33     INFO -  05-11 03:38:00.854 I/GeckoThread( 6678): preparing to run Gecko
<...>
[task 2022-05-11T03:38:33.045Z] 03:38:33     INFO -  05-11 03:38:02.103 D/GeckoViewContent( 3414): onEvent: event=GeckoView:SetFocused, data={"focused":false}
[task 2022-05-11T03:38:33.045Z] 03:38:33     INFO -  05-11 03:38:02.103 I/Gecko   ( 3414): nsWindow[0x72c8aa5c3d00]::Resize [0.000000 144.000000 0.000000 0.000000] (repaint 0)
[task 2022-05-11T03:38:33.045Z] 03:38:33     INFO -  05-11 03:38:02.104 I/Gecko   ( 3414): nsWindow[0x72c8aa5c3d00]::Resize [0.000000 144.000000 800.000000 1136.000000] (repaint 0)
[task 2022-05-11T03:38:33.045Z] 03:38:33     INFO -  05-11 03:38:02.104 I/Gecko   ( 3414): nsWindow: 0x72c8aa5c3d00 OnSizeChanged [800 1136]
[task 2022-05-11T03:38:33.046Z] 03:38:33     INFO -  05-11 03:38:02.104 D/GeckoViewSessionStateAggregator[C]( 6516): receiveMessage: GeckoView:FlushSessionState
[task 2022-05-11T03:38:33.046Z] 03:38:33     INFO -  05-11 03:38:02.105 D/GeckoViewModule( 3414): dispatch GeckoView:SetActive, data={"active":true}
[task 2022-05-11T03:38:33.046Z] 03:38:33     INFO -  05-11 03:38:02.105 D/GeckoViewContent( 3414): onEvent: event=GeckoView:SetActive, data={"active":true}
[task 2022-05-11T03:38:33.046Z] 03:38:33     INFO -  05-11 03:38:02.106 D/GeckoViewModule( 3414): dispatch GeckoView:SetFocused, data={"focused":true}
[task 2022-05-11T03:38:33.046Z] 03:38:33     INFO -  05-11 03:38:02.106 D/GeckoViewContent( 3414): onEvent: event=GeckoView:SetFocused, data={"focused":true}
[task 2022-05-11T03:38:33.046Z] 03:38:33     INFO -  05-11 03:38:02.107 D/GeckoViewConsole( 3414): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"78f6b59b-e816-45b0-bf86-6b0d3ac01593","args":{},"type":"GetPidForTab"}}
[task 2022-05-11T03:38:33.046Z] 03:38:33     INFO -  05-11 03:38:02.109 W/GeckoSession( 3414): No history entries found.
[task 2022-05-11T03:38:33.046Z] 03:38:33     INFO -  05-11 03:38:02.109 D/GeckoSession( 3414): handleMessage GeckoView:StateUpdated uri=null
[task 2022-05-11T03:38:33.047Z] 03:38:33     INFO -  05-11 03:38:02.118 E/eglCodecCommon( 3488): glUtilsParamSize: unknow param 0x00008caa
[task 2022-05-11T03:38:33.047Z] 03:38:33     INFO -  05-11 03:38:02.147 D/GeckoViewContentDelegateChild[C]( 6678): handleEvent: MozFirstContentfulPaint
[task 2022-05-11T03:38:33.047Z] 03:38:33     INFO -  05-11 03:38:02.167 D/GeckoViewConsole( 3414): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"47158dc6-9cc6-4ac0-832a-ba2945f2e37c","args":{},"type":"GetPidForTab"}}
[task 2022-05-11T03:38:33.047Z] 03:38:33     INFO -  05-11 03:38:02.171 W/webrender::device::gl( 3488): Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2022-05-11T03:38:33.047Z] 03:38:33     INFO -  05-11 03:38:02.173 W/webrender::device::gl( 3488): Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2022-05-11T03:38:33.047Z] 03:38:33     INFO -  05-11 03:38:02.176 W/webrender::device::gl( 3488): Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2022-05-11T03:38:33.047Z] 03:38:33     INFO -  05-11 03:38:02.186 E/eglCodecCommon( 3488): glUtilsParamSize: unknow param 0x00008caa
[task 2022-05-11T03:38:33.048Z] 03:38:33     INFO -  05-11 03:38:02.272 D/EGL_emulation( 3488): eglMakeCurrent: 0x72c8e327e600: ver 3 0 (tinfo 0x72c8d8b04f60)
[task 2022-05-11T03:38:33.048Z] 03:38:33     INFO -  05-11 03:38:02.274 W/GeckoSession( 3414): No history entries found.
[task 2022-05-11T03:38:33.048Z] 03:38:33     INFO -  05-11 03:38:02.274 D/GeckoSession( 3414): handleMessage GeckoView:StateUpdated uri=null
[task 2022-05-11T03:38:33.048Z] 03:38:33     INFO -  05-11 03:38:02.275 D/GeckoViewProgressDelegate[C]( 6678): handleEvent: MozAfterPaint
[task 2022-05-11T03:38:33.048Z] 03:38:33     INFO -  05-11 03:38:02.296 D/GeckoViewModule( 3414): dispatch GeckoView:SetActive, data={"active":false}
[task 2022-05-11T03:38:33.048Z] 03:38:33     INFO -  05-11 03:38:02.297 D/GeckoViewContent( 3414): onEvent: event=GeckoView:SetActive, data={"active":false}
[task 2022-05-11T03:38:33.048Z] 03:38:33     INFO -  05-11 03:38:02.298 D/GeckoViewModule( 3414): dispatch GeckoView:FlushSessionState, data=null
[task 2022-05-11T03:38:33.048Z] 03:38:33     INFO -  05-11 03:38:02.298 D/GeckoViewProgress( 3414): onEvent: event=GeckoView:FlushSessionState, data=null
[task 2022-05-11T03:38:33.048Z] 03:38:33     INFO -  05-11 03:38:02.298 D/GeckoViewModule( 3414): dispatch GeckoView:SetFocused, data={"focused":false}
[task 2022-05-11T03:38:33.048Z] 03:38:33     INFO -  05-11 03:38:02.298 D/GeckoViewSessionStateAggregator[C]( 6678): receiveMessage: GeckoView:FlushSessionState
[task 2022-05-11T03:38:33.049Z] 03:38:33     INFO -  05-11 03:38:02.298 D/GeckoViewContent( 3414): onEvent: event=GeckoView:SetFocused, data={"focused":false}
[task 2022-05-11T03:38:33.049Z] 03:38:33     INFO -  05-11 03:38:02.300 D/GeckoViewConsole( 3414): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"d4b52018-820b-4d57-a596-8f676dcbb545","args":{},"type":"GetPidForTab"}}
[task 2022-05-11T03:38:33.049Z] 03:38:33     INFO -  05-11 03:38:02.301 D/GeckoViewProgress( 3414): receiveMessage: MozAfterPaint
[task 2022-05-11T03:38:33.049Z] 03:38:33     INFO -  05-11 03:38:02.301 D/GeckoSession( 3414): handleMessage GeckoView:StateUpdated uri=null
[task 2022-05-11T03:38:33.049Z] 03:38:33     INFO -  05-11 03:38:02.355 D/ServiceAllocator( 3414): org.mozilla.gecko.process.GeckoChildProcessServices$tab26 updateBindings: IDLE priority, 6 importance, 0 successful binds, 0 failed binds, 2 successful unbinds
[task 2022-05-11T03:38:33.049Z] 03:38:33     INFO -  05-11 03:38:03.783 E/TaskPersister( 1659): File error accessing recents directory (directory doesn't exist?).
[task 2022-05-11T03:38:33.049Z] 03:38:33     INFO -  05-11 03:38:08.053 I/Gecko   ( 3414): nsWindow 0x72c8a7a32700 destructor
[task 2022-05-11T03:38:33.050Z] 03:38:33     INFO -  05-11 03:38:12.057 E/eglCodecCommon( 3488): glUtilsParamSize: unknow param 0x00008caa
[task 2022-05-11T03:38:33.050Z] 03:38:33     INFO -  05-11 03:38:12.274 E/eglCodecCommon( 3488): glUtilsParamSize: unknow param 0x00008caa
[task 2022-05-11T03:38:33.050Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): Error
[task 2022-05-11T03:38:33.050Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-05-11T03:38:33.050Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-05-11T03:38:33.050Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-05-11T03:38:33.050Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-05-11T03:38:33.050Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-05-11T03:38:33.050Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): 	at org.mozilla.geckoview.test.GeckoViewTest.waitUntilContentProcessPriority(GeckoViewTest.kt:85)
[task 2022-05-11T03:38:33.051Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): 	at org.mozilla.geckoview.test.GeckoViewTest.setTabActiveKeepsTabAtHighPriority$lambda-12(GeckoViewTest.kt:146)
[task 2022-05-11T03:38:33.051Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): 	at org.mozilla.geckoview.test.GeckoViewTest.$r8$lambda$ikq4DvYOtZ_ZIvxWdkokLGMxdmk(GeckoViewTest.kt)
[task 2022-05-11T03:38:33.051Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): 	at org.mozilla.geckoview.test.GeckoViewTest$$ExternalSyntheticLambda3.perform(Unknown Source)
[task 2022-05-11T03:38:33.051Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): 	at androidx.test.core.app.ActivityScenario.lambda$onActivity$2$ActivityScenario(ActivityScenario.java:660)
[task 2022-05-11T03:38:33.051Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): 	at androidx.test.core.app.ActivityScenario$$Lambda$4.run(ActivityScenario.java:652)
[task 2022-05-11T03:38:33.052Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): 	at androidx.test.core.app.ActivityScenario.onActivity(ActivityScenario.java:670)
[task 2022-05-11T03:38:33.052Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): 	at org.mozilla.geckoview.test.GeckoViewTest.setTabActiveKeepsTabAtHighPriority(GeckoViewTest.kt:130)
[task 2022-05-11T03:38:33.052Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2022-05-11T03:38:33.052Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2022-05-11T03:38:33.052Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2022-05-11T03:38:33.052Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2022-05-11T03:38:33.053Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2022-05-11T03:38:33.053Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): 	at androidx.test.internal.runner.junit4.statement.RunBefores.evaluate(RunBefores.java:80)
[task 2022-05-11T03:38:33.053Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): 	at androidx.test.internal.runner.junit4.statement.RunAfters.evaluate(RunAfters.java:61)
[task 2022-05-11T03:38:33.053Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$org-mozilla-geckoview-test-rule-GeckoSessionTestRule$2(GeckoSessionTestRule.java:1460)
[task 2022-05-11T03:38:33.054Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2$$ExternalSyntheticLambda0.run(Unknown Source)
[task 2022-05-11T03:38:33.054Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:428)
[task 2022-05-11T03:38:33.054Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): 	at java.util.concurrent.FutureTask.run(FutureTask.java:237)
[task 2022-05-11T03:38:33.054Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): 	at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2022-05-11T03:38:33.054Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-05-11T03:38:33.055Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-05-11T03:38:33.055Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): 	at android.os.Looper.loop(Looper.java:154)
[task 2022-05-11T03:38:33.055Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): 	at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2022-05-11T03:38:33.055Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2022-05-11T03:38:33.055Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2022-05-11T03:38:33.055Z] 03:38:33     INFO -  05-11 03:38:32.358 E/GeckoSessionTestRule( 3414): 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2022-05-11T03:38:33.056Z] 03:38:33     INFO -  05-11 03:38:32.362 D/GeckoViewConsole( 3414): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"4cf53f6e-b0d2-4bf5-87da-57ffad470c17","args":{"oldPrefs":{"dom.ipc.processPriorityManager.backgroundGracePeriodMS":3000,"dom.ipc.processPriorityManager.backgroundPerceivableGracePeriodMS":3000}},"type":"RestorePrefs"}}
[task 2022-05-11T03:38:33.056Z] 03:38:33     INFO -  05-11 03:38:32.376 D/GeckoViewContent( 3414): handleEvent: DOMWindowClose
[task 2022-05-11T03:38:33.056Z] 03:38:33     INFO -  05-11 03:38:32.378 D/GeckoViewContent( 3414): handleEvent: DOMWindowClose
[task 2022-05-11T03:38:33.056Z] 03:38:33     INFO -  05-11 03:38:32.379 D/GeckoViewConsole( 3414): onEvent GeckoView:WebExtension:List null
[task 2022-05-11T03:38:33.056Z] 03:38:33     INFO -  05-11 03:38:32.383 E/GeckoSessionTestRule( 3414): test_end c5ee677f-bc83-49bd-9e28-2d35f3d0f059 setTabActiveKeepsTabAtHighPriority(org.mozilla.geckoview.test.GeckoViewTest)
[task 2022-05-11T03:38:33.056Z] 03:38:33  WARNING -  TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.GeckoViewTest#setTabActiveKeepsTabAtHighPriority | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-05-11T03:38:33.056Z] 03:38:33     INFO -  TEST-INFO took 32096ms
[task 2022-05-11T03:38:33.064Z] 03:38:33     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=903
[task 2022-05-11T03:38:33.064Z] 03:38:33     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2022-05-11T03:38:33.064Z] 03:38:33     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2022-05-11T03:38:33.064Z] 03:38:33     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=setSessionOnClosed
[task 2022-05-11T03:38:33.064Z] 03:38:33     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.GeckoViewTest
[task 2022-05-11T03:38:33.064Z] 03:38:33     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=318
[task 2022-05-11T03:38:33.064Z] 03:38:33     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: 1
[task 2022-05-11T03:38:33.064Z] 03:38:33     INFO -  TEST-START | org.mozilla.geckoview.test.GeckoViewTest#setSessionOnClosed

Comment 1

[Cosmin Sabou [:CosminS]]

Cathy, these look to be something started from changes in Bug 1764998. Could you please have a look over it? Thank you.

Comment 2

[BugBot [:suhaib / :marco/ :calixte]]

Set release status flags based on info from the regressing bug 1764998

Comment 3

[Intermittent Failures Robot]

18 failures in 549 pushes (0.033 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 12
• mozilla-central: 4
• try: 2

Platform and build breakdown:

• android-em-7-0-x86_64-lite-qr: 10
  • debug: 10
• android-em-7-0-x86_64-qr: 6
  • opt: 1
  • debug: 5
• android-em-7-0-x86_64-shippable-lite-qr: 1
  • opt: 1
• android-em-7-0-x86_64-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1768776&startday=2022-05-11&endday=2022-05-11&tree=all

Comment 4

[[ex-Mozilla] Agi Sferro | :agi]

I just noticed, there's a typo in the test:

--- a/mobile/android/geckoview/src/androidTest/java/org/mozilla/geckoview/test/GeckoViewTest.kt
+++ b/mobile/android/geckoview/src/androidTest/java/org/mozilla/geckoview/test/GeckoViewTest.kt
@@ -127,51 +127,51 @@ class GeckoViewTest : BaseSessionTest() 
     fun setTabActiveKeepsTabAtHighPriority() {
         // Bug 1768102 - Doesn't seem to work on Fission
         assumeThat(env.isFission || env.isIsolatedProcess, equalTo(false))
         activityRule.scenario.onActivity {
             val otherSession = setupPriorityTest()
 
             // A tab with priority hint does not get de-prioritized even when
             // the surface is destroyed
-            sessionRule.runtime.webExtensionController.setTabActive(mainSession, true)
+            otherSession.setPriorityHint(GeckoSession.PRIORITY_HIGH)
 
             // This will destroy mainSession's surface and create a surface for otherSession
             it.view.setSession(otherSession)
 
             waitUntilContentProcessPriority(high = listOf(mainSession, otherSession), low = listOf())
 
             // Destroying otherSession's surface should leave mainSession as the sole high priority
             // tab
             it.view.releaseSession()
 
             waitUntilContentProcessPriority(high = listOf(mainSession), low = listOf())
 
             // Cleanup
-            sessionRule.runtime.webExtensionController.setTabActive(mainSession, false)
+            otherSession.setPriorityHint(GeckoSession.PRIORITY_DEFAULT)
         }
     }

the before code sets the tab active to mainSession, while the after code sets the priority hint to otherSession, while it should be mainSession, the correct code would be:

             // Cleanup
             mainSession.setPriorityHint(GeckoSession.PRIORITY_DEFAULT)
         }

Also here:

@@ -194,15 +194,38 @@ class GeckoViewTest : BaseSessionTest() 
             waitUntilContentProcessPriority(
                 high = listOf(), low = listOf(mainSession, otherSession))
 
             // Test that re-setting mainSession in the view raises the priority again
             it.view.setSession(mainSession)
             waitUntilContentProcessPriority(
                 high = listOf(mainSession), low = listOf(otherSession))
 
-            // Setting the session to active should also raise priority
-            otherSession.setActive(true)
+            // Setting priorityHint to PRIORITY_HIGH raises priority
+            otherSession.setPriorityHint(GeckoSession.PRIORITY_HIGH)
             waitUntilContentProcessPriority(
                 high = listOf(mainSession, otherSession), low = listOf())
         }
     }

This is not correct, in the test above we're checking whether setting tab to active raises priority and that should not change after the priority hint patch.

Sorry about that, it's my bad I missed these things during code review.

Comment 5

[Intermittent Failures Robot]

18 failures in 680 pushes (0.026 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 16
• try: 2

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 6
  • debug: 6
• android-em-7-0-x86_64-lite-qr: 10
  • debug: 10
• android-em-7-0-x86_64-shippable-lite-qr: 1
  • opt: 1
• android-em-7-0-x86_64-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1768776&startday=2022-05-12&endday=2022-05-12&tree=all

Comment 6

[Cathy Lu [:calu]]

Attachment: Bug 1768776 - Fix intermittent priority tests to set the correct session's priority hint

Comment 7

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/31796cd9dc3a
Fix intermittent priority tests to set the correct session's priority hint r=geckoview-reviewers,agi

Comment 8

[Iulian Moraru]

https://hg.mozilla.org/mozilla-central/rev/31796cd9dc3a

Comment 9

[Intermittent Failures Robot]

18 failures in 631 pushes (0.029 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 12
• mozilla-central: 2
• try: 4

Platform and build breakdown:

• android-em-7-0-x86_64-lite-qr: 6
  • debug: 6
• android-em-7-0-x86_64-qr: 8
  • debug: 7
  • opt: 1
• android-em-7-0-x86_64-shippable-lite-qr: 2
  • opt: 2
• android-em-7-0-x86_64-shippable-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1768776&startday=2022-05-13&endday=2022-05-13&tree=all

Comment 10

[Intermittent Failures Robot]

54 failures in 3317 pushes (0.016 failures/push) were associated with this bug in the last 7 days.

This is the #15 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 40
• mozilla-central: 6
• try: 8

Platform and build breakdown:

• android-em-7-0-x86_64-lite-qr: 26
  • debug: 26
• android-em-7-0-x86_64-qr: 20
  • opt: 2
  • debug: 18
• android-em-7-0-x86_64-shippable-lite-qr: 4
  • opt: 4
• android-em-7-0-x86_64-shippable-qr: 4
  • opt: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1768776&startday=2022-05-09&endday=2022-05-15&tree=all