1765600 - Intermittent org.mozilla.geckoview.test.OrientationDelegateTest#orientationUnlockByExitFullscreen | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms

Status: RESOLVED INCOMPLETE

(GeckoView :: General, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2022-04-20T17:34:09.997Z] 17:34:09     INFO -  TEST-START | org.mozilla.geckoview.test.OrientationDelegateTest#orientationUnlockByExitFullscreen
[task 2022-04-20T17:34:41.386Z] 17:34:41     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=887
[task 2022-04-20T17:34:41.386Z] 17:34:41     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2022-04-20T17:34:41.386Z] 17:34:41     INFO -  org.mozilla.geckoview.test | Error in orientationUnlockByExitFullscreen(org.mozilla.geckoview.test.OrientationDelegateTest):
[task 2022-04-20T17:34:41.387Z] 17:34:41     INFO -  org.mozilla.geckoview.test | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-04-20T17:34:41.387Z] 17:34:41     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-04-20T17:34:41.387Z] 17:34:41     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-04-20T17:34:41.387Z] 17:34:41     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-04-20T17:34:41.387Z] 17:34:41     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-04-20T17:34:41.387Z] 17:34:41     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1741)
[task 2022-04-20T17:34:41.387Z] 17:34:41     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1665)
[task 2022-04-20T17:34:41.387Z] 17:34:41     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1617)
[task 2022-04-20T17:34:41.388Z] 17:34:41     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.OrientationDelegateTest.orientationUnlockByExitFullscreen(OrientationDelegateTest.kt:228)
[task 2022-04-20T17:34:41.388Z] 17:34:41     INFO -  org.mozilla.geckoview.test |
[task 2022-04-20T17:34:41.388Z] 17:34:41     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2022-04-20T17:34:41.388Z] 17:34:41     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=orientationUnlockByExitFullscreen
[task 2022-04-20T17:34:41.388Z] 17:34:41     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.OrientationDelegateTest
[task 2022-04-20T17:34:41.388Z] 17:34:41     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-04-20T17:34:41.388Z] 17:34:41     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-04-20T17:34:41.388Z] 17:34:41     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-04-20T17:34:41.389Z] 17:34:41     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-04-20T17:34:41.389Z] 17:34:41     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-04-20T17:34:41.389Z] 17:34:41     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1741)
[task 2022-04-20T17:34:41.389Z] 17:34:41     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1665)
[task 2022-04-20T17:34:41.389Z] 17:34:41     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1617)
[task 2022-04-20T17:34:41.389Z] 17:34:41     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.OrientationDelegateTest.orientationUnlockByExitFullscreen(OrientationDelegateTest.kt:228)
[task 2022-04-20T17:34:41.389Z] 17:34:41     INFO -  org.mozilla.geckoview.test |
[task 2022-04-20T17:34:41.389Z] 17:34:41     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=421
[task 2022-04-20T17:34:41.389Z] 17:34:41     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: -2
[task 2022-04-20T17:34:41.390Z] 17:34:41     INFO -  Printing logcat for test:
[task 2022-04-20T17:34:41.433Z] 17:34:41     INFO -  04-20 17:34:09.396 E/GeckoSessionTestRule( 3482): test_start 1f0befec-3ff2-40ff-89cf-b127eb38b1ec orientationUnlockByExitFullscreen(org.mozilla.geckoview.test.OrientationDelegateTest)
[task 2022-04-20T17:34:41.433Z] 17:34:41     INFO -  04-20 17:34:09.396 E/GeckoSessionTestRule( 3482): before prepareStatement orientationUnlockByExitFullscreen(org.mozilla.geckoview.test.OrientationDelegateTest)
[task 2022-04-20T17:34:41.433Z] 17:34:41     INFO -  04-20 17:34:09.398 D/GeckoViewStartup( 3482): onEvent GeckoView:StorageDelegate:Attached
[task 2022-04-20T17:34:41.433Z] 17:34:41     INFO -  04-20 17:34:09.398 D/LifecycleMonitor( 3482): Lifecycle status change: androidx.test.core.app.InstrumentationActivityInvoker$BootstrapActivity@f46966e in: STOPPED
[task 2022-04-20T17:34:41.434Z] 17:34:41     INFO -  04-20 17:34:09.398 V/ActivityScenario( 3482): 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@f46966e
[task 2022-04-20T17:34:41.434Z] 17:34:41     INFO -  04-20 17:34:09.409 D/GeckoViewNavigation( 3482): sessionContextId=null
[task 2022-04-20T17:34:41.434Z] 17:34:41     INFO -  04-20 17:34:09.418 E/eglCodecCommon( 6783): glUtilsParamSize: unknow param 0x00008caa
[task 2022-04-20T17:34:41.434Z] 17:34:41     INFO -  04-20 17:34:09.425 E/eglCodecCommon( 6783): glUtilsParamSize: unknow param 0x00008741
[task 2022-04-20T17:34:41.435Z] 17:34:41     INFO -  04-20 17:34:09.428 D/GeckoViewModule( 3482): registerListener ["GeckoViewContent:ExitFullScreen","GeckoView:ClearMatches","GeckoView:DisplayMatches","GeckoView:FindInPage","GeckoView:RestoreState","GeckoView:ScrollBy","GeckoView:ScrollTo","GeckoView:SetActive","GeckoView:SetFocused","GeckoView:UpdateInitData","GeckoView:ZoomToInput"]
[task 2022-04-20T17:34:41.435Z] 17:34:41     INFO -  04-20 17:34:09.429 D/GeckoViewNavigation( 3482): onInit
[task 2022-04-20T17:34:41.435Z] 17:34:41     INFO -  04-20 17:34:09.429 D/GeckoViewModule( 3482): registerListener ["GeckoView:GoBack","GeckoView:GoForward","GeckoView:GotoHistoryIndex","GeckoView:LoadUri","GeckoView:Reload","GeckoView:Stop","GeckoView:PurgeHistory"]
[task 2022-04-20T17:34:41.435Z] 17:34:41     INFO -  04-20 17:34:09.429 D/GeckoViewNavigation( 3482): onEnable
[task 2022-04-20T17:34:41.435Z] 17:34:41     INFO -  04-20 17:34:09.429 D/GeckoViewProcessHangMonitor( 3482): onInit
[task 2022-04-20T17:34:41.435Z] 17:34:41     INFO -  04-20 17:34:09.429 D/GeckoViewProcessHangMonitor( 3482): onEnable
[task 2022-04-20T17:34:41.435Z] 17:34:41     INFO -  04-20 17:34:09.429 D/GeckoViewModule( 3482): registerListener ["GeckoView:HangReportStop","GeckoView:HangReportWait"]
[task 2022-04-20T17:34:41.435Z] 17:34:41     INFO -  04-20 17:34:09.429 D/GeckoViewProgress( 3482): onEnable
[task 2022-04-20T17:34:41.435Z] 17:34:41     INFO -  04-20 17:34:09.430 D/GeckoViewModule( 3482): registerListener GeckoView:FlushSessionState
[task 2022-04-20T17:34:41.436Z] 17:34:41     INFO -  04-20 17:34:09.430 D/GeckoViewSettings( 3482): onInit
[task 2022-04-20T17:34:41.436Z] 17:34:41     INFO -  04-20 17:34:09.430 D/GeckoViewModule( 3482): registerListener ["GeckoView:GetUserAgent"]
[task 2022-04-20T17:34:41.436Z] 17:34:41     INFO -  04-20 17:34:09.430 D/GeckoViewSettings( 3482): 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-04-20T17:34:41.436Z] 17:34:41     INFO -  04-20 17:34:09.431 D/GeckoViewModule( 3482): registerListener ["GeckoView:WebExtension:SetTabActive"]
[task 2022-04-20T17:34:41.436Z] 17:34:41     INFO -  04-20 17:34:09.431 D/GeckoViewModule( 3482): registerListener ["ContentBlocking:RequestLog"]
[task 2022-04-20T17:34:41.436Z] 17:34:41     INFO -  04-20 17:34:09.432 D/GeckoViewMediaControl( 3482): onEnable
[task 2022-04-20T17:34:41.437Z] 17:34:41     INFO -  04-20 17:34:09.432 D/GeckoViewModule( 3482): 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-04-20T17:34:41.437Z] 17:34:41     INFO -  04-20 17:34:09.433 I/GeckoSession( 3482): zerdatime 210938 - chrome startup finished
[task 2022-04-20T17:34:41.437Z] 17:34:41     INFO -  04-20 17:34:09.435 D/EGL_emulation( 6783): eglMakeCurrent: 0x7c63b5e7bae0: ver 3 0 (tinfo 0x7c63ab7c3140)
[task 2022-04-20T17:34:41.437Z] 17:34:41     INFO -  04-20 17:34:09.437 D/GeckoSession( 3482): handleMessage GeckoView:PageStart uri=about:blank
[task 2022-04-20T17:34:41.437Z] 17:34:41     INFO -  04-20 17:34:09.437 E/GeckoSessionTestRule( 3482): method: public abstract void org.mozilla.geckoview.GeckoSession$ProgressDelegate.onPageStart(org.mozilla.geckoview.GeckoSession,java.lang.String)
[task 2022-04-20T17:34:41.437Z] 17:34:41     INFO -  04-20 17:34:09.437 D/GeckoSession( 3482): handleMessage GeckoView:LocationChange uri=about:blank
[task 2022-04-20T17:34:41.438Z] 17:34:41     INFO -  04-20 17:34:09.437 E/GeckoSessionTestRule( 3482): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onLocationChange(org.mozilla.geckoview.GeckoSession,java.lang.String,java.util.List)
[task 2022-04-20T17:34:41.438Z] 17:34:41     INFO -  04-20 17:34:09.437 E/GeckoSessionTestRule( 3482): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onLocationChange(org.mozilla.geckoview.GeckoSession,java.lang.String)
[task 2022-04-20T17:34:41.438Z] 17:34:41     INFO -  04-20 17:34:09.437 E/GeckoSessionTestRule( 3482): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onCanGoBack(org.mozilla.geckoview.GeckoSession,boolean)
[task 2022-04-20T17:34:41.438Z] 17:34:41     INFO -  04-20 17:34:09.437 E/GeckoSessionTestRule( 3482): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onCanGoForward(org.mozilla.geckoview.GeckoSession,boolean)
[task 2022-04-20T17:34:41.438Z] 17:34:41     INFO -  04-20 17:34:09.437 D/GeckoSession( 3482): handleMessage GeckoView:PageStop uri=null
[task 2022-04-20T17:34:41.438Z] 17:34:41     INFO -  04-20 17:34:09.437 E/GeckoSessionTestRule( 3482): method: public abstract void org.mozilla.geckoview.GeckoSession$ProgressDelegate.onPageStop(org.mozilla.geckoview.GeckoSession,boolean)
[task 2022-04-20T17:34:41.439Z] 17:34:41     INFO -  04-20 17:34:09.437 E/GeckoSessionTestRule( 3482): after prepareStatement
[task 2022-04-20T17:34:41.439Z] 17:34:41     INFO -  04-20 17:34:09.438 D/GeckoViewConsole( 3482): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"3f0297e1-1781-4c9b-8fd5-fbd75d034f3c","args":{"oldPrefs":{},"newPrefs":{"dom.screenorientation.allow-lock":true}},"type":"SetPrefs"}}
[task 2022-04-20T17:34:41.439Z] 17:34:41     INFO -  04-20 17:34:09.438 W/GeckoEditableChild( 7661): No editable parent
[task 2022-04-20T17:34:41.439Z] 17:34:41     INFO -  04-20 17:34:09.441 D/GeckoViewSelectionActionDelegate[C]( 7661): handleEvent: visibilitychange
[task 2022-04-20T17:34:41.439Z] 17:34:41     INFO -  04-20 17:34:09.442 D/GeckoViewConsole( 3482): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"ca6e954b-a25f-4dc2-a337-2dc3d823e6b5","args":{"oldPrefs":{"dom.screenorientation.allow-lock":false},"newPrefs":{"full-screen-api.allow-trusted-requests-only":false}},"type":"SetPrefs"}}
[task 2022-04-20T17:34:41.439Z] 17:34:41     INFO -  04-20 17:34:09.442 D/GeckoViewSettings[C]( 7661): receiveMessage: SettingsUpdate
[task 2022-04-20T17:34:41.439Z] 17:34:41     INFO -  04-20 17:34:09.445 D/GeckoViewXUL( 3482): receiveMessage GeckoView:ContentModuleLoaded {"module":"SessionStateAggregator"}
[task 2022-04-20T17:34:41.440Z] 17:34:41     INFO -  04-20 17:34:09.445 E/eglCodecCommon( 6783): glUtilsParamSize: unknow param 0x00008caa
[task 2022-04-20T17:34:41.440Z] 17:34:41     INFO -  04-20 17:34:09.447 D/GeckoViewModule( 3482): dispatch GeckoView:LoadUri, data={"uri":"http://localhost:4245/assets/www/fullscreen.html","flags":0,"headerFilter":1}
[task 2022-04-20T17:34:41.440Z] 17:34:41     INFO -  04-20 17:34:09.447 D/GeckoViewNavigation( 3482): onEvent: event=GeckoView:LoadUri, data={"uri":"http://localhost:4245/assets/www/fullscreen.html","flags":0,"headerFilter":1}
[task 2022-04-20T17:34:41.440Z] 17:34:41     INFO -  04-20 17:34:09.450 D/GeckoViewProgress( 3482): ProgressTracker onStateChange: isTopLevel=true, flags=0xf0001, status=NS_OK
[task 2022-04-20T17:34:41.440Z] 17:34:41     INFO -  04-20 17:34:09.451 D/GeckoViewProgress( 3482): ProgressTracker onStateChange: uri=http://localhost:4245/assets/www/fullscreen.html
[task 2022-04-20T17:34:41.440Z] 17:34:41     INFO -  04-20 17:34:09.451 D/GeckoViewProgress( 3482): ProgressTracker start http://localhost:4245/assets/www/fullscreen.html
[task 2022-04-20T17:34:41.440Z] 17:34:41     INFO -  04-20 17:34:09.451 D/GeckoViewProgress( 3482): ProgressTracker updateProgress
[task 2022-04-20T17:34:41.441Z] 17:34:41     INFO -  04-20 17:34:09.451 D/GeckoViewProgress( 3482): ProgressTracker updateProgress data={"prev":0,"uri":"http://localhost:4245/assets/www/fullscreen.html","locationChange":false,"pageStart":true,"pageStop":false,"firstPaint":false,"pageShow":false,"parsed":false} progress=15
[task 2022-04-20T17:34:41.441Z] 17:34:41     INFO -  04-20 17:34:09.451 D/GeckoSession( 3482): handleMessage GeckoView:ProgressChanged uri=null
[task 2022-04-20T17:34:41.441Z] 17:34:41     INFO -  04-20 17:34:09.451 D/GeckoViewProgress( 3482): StateTracker onStateChange: isTopLevel=true, flags=0xf0001, status=NS_OK loadType=1
[task 2022-04-20T17:34:41.441Z] 17:34:41     INFO -  04-20 17:34:09.451 D/GeckoSession( 3482): handleMessage GeckoView:PageStart uri=http://localhost:4245/assets/www/fullscreen.html
[task 2022-04-20T17:34:41.441Z] 17:34:41     INFO -  04-20 17:34:09.455 D/GeckoViewSelectionActionDelegate[C]( 7661): handleEvent: visibilitychange
[task 2022-04-20T17:34:41.441Z] 17:34:41     INFO -  04-20 17:34:09.456 D/GeckoViewAutoFill[C]( 7661): handleEvent: pagehide
[task 2022-04-20T17:34:41.441Z] 17:34:41     INFO -  04-20 17:34:09.456 D/GeckoViewAutofill( 7661): Clearing auto-fill
[task 2022-04-20T17:34:41.442Z] 17:34:41     INFO -  04-20 17:34:09.459 W/Web Content( 7661): [JavaScript Warning: "This page is in Quirks Mode. Page layout may be impacted. For Standards Mode use “<!DOCTYPE html>”." {file: "http://localhost:4245/assets/www/fullscreen.html" line: 0}]
[task 2022-04-20T17:34:41.442Z] 17:34:41     INFO -  04-20 17:34:09.459 D/GeckoViewNavigation( 3482): onLocationChange
[task 2022-04-20T17:34:41.442Z] 17:34:41     INFO -  04-20 17:34:09.459 D/GeckoSession( 3482): handleMessage GeckoView:LocationChange uri=http://localhost:4245/assets/www/fullscreen.html
[task 2022-04-20T17:34:41.442Z] 17:34:41     INFO -  04-20 17:34:09.460 D/GeckoViewProgress( 3482): SecurityTracker onLocationChange: location=http://localhost:4245/assets/www/fullscreen.html, flags=0
[task 2022-04-20T17:34:41.442Z] 17:34:41     INFO -  04-20 17:34:09.460 D/GeckoViewProgress( 3482): ProgressTracker onLocationChange: location=http://localhost:4245/assets/www/fullscreen.html, flags=0
[task 2022-04-20T17:34:41.442Z] 17:34:41     INFO -  04-20 17:34:09.460 D/GeckoViewProgress( 3482): ProgressTracker changeLocation http://localhost:4245/assets/www/fullscreen.html
[task 2022-04-20T17:34:41.442Z] 17:34:41     INFO -  04-20 17:34:09.460 D/GeckoViewProgress( 3482): onSecurityChange
[task 2022-04-20T17:34:41.442Z] 17:34:41     INFO -  04-20 17:34:09.460 D/GeckoSession( 3482): handleMessage GeckoView:SecurityChanged uri=null
[task 2022-04-20T17:34:41.443Z] 17:34:41     INFO -  04-20 17:34:09.463 D/GeckoViewContent( 3482): handleEvent: pagetitlechanged
[task 2022-04-20T17:34:41.443Z] 17:34:41     INFO -  04-20 17:34:09.463 D/GeckoViewContentDelegateChild[C]( 7661): handleEvent: DOMContentLoaded
[task 2022-04-20T17:34:41.443Z] 17:34:41     INFO -  04-20 17:34:09.463 D/GeckoViewProgressDelegate[C]( 7661): handleEvent: DOMContentLoaded
[task 2022-04-20T17:34:41.443Z] 17:34:41     INFO -  04-20 17:34:09.464 D/GeckoViewProgress( 3482): receiveMessage: DOMContentLoaded
[task 2022-04-20T17:34:41.443Z] 17:34:41     INFO -  04-20 17:34:09.464 D/GeckoViewProgress( 3482): ProgressTracker handleEvent: DOMContentLoaded
[task 2022-04-20T17:34:41.443Z] 17:34:41     INFO -  04-20 17:34:09.464 D/GeckoViewProgress( 3482): ProgressTracker updateProgress
[task 2022-04-20T17:34:41.443Z] 17:34:41     INFO -  04-20 17:34:09.464 D/GeckoViewProgress( 3482): ProgressTracker updateProgress data={"prev":15,"uri":"http://localhost:4245/assets/www/fullscreen.html","locationChange":true,"pageStart":true,"pageStop":false,"firstPaint":false,"pageShow":false,"parsed":true} progress=55
[task 2022-04-20T17:34:41.443Z] 17:34:41     INFO -  04-20 17:34:09.464 D/GeckoSession( 3482): handleMessage GeckoView:ProgressChanged uri=null
[task 2022-04-20T17:34:41.443Z] 17:34:41     INFO -  04-20 17:34:09.467 D/GeckoViewProgressDelegate[C]( 7661): handleEvent: pageshow
[task 2022-04-20T17:34:41.444Z] 17:34:41     INFO -  04-20 17:34:09.467 D/GeckoViewAutoFill[C]( 7661): handleEvent: pageshow
[task 2022-04-20T17:34:41.444Z] 17:34:41     INFO -  04-20 17:34:09.467 D/GeckoViewContent[C]( 7661): handleEvent: pageshow
[task 2022-04-20T17:34:41.444Z] 17:34:41     INFO -  04-20 17:34:09.468 D/WebExtension( 3482): handleMessage GeckoView:WebExtension:Connect
[task 2022-04-20T17:34:41.444Z] 17:34:41     INFO -  04-20 17:34:09.469 D/GeckoViewProgress( 3482): receiveMessage: pageshow
[task 2022-04-20T17:34:41.444Z] 17:34:41     INFO -  04-20 17:34:09.469 D/GeckoViewProgress( 3482): ProgressTracker handleEvent: pageshow
[task 2022-04-20T17:34:41.444Z] 17:34:41     INFO -  04-20 17:34:09.469 D/GeckoViewProgress( 3482): ProgressTracker updateProgress
[task 2022-04-20T17:34:41.444Z] 17:34:41     INFO -  04-20 17:34:09.470 D/GeckoViewProgress( 3482): ProgressTracker updateProgress data={"prev":55,"uri":"http://localhost:4245/assets/www/fullscreen.html","locationChange":true,"pageStart":true,"pageStop":false,"firstPaint":false,"pageShow":true,"parsed":true} progress=100
[task 2022-04-20T17:34:41.444Z] 17:34:41     INFO -  04-20 17:34:09.470 D/GeckoSession( 3482): handleMessage GeckoView:ProgressChanged uri=null
[task 2022-04-20T17:34:41.445Z] 17:34:41     INFO -  04-20 17:34:09.470 D/GeckoViewProgress( 3482): ProgressTracker onStateChange: isTopLevel=true, flags=0xc0010, status=NS_OK
[task 2022-04-20T17:34:41.445Z] 17:34:41     INFO -  04-20 17:34:09.470 D/GeckoViewProgress( 3482): ProgressTracker onStateChange: uri=http://localhost:4245/assets/www/fullscreen.html
[task 2022-04-20T17:34:41.445Z] 17:34:41     INFO -  04-20 17:34:09.470 D/GeckoViewProgress( 3482): ProgressTracker stop
[task 2022-04-20T17:34:41.445Z] 17:34:41     INFO -  04-20 17:34:09.470 D/GeckoViewProgress( 3482): ProgressTracker updateProgress
[task 2022-04-20T17:34:41.445Z] 17:34:41     INFO -  04-20 17:34:09.471 D/GeckoViewProgress( 3482): StateTracker onStateChange: isTopLevel=true, flags=0xc0010, status=NS_OK loadType=1
[task 2022-04-20T17:34:41.445Z] 17:34:41     INFO -  04-20 17:34:09.471 D/GeckoSession( 3482): handleMessage GeckoView:PageStop uri=null
[task 2022-04-20T17:34:41.445Z] 17:34:41     INFO -  04-20 17:34:09.471 D/GeckoViewConsole( 3482): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"375c4fd1-8100-44ed-8cd7-4817a4cff5c0","eval":"this['be046926-4b3d-4543-9199-09e2bb107a88'] = document.querySelector('#fullscreen').requestFullscreen(); true"}}
[task 2022-04-20T17:34:41.445Z] 17:34:41     INFO -  04-20 17:34:09.473 D/GeckoViewContentDelegateChild[C]( 7661): handleEvent: MozDOMFullscreen:Request
[task 2022-04-20T17:34:41.446Z] 17:34:41     INFO -  04-20 17:34:09.473 D/GeckoViewContentDelegateParent( 3482): receiveMessage: GeckoView:DOMFullscreenRequest {"data":{},"json":{},"name":"GeckoView:DOMFullscreenRequest","sync":false,"target":{}}
[task 2022-04-20T17:34:41.446Z] 17:34:41     INFO -  04-20 17:34:09.474 D/GeckoViewConsole( 3482): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"1746038a-ec03-4f17-8655-5172a60db798","eval":"this['be046926-4b3d-4543-9199-09e2bb107a88']"}}
[task 2022-04-20T17:34:41.446Z] 17:34:41     INFO -  04-20 17:34:09.479 D/GeckoViewContentDelegateChild[C]( 7661): handleEvent: MozFirstContentfulPaint
[task 2022-04-20T17:34:41.446Z] 17:34:41     INFO -  04-20 17:34:09.504 E/eglCodecCommon( 6783): glUtilsParamSize: unknow param 0x00008caa
[task 2022-04-20T17:34:41.446Z] 17:34:41     INFO -  04-20 17:34:09.509 D/GeckoViewProgressDelegate[C]( 7661): handleEvent: MozAfterPaint
[task 2022-04-20T17:34:41.446Z] 17:34:41     INFO -  04-20 17:34:09.510 D/GeckoViewProgress( 3482): receiveMessage: MozAfterPaint
[task 2022-04-20T17:34:41.446Z] 17:34:41     INFO -  04-20 17:34:09.512 E/eglCodecCommon( 6783): glUtilsParamSize: unknow param 0x00008caa
[task 2022-04-20T17:34:41.446Z] 17:34:41     INFO -  04-20 17:34:09.538 D/gralloc_ranchu( 1336): gralloc_alloc: Creating ashmem region of size 4096000
[task 2022-04-20T17:34:41.447Z] 17:34:41     INFO -  04-20 17:34:09.549 E/eglCodecCommon( 6783): glUtilsParamSize: unknow param 0x00008741
[task 2022-04-20T17:34:41.447Z] 17:34:41     INFO -  04-20 17:34:09.554 D/GeckoViewContent( 3482): handleEvent: MozDOMFullscreen:Entered
[task 2022-04-20T17:34:41.447Z] 17:34:41     INFO -  04-20 17:34:09.555 D/GeckoViewContent[C]( 7661): receiveMessage: GeckoView:DOMFullscreenEntered
[task 2022-04-20T17:34:41.447Z] 17:34:41     INFO -  04-20 17:34:09.555 D/GeckoViewContentDelegateChild[C]( 7661): handleEvent: MozDOMFullscreen:Entered
[task 2022-04-20T17:34:41.447Z] 17:34:41     INFO -  04-20 17:34:09.556 D/GeckoViewMediaControlDelegateChild[C]( 7661): handleEvent: MozDOMFullscreen:Entered
[task 2022-04-20T17:34:41.447Z] 17:34:41     INFO -  04-20 17:34:09.556 D/GeckoViewMediaControlDelegateChild[C]( 7661): handleFullscreenChanged
[task 2022-04-20T17:34:41.447Z] 17:34:41     INFO -  04-20 17:34:09.556 D/GeckoViewMediaControlDelegateChild[C]( 7661): No fullscreen media element found.
[task 2022-04-20T17:34:41.447Z] 17:34:41     INFO -  04-20 17:34:09.558 D/GeckoViewConsole( 3482): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"15a203b1-ff32-44ca-8d8b-ba7b0bbb3e12","eval":"this['3e33c956-8f07-40a4-938a-77f9d92c378f'] = document.exitFullscreen(); true"}}
[task 2022-04-20T17:34:41.448Z] 17:34:41     INFO -  04-20 17:34:09.560 D/GeckoViewContentDelegateChild[C]( 7661): handleEvent: MozDOMFullscreen:Exit
[task 2022-04-20T17:34:41.448Z] 17:34:41     INFO -  04-20 17:34:09.560 D/GeckoViewContentDelegateParent( 3482): receiveMessage: GeckoView:DOMFullscreenExit {"data":{},"json":{},"name":"GeckoView:DOMFullscreenExit","sync":false,"target":{}}
[task 2022-04-20T17:34:41.448Z] 17:34:41     INFO -  04-20 17:34:09.562 I/WindowManager( 1652): 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.destroyOrSaveSurface:2073 com.android.server.wm.AppWindowToken.destroySurfaces:363 com.android.server.wm.WindowStateAnimator.finishExit:565 com.android.server.wm.WindowStateAnimator.stepAnimationLocked:491 com.android.server.wm.WindowAnimator.updateWindowsLocked:303 com.android.server.wm.WindowAnimator.animateLocked:704
[task 2022-04-20T17:34:41.448Z] 17:34:41     INFO -  04-20 17:34:09.565 I/InputReader( 1652): Reconfiguring input devices.  changes=0x00000004
[task 2022-04-20T17:34:41.449Z] 17:34:41     INFO -  04-20 17:34:09.565 I/InputReader( 1652): Device reconfigured: id=0, name='qwerty2', size 800x1280, orientation 0, mode 1, display id 0
[task 2022-04-20T17:34:41.449Z] 17:34:41     INFO -  04-20 17:34:09.565 D/GeckoScreenOrientation( 3482): updating to new orientation PORTRAIT_PRIMARY
[task 2022-04-20T17:34:41.449Z] 17:34:41     INFO -  04-20 17:34:09.565 I/ActivityManager( 1652): Config changes=480 {1.0 310mcc260mnc [en_US] ldltr sw400dp w400dp h616dp 320dpi nrml port finger qwerty/v/v tball/v s.12}
[task 2022-04-20T17:34:41.449Z] 17:34:41     INFO -  04-20 17:34:09.569 D/LifecycleMonitor( 3482): Lifecycle status change: org.mozilla.geckoview.test.GeckoViewTestActivity@e1b4ba5 in: PAUSED
[task 2022-04-20T17:34:41.449Z] 17:34:41     INFO -  04-20 17:34:09.570 V/ActivityScenario( 3482): Update currentActivityStage to PAUSED, currentActivity=org.mozilla.geckoview.test.GeckoViewTestActivity@e1b4ba5
[task 2022-04-20T17:34:41.449Z] 17:34:41     INFO -  04-20 17:34:09.571 D/EGL_emulation( 3482): eglMakeCurrent: 0x7c638cb8a5a0: ver 3 0 (tinfo 0x7c638caf07c0)
[task 2022-04-20T17:34:41.449Z] 17:34:41     INFO -  04-20 17:34:09.572 D/LifecycleMonitor( 3482): Lifecycle status change: org.mozilla.geckoview.test.GeckoViewTestActivity@e1b4ba5 in: STOPPED
[task 2022-04-20T17:34:41.450Z] 17:34:41     INFO -  04-20 17:34:09.572 V/ActivityScenario( 3482): Update currentActivityStage to STOPPED, currentActivity=org.mozilla.geckoview.test.GeckoViewTestActivity@e1b4ba5
[task 2022-04-20T17:34:41.450Z] 17:34:41     INFO -  04-20 17:34:09.572 D/LifecycleMonitor( 3482): Lifecycle status change: org.mozilla.geckoview.test.GeckoViewTestActivity@e1b4ba5 in: DESTROYED
[task 2022-04-20T17:34:41.450Z] 17:34:41     INFO -  04-20 17:34:09.572 V/ActivityScenario( 3482): Update currentActivityStage to DESTROYED, currentActivity=null
[task 2022-04-20T17:34:41.450Z] 17:34:41     INFO -  04-20 17:34:09.579 I/WindowManager( 1652): Destroying surface Surface(name=SurfaceView - org.mozilla.geckoview.test/org.mozilla.geckoview.test.GeckoViewTestActivity) called by com.android.server.wm.WindowStateAnimator.destroyDeferredSurfaceLocked:910 com.android.server.wm.WindowManagerService.performDeferredDestroyWindow:3114 com.android.server.wm.Session.performDeferredDestroy:225 android.view.IWindowSession$Stub.onTransact:398 com.android.server.wm.Session.onTransact:136 android.os.Binder.execTransact:565 <bottom of call stack> <bottom of call stack>
[task 2022-04-20T17:34:41.451Z] 17:34:41     INFO -  04-20 17:34:09.585 I/WindowManager( 1652): Destroying surface Surface(name=org.mozilla.geckoview.test/org.mozilla.geckoview.test.GeckoViewTestActivity) 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-04-20T17:34:41.451Z] 17:34:41     INFO -  04-20 17:34:09.587 D/LifecycleMonitor( 3482): Lifecycle status change: org.mozilla.geckoview.test.GeckoViewTestActivity@4f42f59 in: PRE_ON_CREATE
[task 2022-04-20T17:34:41.451Z] 17:34:41     INFO -  04-20 17:34:09.587 V/ActivityScenario( 3482): Activity lifecycle changed event received but ignored because the reported transition was not ON_CREATE while the last known transition was DESTROYED
[task 2022-04-20T17:34:41.451Z] 17:34:41     INFO -  04-20 17:34:09.590 D/LifecycleMonitor( 3482): Lifecycle status change: org.mozilla.geckoview.test.GeckoViewTestActivity@4f42f59 in: CREATED
[task 2022-04-20T17:34:41.451Z] 17:34:41     INFO -  04-20 17:34:09.590 V/ActivityScenario( 3482): Update currentActivityStage to CREATED, currentActivity=org.mozilla.geckoview.test.GeckoViewTestActivity@4f42f59
[task 2022-04-20T17:34:41.452Z] 17:34:41     INFO -  04-20 17:34:09.590 D/LifecycleMonitor( 3482): Lifecycle status change: org.mozilla.geckoview.test.GeckoViewTestActivity@4f42f59 in: STARTED
[task 2022-04-20T17:34:41.452Z] 17:34:41     INFO -  04-20 17:34:09.590 V/ActivityScenario( 3482): Update currentActivityStage to STARTED, currentActivity=org.mozilla.geckoview.test.GeckoViewTestActivity@4f42f59
[task 2022-04-20T17:34:41.452Z] 17:34:41     INFO -  04-20 17:34:09.592 D/LifecycleMonitor( 3482): Lifecycle status change: org.mozilla.geckoview.test.GeckoViewTestActivity@4f42f59 in: RESUMED
[task 2022-04-20T17:34:41.452Z] 17:34:41     INFO -  04-20 17:34:09.592 V/ActivityScenario( 3482): Update currentActivityStage to RESUMED, currentActivity=org.mozilla.geckoview.test.GeckoViewTestActivity@4f42f59
[task 2022-04-20T17:34:41.452Z] 17:34:41     INFO -  04-20 17:34:09.608 W/ResourceType( 3482): Too many attribute references, stopped at: 0x01010099
[task 2022-04-20T17:34:41.452Z] 17:34:41     INFO -  04-20 17:34:09.608 D/EGL_emulation( 1748): eglMakeCurrent: 0x7c63ab7fc020: ver 3 0 (tinfo 0x7c63ae0aba60)
[task 2022-04-20T17:34:41.452Z] 17:34:41     INFO -  04-20 17:34:09.617 D/EGL_emulation( 3482): eglMakeCurrent: 0x7c638cb8a5a0: ver 3 0 (tinfo 0x7c638caf07c0)
[task 2022-04-20T17:34:41.453Z] 17:34:41     INFO -  04-20 17:34:09.618 D/EGL_emulation( 1748): eglMakeCurrent: 0x7c63ab7fc020: ver 3 0 (tinfo 0x7c63ae0aba60)
[task 2022-04-20T17:34:41.453Z] 17:34:41     INFO -  04-20 17:34:09.641 D/GeckoViewContent( 3482): handleEvent: MozDOMFullscreen:Exited
[task 2022-04-20T17:34:41.453Z] 17:34:41     INFO -  04-20 17:34:09.641 D/GeckoViewContent[C]( 7661): receiveMessage: GeckoView:DOMFullscreenExited
[task 2022-04-20T17:34:41.453Z] 17:34:41     INFO -  04-20 17:34:09.642 D/GeckoViewContentDelegateChild[C]( 7661): handleEvent: MozDOMFullscreen:Exited
[task 2022-04-20T17:34:41.453Z] 17:34:41     INFO -  04-20 17:34:09.642 D/GeckoViewMediaControlDelegateChild[C]( 7661): handleEvent: MozDOMFullscreen:Exited
[task 2022-04-20T17:34:41.453Z] 17:34:41     INFO -  04-20 17:34:09.643 D/GeckoViewMediaControlDelegateChild[C]( 7661): handleFullscreenChanged
[task 2022-04-20T17:34:41.453Z] 17:34:41     INFO -  04-20 17:34:09.643 D/GeckoViewContentDelegateParent( 3482): receiveMessage: GeckoView:DOMFullscreenExit {"data":{},"json":{},"name":"GeckoView:DOMFullscreenExit","sync":false,"target":{}}
[task 2022-04-20T17:34:41.453Z] 17:34:41     INFO -  04-20 17:34:09.645 I/WindowManager( 1652): Screen frozen for +116ms due to Window{c3be1ae u0 StatusBar}
[task 2022-04-20T17:34:41.454Z] 17:34:41     INFO -  04-20 17:34:09.677 E/eglCodecCommon( 6783): glUtilsParamSize: unknow param 0x00008caa
[task 2022-04-20T17:34:41.454Z] 17:34:41     INFO -  04-20 17:34:09.681 D/GeckoViewProgressDelegate[C]( 7661): handleEvent: MozAfterPaint
[task 2022-04-20T17:34:41.454Z] 17:34:41     INFO -  04-20 17:34:09.682 D/GeckoViewProgress( 3482): receiveMessage: MozAfterPaint
[task 2022-04-20T17:34:41.454Z] 17:34:41     INFO -  04-20 17:34:09.688 E/eglCodecCommon( 6783): glUtilsParamSize: unknow param 0x00008caa
[task 2022-04-20T17:34:41.454Z] 17:34:41     INFO -  04-20 17:34:09.744 E/eglCodecCommon( 6783): glUtilsParamSize: unknow param 0x00008caa
[task 2022-04-20T17:34:41.454Z] 17:34:41     INFO -  04-20 17:34:09.744 D/GeckoViewProgressDelegate[C]( 7661): handleEvent: MozAfterPaint
[task 2022-04-20T17:34:41.454Z] 17:34:41     INFO -  04-20 17:34:09.745 D/GeckoViewProgress( 3482): receiveMessage: MozAfterPaint
[task 2022-04-20T17:34:41.454Z] 17:34:41     INFO -  04-20 17:34:09.753 E/eglCodecCommon( 6783): glUtilsParamSize: unknow param 0x00008caa
[task 2022-04-20T17:34:41.454Z] 17:34:41     INFO -  04-20 17:34:09.761 D/GeckoViewProgressDelegate[C]( 7661): handleEvent: MozAfterPaint
[task 2022-04-20T17:34:41.454Z] 17:34:41     INFO -  04-20 17:34:09.762 E/eglCodecCommon( 6783): glUtilsParamSize: unknow param 0x00008caa
[task 2022-04-20T17:34:41.455Z] 17:34:41     INFO -  04-20 17:34:09.763 D/GeckoViewProgress( 3482): receiveMessage: MozAfterPaint
[task 2022-04-20T17:34:41.455Z] 17:34:41     INFO -  04-20 17:34:10.181 E/eglCodecCommon( 6783): glUtilsParamSize: unknow param 0x00008caa
[task 2022-04-20T17:34:41.455Z] 17:34:41     INFO -  04-20 17:34:10.188 D/GeckoViewProgressDelegate[C]( 7661): handleEvent: MozAfterPaint
[task 2022-04-20T17:34:41.455Z] 17:34:41     INFO -  04-20 17:34:10.188 D/GeckoViewProgress( 3482): receiveMessage: MozAfterPaint
[task 2022-04-20T17:34:41.455Z] 17:34:41     INFO -  04-20 17:34:10.489 W/GeckoSession( 3482): No history entries found.
[task 2022-04-20T17:34:41.455Z] 17:34:41     INFO -  04-20 17:34:10.490 D/GeckoSession( 3482): handleMessage GeckoView:StateUpdated uri=null
[task 2022-04-20T17:34:41.455Z] 17:34:41     INFO -  04-20 17:34:11.678 I/art     ( 1652): Starting a blocking GC Explicit
[task 2022-04-20T17:34:41.455Z] 17:34:41     INFO -  04-20 17:34:11.700 I/art     ( 1652): Explicit concurrent mark sweep GC freed 17030(1336KB) AllocSpace objects, 6(116KB) LOS objects, 29% free, 9MB/13MB, paused 261us total 21.892ms
[task 2022-04-20T17:34:41.455Z] 17:34:41     INFO -  04-20 17:34:40.491 E/GeckoSessionTestRule( 3482): Error
[task 2022-04-20T17:34:41.456Z] 17:34:41     INFO -  04-20 17:34:40.491 E/GeckoSessionTestRule( 3482): org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-04-20T17:34:41.456Z] 17:34:41     INFO -  04-20 17:34:40.491 E/GeckoSessionTestRule( 3482): 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-04-20T17:34:41.456Z] 17:34:41     INFO -  04-20 17:34:40.491 E/GeckoSessionTestRule( 3482): 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-04-20T17:34:41.456Z] 17:34:41     INFO -  04-20 17:34:40.491 E/GeckoSessionTestRule( 3482): 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-04-20T17:34:41.456Z] 17:34:41     INFO -  04-20 17:34:40.491 E/GeckoSessionTestRule( 3482): 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-04-20T17:34:41.456Z] 17:34:41     INFO -  04-20 17:34:40.491 E/GeckoSessionTestRule( 3482): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1741)
[task 2022-04-20T17:34:41.456Z] 17:34:41     INFO -  04-20 17:34:40.491 E/GeckoSessionTestRule( 3482): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1665)
[task 2022-04-20T17:34:41.457Z] 17:34:41     INFO -  04-20 17:34:40.491 E/GeckoSessionTestRule( 3482): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1617)
[task 2022-04-20T17:34:41.457Z] 17:34:41     INFO -  04-20 17:34:40.491 E/GeckoSessionTestRule( 3482): 	at org.mozilla.geckoview.test.OrientationDelegateTest.orientationUnlockByExitFullscreen(OrientationDelegateTest.kt:228)
[task 2022-04-20T17:34:41.457Z] 17:34:41     INFO -  04-20 17:34:40.491 E/GeckoSessionTestRule( 3482): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2022-04-20T17:34:41.457Z] 17:34:41     INFO -  04-20 17:34:40.491 E/GeckoSessionTestRule( 3482): 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2022-04-20T17:34:41.457Z] 17:34:41     INFO -  04-20 17:34:40.491 E/GeckoSessionTestRule( 3482): 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2022-04-20T17:34:41.457Z] 17:34:41     INFO -  04-20 17:34:40.491 E/GeckoSessionTestRule( 3482): 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2022-04-20T17:34:41.457Z] 17:34:41     INFO -  04-20 17:34:40.491 E/GeckoSessionTestRule( 3482): 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2022-04-20T17:34:41.458Z] 17:34:41     INFO -  04-20 17:34:40.491 E/GeckoSessionTestRule( 3482): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$org-mozilla-geckoview-test-rule-GeckoSessionTestRule$2(GeckoSessionTestRule.java:1451)
[task 2022-04-20T17:34:41.458Z] 17:34:41     INFO -  04-20 17:34:40.491 E/GeckoSessionTestRule( 3482): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2$$ExternalSyntheticLambda0.run(Unknown Source)
[task 2022-04-20T17:34:41.458Z] 17:34:41     INFO -  04-20 17:34:40.491 E/GeckoSessionTestRule( 3482): 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:428)
[task 2022-04-20T17:34:41.458Z] 17:34:41     INFO -  04-20 17:34:40.491 E/GeckoSessionTestRule( 3482): 	at java.util.concurrent.FutureTask.run(FutureTask.java:237)
[task 2022-04-20T17:34:41.458Z] 17:34:41     INFO -  04-20 17:34:40.491 E/GeckoSessionTestRule( 3482): 	at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2022-04-20T17:34:41.458Z] 17:34:41     INFO -  04-20 17:34:40.491 E/GeckoSessionTestRule( 3482): 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-04-20T17:34:41.458Z] 17:34:41     INFO -  04-20 17:34:40.491 E/GeckoSessionTestRule( 3482): 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-04-20T17:34:41.459Z] 17:34:41     INFO -  04-20 17:34:40.491 E/GeckoSessionTestRule( 3482): 	at android.os.Looper.loop(Looper.java:154)
[task 2022-04-20T17:34:41.459Z] 17:34:41     INFO -  04-20 17:34:40.491 E/GeckoSessionTestRule( 3482): 	at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2022-04-20T17:34:41.459Z] 17:34:41     INFO -  04-20 17:34:40.491 E/GeckoSessionTestRule( 3482): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2022-04-20T17:34:41.459Z] 17:34:41     INFO -  04-20 17:34:40.491 E/GeckoSessionTestRule( 3482): 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2022-04-20T17:34:41.459Z] 17:34:41     INFO -  04-20 17:34:40.491 E/GeckoSessionTestRule( 3482): 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2022-04-20T17:34:41.459Z] 17:34:41     INFO -  04-20 17:34:40.492 D/GeckoViewConsole( 3482): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"1d53c8ee-3eba-40d2-b335-b540d48e771d","args":{"oldPrefs":{"dom.screenorientation.allow-lock":false,"full-screen-api.allow-trusted-requests-only":true}},"type":"RestorePrefs"}}
[task 2022-04-20T17:34:41.459Z] 17:34:41     INFO -  04-20 17:34:40.497 D/EGL_emulation( 6783): eglMakeCurrent: 0x7c63b5e7bae0: ver 3 0 (tinfo 0x7c63ab7c3140)
[task 2022-04-20T17:34:41.459Z] 17:34:41     INFO -  04-20 17:34:40.499 D/GeckoViewContent( 3482): handleEvent: DOMWindowClose
[task 2022-04-20T17:34:41.460Z] 17:34:41     INFO -  04-20 17:34:40.500 D/GeckoViewConsole( 3482): onEvent GeckoView:WebExtension:List null
[task 2022-04-20T17:34:41.460Z] 17:34:41     INFO -  04-20 17:34:40.502 E/GeckoSessionTestRule( 3482): test_end c5ee677f-bc83-49bd-9e28-2d35f3d0f059 orientationUnlockByExitFullscreen(org.mozilla.geckoview.test.OrientationDelegateTest)
[task 2022-04-20T17:34:41.460Z] 17:34:41  WARNING -  TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.OrientationDelegateTest#orientationUnlockByExitFullscreen | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-04-20T17:34:41.460Z] 17:34:41     INFO -  TEST-INFO took 31437ms
[task 2022-04-20T17:34:41.539Z] 17:34:41     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=887
[task 2022-04-20T17:34:41.539Z] 17:34:41     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2022-04-20T17:34:41.539Z] 17:34:41     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2022-04-20T17:34:41.540Z] 17:34:41     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=orientationLockedExistingOrientation
[task 2022-04-20T17:34:41.540Z] 17:34:41     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.OrientationDelegateTest
[task 2022-04-20T17:34:41.540Z] 17:34:41     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=422
[task 2022-04-20T17:34:41.540Z] 17:34:41     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: 1
[task 2022-04-20T17:34:41.540Z] 17:34:41     INFO -  TEST-START | org.mozilla.geckoview.test.OrientationDelegateTest#orientationLockedExistingOrientation

Comment 1

[Intermittent Failures Robot]

1 failures in 3159 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• android-em-7-0-x86_64-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1765600&startday=2022-04-18&endday=2022-04-24&tree=all

Comment 2

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

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Comment 3

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=381805560&repo=autoland

Comment 4

[Intermittent Failures Robot]

1 failures in 3170 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• android-em-7-0-x86_64-lite-qr: 1
  • opt: 1

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

Comment 5

[Intermittent Failures Robot]

1 failures in 3072 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1765600&startday=2022-07-04&endday=2022-07-10&tree=all

Comment 6

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

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.