1762184 - Intermittent org.mozilla.geckoview.test.WebExtensionTest#loadWebExtensionPage | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms

Status: RESOLVED INCOMPLETE

(GeckoView :: Extensions, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2022-03-30T12:25:53.438Z] 12:25:53     INFO -  TEST-START | org.mozilla.geckoview.test.WebExtensionTest#loadWebExtensionPage
[task 2022-03-30T12:26:31.909Z] 12:26:31     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=884
[task 2022-03-30T12:26:31.909Z] 12:26:31     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2022-03-30T12:26:31.910Z] 12:26:31     INFO -  org.mozilla.geckoview.test | Error in loadWebExtensionPage(org.mozilla.geckoview.test.WebExtensionTest):
[task 2022-03-30T12:26:31.910Z] 12:26:31     INFO -  org.mozilla.geckoview.test | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-03-30T12:26:31.911Z] 12:26:31     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-03-30T12:26:31.911Z] 12:26:31     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-03-30T12:26:31.911Z] 12:26:31     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-03-30T12:26:31.912Z] 12:26:31     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-03-30T12:26:31.912Z] 12:26:31     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1741)
[task 2022-03-30T12:26:31.912Z] 12:26:31     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1665)
[task 2022-03-30T12:26:31.913Z] 12:26:31     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.BaseSessionTest.waitUntilCalled(BaseSessionTest.kt:190)
[task 2022-03-30T12:26:31.913Z] 12:26:31     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.WebExtensionTest.loadWebExtensionPage(WebExtensionTest.kt:1627)
[task 2022-03-30T12:26:31.913Z] 12:26:31     INFO -  org.mozilla.geckoview.test |
[task 2022-03-30T12:26:31.913Z] 12:26:31     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2022-03-30T12:26:31.914Z] 12:26:31     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=loadWebExtensionPage
[task 2022-03-30T12:26:31.914Z] 12:26:31     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.WebExtensionTest
[task 2022-03-30T12:26:31.914Z] 12:26:31     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-03-30T12:26:31.915Z] 12:26:31     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-03-30T12:26:31.915Z] 12:26:31     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-03-30T12:26:31.915Z] 12:26:31     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-03-30T12:26:31.915Z] 12:26:31     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-03-30T12:26:31.916Z] 12:26:31     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1741)
[task 2022-03-30T12:26:31.916Z] 12:26:31     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1665)
[task 2022-03-30T12:26:31.917Z] 12:26:31     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.BaseSessionTest.waitUntilCalled(BaseSessionTest.kt:190)
[task 2022-03-30T12:26:31.917Z] 12:26:31     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.WebExtensionTest.loadWebExtensionPage(WebExtensionTest.kt:1627)
[task 2022-03-30T12:26:31.917Z] 12:26:31     INFO -  org.mozilla.geckoview.test |
[task 2022-03-30T12:26:31.918Z] 12:26:31     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=842
[task 2022-03-30T12:26:31.918Z] 12:26:31     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: -2
[task 2022-03-30T12:26:31.918Z] 12:26:31     INFO -  Printing logcat for test:
[task 2022-03-30T12:26:32.002Z] 12:26:32     INFO -  03-30 12:25:53.402 E/GeckoSessionTestRule( 3467): test_start 1f0befec-3ff2-40ff-89cf-b127eb38b1ec loadWebExtensionPage(org.mozilla.geckoview.test.WebExtensionTest)
[task 2022-03-30T12:26:32.003Z] 12:26:32     INFO -  03-30 12:25:53.402 E/GeckoSessionTestRule( 3467): before prepareStatement loadWebExtensionPage(org.mozilla.geckoview.test.WebExtensionTest)
[task 2022-03-30T12:26:32.003Z] 12:26:32     INFO -  03-30 12:25:53.402 D/GeckoViewProgress( 3467): onDisable
[task 2022-03-30T12:26:32.004Z] 12:26:32     INFO -  03-30 12:25:53.402 E/Isolated Web Content( 1266): [JavaScript Error: "can't access property "sendRequestForResult", this.dispatcher is null" {file: "resource://gre/modules/GeckoViewWebExtension.jsm" line: 251}]
[task 2022-03-30T12:26:32.005Z] 12:26:32     INFO -  03-30 12:25:53.402 E/Isolated Web Content( 1266): _sendMessage@resource://gre/modules/GeckoViewWebExtension.jsm:251:5
[task 2022-03-30T12:26:32.005Z] 12:26:32     INFO -  03-30 12:25:53.402 E/Isolated Web Content( 1266): onConnect@resource://gre/modules/GeckoViewWebExtension.jsm:264:10
[task 2022-03-30T12:26:32.006Z] 12:26:32     INFO -  03-30 12:25:53.402 E/Isolated Web Content( 1266): recvPortConnect@resource://gre/modules/ExtensionParent.jsm:358:52
[task 2022-03-30T12:26:32.006Z] 12:26:32     INFO -  03-30 12:25:53.402 E/Isolated Web Content( 1266): _recv@resource://gre/modules/ConduitsChild.jsm:82:20
[task 2022-03-30T12:26:32.007Z] 12:26:32     INFO -  03-30 12:25:53.402 E/Isolated Web Content( 1266): receiveMessage@resource://gre/modules/ConduitsParent.jsm:450:20
[task 2022-03-30T12:26:32.007Z] 12:26:32     INFO -  03-30 12:25:53.402 D/GeckoViewModule( 3467): unregisterListener
[task 2022-03-30T12:26:32.007Z] 12:26:32     INFO -  03-30 12:25:53.402 D/GeckoViewModule( 3467): unregisterListener
[task 2022-03-30T12:26:32.007Z] 12:26:32     INFO -  03-30 12:25:53.402 D/GeckoViewMediaControl( 3467): onDisable
[task 2022-03-30T12:26:32.008Z] 12:26:32     INFO -  03-30 12:25:53.402 D/GeckoViewModule( 3467): unregisterListener
[task 2022-03-30T12:26:32.008Z] 12:26:32     INFO -  03-30 12:25:53.402 D/GeckoViewSelectionActionDelegate[C]( 1266): handleEvent: visibilitychange
[task 2022-03-30T12:26:32.009Z] 12:26:32     INFO -  03-30 12:25:53.402 E/Isolated Web Content( 1266): [JavaScript Error: "NS_ERROR_NOT_AVAILABLE" {file: "resource://gre/modules/ExtensionContent.jsm" line: 171}]
[task 2022-03-30T12:26:32.009Z] 12:26:32     INFO -  03-30 12:25:53.402 E/Isolated Web Content( 1266): [JavaScript Error: "NS_ERROR_NOT_AVAILABLE" {file: "resource://gre/modules/ExtensionContent.jsm" line: 171}]
[task 2022-03-30T12:26:32.010Z] 12:26:32     INFO -  03-30 12:25:53.403 E/Isolated Web Content( 1266): [JavaScript Error: "TypeError: context.contentWindow is null" {file: "resource://gre/modules/ExtensionContent.jsm" line: 619}]
[task 2022-03-30T12:26:32.010Z] 12:26:32     INFO -  03-30 12:25:53.406 E/eglCodecCommon(23414): glUtilsParamSize: unknow param 0x00008caa
[task 2022-03-30T12:26:32.010Z] 12:26:32     INFO -  03-30 12:25:53.406 D/GeckoViewSelectionActionDelegate[C]( 1266): handleEvent: visibilitychange
[task 2022-03-30T12:26:32.010Z] 12:26:32     INFO -  03-30 12:25:53.406 D/GeckoViewAutoFill[C]( 1266): handleEvent: pagehide
[task 2022-03-30T12:26:32.011Z] 12:26:32     INFO -  03-30 12:25:53.407 D/GeckoViewAutofill( 1266): Clearing auto-fill
[task 2022-03-30T12:26:32.011Z] 12:26:32     INFO -  03-30 12:25:53.411 D/GeckoViewStartup( 3467): onEvent GeckoView:StorageDelegate:Attached
[task 2022-03-30T12:26:32.011Z] 12:26:32     INFO -  03-30 12:25:53.419 I/ServiceChildProcess( 1266): Destroying GeckoServiceChildProcess
[task 2022-03-30T12:26:32.011Z] 12:26:32     INFO -  03-30 12:25:53.429 I/art     ( 1266): System.exit called, status: 0
[task 2022-03-30T12:26:32.011Z] 12:26:32     INFO -  03-30 12:25:53.429 I/AndroidRuntime( 1266): VM exiting with result code 0, cleanup skipped.
[task 2022-03-30T12:26:32.012Z] 12:26:32     INFO -  03-30 12:25:53.435 D/GeckoViewNavigation( 3467): sessionContextId=null
[task 2022-03-30T12:26:32.012Z] 12:26:32     INFO -  03-30 12:25:53.439 E/eglCodecCommon(23414): glUtilsParamSize: unknow param 0x00008caa
[task 2022-03-30T12:26:32.012Z] 12:26:32     INFO -  03-30 12:25:53.449 E/eglCodecCommon(23414): glUtilsParamSize: unknow param 0x00008741
[task 2022-03-30T12:26:32.013Z] 12:26:32     INFO -  03-30 12:25:53.454 D/GeckoViewModule( 3467): 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-03-30T12:26:32.013Z] 12:26:32     INFO -  03-30 12:25:53.455 D/GeckoViewSelectionActionDelegate[C](14786): handleEvent: visibilitychange
[task 2022-03-30T12:26:32.013Z] 12:26:32     INFO -  03-30 12:25:53.455 D/GeckoViewNavigation( 3467): onInit
[task 2022-03-30T12:26:32.014Z] 12:26:32     INFO -  03-30 12:25:53.455 D/GeckoViewModule( 3467): registerListener ["GeckoView:GoBack","GeckoView:GoForward","GeckoView:GotoHistoryIndex","GeckoView:LoadUri","GeckoView:Reload","GeckoView:Stop","GeckoView:PurgeHistory"]
[task 2022-03-30T12:26:32.014Z] 12:26:32     INFO -  03-30 12:25:53.456 D/GeckoViewNavigation( 3467): onEnable
[task 2022-03-30T12:26:32.014Z] 12:26:32     INFO -  03-30 12:25:53.456 D/GeckoViewProcessHangMonitor( 3467): onInit
[task 2022-03-30T12:26:32.014Z] 12:26:32     INFO -  03-30 12:25:53.456 D/GeckoViewProcessHangMonitor( 3467): onEnable
[task 2022-03-30T12:26:32.015Z] 12:26:32     INFO -  03-30 12:25:53.456 D/GeckoViewModule( 3467): registerListener ["GeckoView:HangReportStop","GeckoView:HangReportWait"]
[task 2022-03-30T12:26:32.015Z] 12:26:32     INFO -  03-30 12:25:53.456 D/GeckoViewProgress( 3467): onEnable
[task 2022-03-30T12:26:32.015Z] 12:26:32     INFO -  03-30 12:25:53.457 D/GeckoSession( 3467): handleMessage GeckoView:PageStart uri=about:blank
[task 2022-03-30T12:26:32.016Z] 12:26:32     INFO -  03-30 12:25:53.457 E/GeckoSessionTestRule( 3467): method: public abstract void org.mozilla.geckoview.GeckoSession$ProgressDelegate.onPageStart(org.mozilla.geckoview.GeckoSession,java.lang.String)
[task 2022-03-30T12:26:32.016Z] 12:26:32     INFO -  03-30 12:25:53.457 D/GeckoSession( 3467): handleMessage GeckoView:LocationChange uri=about:blank
[task 2022-03-30T12:26:32.016Z] 12:26:32     INFO -  03-30 12:25:53.457 E/GeckoSessionTestRule( 3467): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onLocationChange(org.mozilla.geckoview.GeckoSession,java.lang.String,java.util.List)
[task 2022-03-30T12:26:32.017Z] 12:26:32     INFO -  03-30 12:25:53.457 D/GeckoViewModule( 3467): registerListener GeckoView:FlushSessionState
[task 2022-03-30T12:26:32.017Z] 12:26:32     INFO -  03-30 12:25:53.457 E/GeckoSessionTestRule( 3467): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onLocationChange(org.mozilla.geckoview.GeckoSession,java.lang.String)
[task 2022-03-30T12:26:32.018Z] 12:26:32     INFO -  03-30 12:25:53.457 E/GeckoSessionTestRule( 3467): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onCanGoBack(org.mozilla.geckoview.GeckoSession,boolean)
[task 2022-03-30T12:26:32.018Z] 12:26:32     INFO -  03-30 12:25:53.457 E/GeckoSessionTestRule( 3467): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onCanGoForward(org.mozilla.geckoview.GeckoSession,boolean)
[task 2022-03-30T12:26:32.018Z] 12:26:32     INFO -  03-30 12:25:53.457 D/GeckoSession( 3467): handleMessage GeckoView:PageStop uri=null
[task 2022-03-30T12:26:32.019Z] 12:26:32     INFO -  03-30 12:25:53.457 E/GeckoSessionTestRule( 3467): method: public abstract void org.mozilla.geckoview.GeckoSession$ProgressDelegate.onPageStop(org.mozilla.geckoview.GeckoSession,boolean)
[task 2022-03-30T12:26:32.019Z] 12:26:32     INFO -  03-30 12:25:53.457 E/GeckoSessionTestRule( 3467): after prepareStatement
[task 2022-03-30T12:26:32.019Z] 12:26:32     INFO -  03-30 12:25:53.458 D/GeckoViewSettings( 3467): onInit
[task 2022-03-30T12:26:32.019Z] 12:26:32     INFO -  03-30 12:25:53.458 D/GeckoViewModule( 3467): registerListener ["GeckoView:GetUserAgent"]
[task 2022-03-30T12:26:32.020Z] 12:26:32     INFO -  03-30 12:25:53.458 D/GeckoViewSettings( 3467): 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-03-30T12:26:32.020Z] 12:26:32     INFO -  03-30 12:25:53.459 D/GeckoViewModule( 3467): registerListener ["GeckoView:WebExtension:SetTabActive"]
[task 2022-03-30T12:26:32.020Z] 12:26:32     INFO -  03-30 12:25:53.459 D/GeckoViewModule( 3467): registerListener ["ContentBlocking:RequestLog"]
[task 2022-03-30T12:26:32.020Z] 12:26:32     INFO -  03-30 12:25:53.459 D/GeckoViewSettings[C](14786): receiveMessage: SettingsUpdate
[task 2022-03-30T12:26:32.020Z] 12:26:32     INFO -  03-30 12:25:53.460 D/GeckoViewMediaControl( 3467): onEnable
[task 2022-03-30T12:26:32.021Z] 12:26:32     INFO -  03-30 12:25:53.460 D/GeckoViewModule( 3467): 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-03-30T12:26:32.021Z] 12:26:32     INFO -  03-30 12:25:53.462 I/GeckoSession( 3467): zerdatime 619764 - chrome startup finished
[task 2022-03-30T12:26:32.021Z] 12:26:32     INFO -  03-30 12:25:53.467 W/GeckoEditableChild(14786): No editable parent
[task 2022-03-30T12:26:32.021Z] 12:26:32     INFO -  03-30 12:25:53.468 D/GeckoViewConsole( 3467): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"f4e1dc3d-371d-4e56-86e7-637d5d712b96","args":{"oldPrefs":{},"newPrefs":{"extensions.isembedded":true}},"type":"SetPrefs"}}
[task 2022-03-30T12:26:32.021Z] 12:26:32     INFO -  03-30 12:25:53.468 I/ActivityManager( 1649): Process org.mozilla.geckoview.test:tab21 (pid 1266) has died
[task 2022-03-30T12:26:32.021Z] 12:26:32     INFO -  03-30 12:25:53.469 D/ActivityManager( 1649): cleanUpApplicationRecord -- 1266
[task 2022-03-30T12:26:32.022Z] 12:26:32     INFO -  03-30 12:25:53.469 D/GeckoViewXUL( 3467): receiveMessage GeckoView:ContentModuleLoaded {}
[task 2022-03-30T12:26:32.022Z] 12:26:32     INFO -  03-30 12:25:53.472 D/GeckoViewContent( 3467): observe: ipc:content-shutdown
[task 2022-03-30T12:26:32.022Z] 12:26:32     INFO -  03-30 12:25:53.475 I/Gecko   ( 3467): [Parent 3467, Unnamed thread 730fc7421ac0] WARNING: waitpid failed pid:1266 errno:10: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_posix.cc:221
[task 2022-03-30T12:26:32.022Z] 12:26:32     INFO -  03-30 12:25:53.477 D/GeckoViewModule( 3467): dispatch GeckoView:WebExtension:SetTabActive, data={"active":true}
[task 2022-03-30T12:26:32.022Z] 12:26:32     INFO -  03-30 12:25:53.477 D/GeckoViewTab( 3467): onEvent: event=GeckoView:WebExtension:SetTabActive, data={"active":true}
[task 2022-03-30T12:26:32.022Z] 12:26:32     INFO -  03-30 12:25:53.477 D/GeckoViewConsole( 3467): onEvent GeckoView:WebExtension:EnsureBuiltIn {"locationUri":"resource://android/assets/web_extensions/extension-page-update/","webExtensionId":"[e-mail]"}
[task 2022-03-30T12:26:32.023Z] 12:26:32     INFO -  03-30 12:25:53.478 D/GeckoViewConsole( 3467): Could not find extension with id=[e-mail]
[task 2022-03-30T12:26:32.023Z] 12:26:32     INFO -  03-30 12:25:53.530 I/WebExtension( 3467): releasePendingMessages: extension=[e-mail] nativeApp=browser session=org.mozilla.geckoview.GeckoSession@d5e2776
[task 2022-03-30T12:26:32.023Z] 12:26:32     INFO -  03-30 12:25:53.531 D/GeckoViewModule( 3467): dispatch GeckoView:LoadUri, data={"uri":"https://example.com","flags":0,"headerFilter":1}
[task 2022-03-30T12:26:32.023Z] 12:26:32     INFO -  03-30 12:25:53.531 D/GeckoViewNavigation( 3467): onEvent: event=GeckoView:LoadUri, data={"uri":"https://example.com","flags":0,"headerFilter":1}
[task 2022-03-30T12:26:32.023Z] 12:26:32     INFO -  03-30 12:25:53.535 D/GeckoViewProgress( 3467): ProgressTracker onStateChange: isTopLevel=true, flags=0xf0001, status=NS_OK
[task 2022-03-30T12:26:32.023Z] 12:26:32     INFO -  03-30 12:25:53.535 D/GeckoViewProgress( 3467): ProgressTracker onStateChange: uri=https://example.com/
[task 2022-03-30T12:26:32.024Z] 12:26:32     INFO -  03-30 12:25:53.535 D/GeckoViewProgress( 3467): ProgressTracker start https://example.com/
[task 2022-03-30T12:26:32.024Z] 12:26:32     INFO -  03-30 12:25:53.535 D/GeckoViewProgress( 3467): ProgressTracker updateProgress
[task 2022-03-30T12:26:32.024Z] 12:26:32     INFO -  03-30 12:25:53.535 D/GeckoViewProgress( 3467): ProgressTracker updateProgress data={"prev":0,"uri":"https://example.com/","locationChange":false,"pageStart":true,"pageStop":false,"firstPaint":false,"pageShow":false,"parsed":false} progress=15
[task 2022-03-30T12:26:32.024Z] 12:26:32     INFO -  03-30 12:25:53.535 D/GeckoViewProgress( 3467): StateTracker onStateChange: isTopLevel=true, flags=0xf0001, status=NS_OK loadType=1
[task 2022-03-30T12:26:32.024Z] 12:26:32     INFO -  03-30 12:25:53.536 D/GeckoSession( 3467): handleMessage GeckoView:ProgressChanged uri=null
[task 2022-03-30T12:26:32.025Z] 12:26:32     INFO -  03-30 12:25:53.536 D/GeckoSession( 3467): handleMessage GeckoView:PageStart uri=https://example.com/
[task 2022-03-30T12:26:32.025Z] 12:26:32     INFO -  03-30 12:25:53.569 I/art     ( 1371): Late-enabling -Xcheck:jni
[task 2022-03-30T12:26:32.025Z] 12:26:32     INFO -  03-30 12:25:53.570 W/art     ( 1371): Unexpected CPU variant for X86 using defaults: x86_64
[task 2022-03-30T12:26:32.025Z] 12:26:32     INFO -  03-30 12:25:53.573 I/ActivityManager( 1649): Start proc 1371:org.mozilla.geckoview.test:tab19/u0a62 for service org.mozilla.geckoview.test/org.mozilla.gecko.process.GeckoChildProcessServices$tab19
[task 2022-03-30T12:26:32.025Z] 12:26:32     INFO -  03-30 12:25:53.575 D/ServiceAllocator( 3467): org.mozilla.gecko.process.GeckoChildProcessServices$tab19 updateBindings: FOREGROUND priority, 0 importance, 3 successful binds, 0 failed binds, 0 successful unbinds
[task 2022-03-30T12:26:32.026Z] 12:26:32     INFO -  03-30 12:25:53.590 I/MultiDex( 1371): VM with version 2.1.0 has multidex support
[task 2022-03-30T12:26:32.026Z] 12:26:32     INFO -  03-30 12:25:53.590 I/MultiDex( 1371): Installing application
[task 2022-03-30T12:26:32.026Z] 12:26:32     INFO -  03-30 12:25:53.590 I/MultiDex( 1371): VM has multidex support, MultiDex support library is disabled.
[task 2022-03-30T12:26:32.026Z] 12:26:32     INFO -  03-30 12:25:53.592 I/ServiceChildProcess( 1371): onCreate
[task 2022-03-30T12:26:32.026Z] 12:26:32     INFO -  03-30 12:25:53.593 D/GeckoThread( 1371): State changed to LAUNCHED
[task 2022-03-30T12:26:32.026Z] 12:26:32     INFO -  03-30 12:25:53.593 I/GeckoThread( 1371): preparing to run Gecko
[task 2022-03-30T12:26:32.026Z] 12:26:32     INFO -  03-30 12:25:53.598 D/ServiceAllocator( 3467): org.mozilla.gecko.process.GeckoChildProcessServices$tab19 updateBindings: FOREGROUND priority, 2 importance, 0 successful binds, 0 failed binds, 0 successful unbinds
[task 2022-03-30T12:26:32.026Z] 12:26:32     INFO -  03-30 12:25:53.601 D/GeckoThread( 1371): env var: MOZ_CRASHREPORTER=1
[task 2022-03-30T12:26:32.027Z] 12:26:32     INFO -  03-30 12:25:53.601 D/GeckoThread( 1371): env var: MOZ_CRASHREPORTER_SHUTDOWN=1
[task 2022-03-30T12:26:32.027Z] 12:26:32     INFO -  03-30 12:25:53.601 D/GeckoThread( 1371): env var: XPCOM_DEBUG_BREAK=stack
[task 2022-03-30T12:26:32.027Z] 12:26:32     INFO -  03-30 12:25:53.601 D/GeckoThread( 1371): env var: MOZ_DISABLE_NONLOCAL_CONNECTIONS=1
[task 2022-03-30T12:26:32.027Z] 12:26:32     INFO -  03-30 12:25:53.601 D/GeckoThread( 1371): env var: MOZ_IN_AUTOMATION=1
[task 2022-03-30T12:26:32.027Z] 12:26:32     INFO -  03-30 12:25:53.601 D/GeckoThread( 1371): env var: R_LOG_VERBOSE=1
[task 2022-03-30T12:26:32.027Z] 12:26:32     INFO -  03-30 12:25:53.601 D/GeckoThread( 1371): env var: R_LOG_LEVEL=6
[task 2022-03-30T12:26:32.027Z] 12:26:32     INFO -  03-30 12:25:53.601 D/GeckoThread( 1371): env var: R_LOG_DESTINATION=stderr
[task 2022-03-30T12:26:32.027Z] 12:26:32     INFO -  03-30 12:25:53.601 D/GeckoThread( 1371): env var: MOZ_WEBRENDER=1
[task 2022-03-30T12:26:32.028Z] 12:26:32     INFO -  03-30 12:25:53.601 D/GeckoThread( 1371): env var: MOZ_FORCE_ENABLE_FISSION=1
[task 2022-03-30T12:26:32.028Z] 12:26:32     INFO -  03-30 12:25:53.601 D/GeckoViewXUL( 3467): WillChangeBrowserRemoteness
[task 2022-03-30T12:26:32.028Z] 12:26:32     INFO -  03-30 12:25:53.603 D/GeckoViewSelectionActionDelegate[C](14786): handleEvent: visibilitychange
[task 2022-03-30T12:26:32.028Z] 12:26:32     INFO -  03-30 12:25:53.603 D/GeckoViewAutoFill[C](14786): handleEvent: pagehide
[task 2022-03-30T12:26:32.028Z] 12:26:32     INFO -  03-30 12:25:53.603 D/GeckoViewAutofill(14786): Clearing auto-fill
[task 2022-03-30T12:26:32.028Z] 12:26:32     INFO -  03-30 12:25:53.604 D/GeckoThread( 1371): State changed to MOZGLUE_READY
[task 2022-03-30T12:26:32.028Z] 12:26:32     INFO -  03-30 12:25:53.606 I/GeckoLoader( 1371): Library base=/data/app/org.mozilla.geckoview.test-1/lib/x86_64
[task 2022-03-30T12:26:32.029Z] 12:26:32     INFO -  03-30 12:25:53.606 I/GeckoLoader( 1371): Library base=/data/app/org.mozilla.geckoview.test-1/lib/x86_64
[task 2022-03-30T12:26:32.029Z] 12:26:32     INFO -  03-30 12:25:53.606 E/GeckoLibLoad( 1371): Load sqlite start
[task 2022-03-30T12:26:32.029Z] 12:26:32     INFO -  03-30 12:25:53.606 D/GeckoViewXUL( 3467): DidChangeBrowserRemoteness
[task 2022-03-30T12:26:32.029Z] 12:26:32     INFO -  03-30 12:25:53.606 D/GeckoViewNavigation( 3467): sessionContextId=null
[task 2022-03-30T12:26:32.029Z] 12:26:32     INFO -  03-30 12:25:53.607 E/GeckoLibLoad( 1371): Load sqlite done
[task 2022-03-30T12:26:32.029Z] 12:26:32     INFO -  03-30 12:25:53.607 I/GeckoLoader( 1371): Library base=/data/app/org.mozilla.geckoview.test-1/lib/x86_64
[task 2022-03-30T12:26:32.029Z] 12:26:32     INFO -  03-30 12:25:53.607 E/GeckoLibLoad( 1371): Load nss start
[task 2022-03-30T12:26:32.029Z] 12:26:32     INFO -  03-30 12:25:53.607 E/GeckoLibLoad( 1371): Load nss done
[task 2022-03-30T12:26:32.030Z] 12:26:32     INFO -  03-30 12:25:53.607 I/GeckoLoader( 1371): Library base=/data/app/org.mozilla.geckoview.test-1/lib/x86_64
[task 2022-03-30T12:26:32.030Z] 12:26:32     INFO -  03-30 12:25:53.620 E/GeckoLibLoad( 1371): Loaded libs in 12.323445ms total, 10ms(20ms) user, 0ms(0ms) system, 0(0) faults
[task 2022-03-30T12:26:32.030Z] 12:26:32     INFO -  03-30 12:25:53.620 D/GeckoThread( 1371): State changed to LIBS_READY
[task 2022-03-30T12:26:32.030Z] 12:26:32     INFO -  03-30 12:25:53.620 W/GeckoThread( 1371): zerdatime 619922 - runGecko
[task 2022-03-30T12:26:32.030Z] 12:26:32     INFO -  03-30 12:25:53.674 D/GeckoThread( 1371): State changed to RUNNING
[task 2022-03-30T12:26:32.030Z] 12:26:32     INFO -  03-30 12:25:53.704 D/GeckoViewStartup( 1371): observe: content-process-ready-for-script
[task 2022-03-30T12:26:32.030Z] 12:26:32     INFO -  03-30 12:25:53.705 D/GeckoViewConsole( 1371): enabled = true
[task 2022-03-30T12:26:32.031Z] 12:26:32     INFO -  03-30 12:25:53.736 D/GeckoViewXUL( 3467): receiveMessage GeckoView:ContentModuleLoaded {}
[task 2022-03-30T12:26:32.031Z] 12:26:32     INFO -  03-30 12:25:53.752 D/GeckoViewSelectionActionDelegate[C]( 1371): handleEvent: visibilitychange
[task 2022-03-30T12:26:32.031Z] 12:26:32     INFO -  03-30 12:25:53.753 D/GeckoViewAutoFill[C]( 1371): handleEvent: pagehide
[task 2022-03-30T12:26:32.031Z] 12:26:32     INFO -  03-30 12:25:53.756 D/GeckoViewAutofill( 1371): Clearing auto-fill
[task 2022-03-30T12:26:32.031Z] 12:26:32     INFO -  03-30 12:25:53.762 D/GeckoViewNavigation( 3467): onLocationChange
[task 2022-03-30T12:26:32.031Z] 12:26:32     INFO -  03-30 12:25:53.763 D/GeckoSession( 3467): handleMessage GeckoView:LocationChange uri=https://example.com/
[task 2022-03-30T12:26:32.032Z] 12:26:32     INFO -  03-30 12:25:53.763 W/Isolated Web Content( 1371): [JavaScript Warning: "This page is in Quirks Mode. Page layout may be impacted. For Standards Mode use “<!DOCTYPE html>”." {file: "https://example.com/" line: 0}]
[task 2022-03-30T12:26:32.032Z] 12:26:32     INFO -  03-30 12:25:53.763 D/GeckoViewProgress( 3467): SecurityTracker onLocationChange: location=https://example.com/, flags=0
[task 2022-03-30T12:26:32.032Z] 12:26:32     INFO -  03-30 12:25:53.763 D/GeckoViewProgress( 3467): ProgressTracker onLocationChange: location=https://example.com/, flags=0
[task 2022-03-30T12:26:32.032Z] 12:26:32     INFO -  03-30 12:25:53.763 D/GeckoViewProgress( 3467): ProgressTracker changeLocation https://example.com/
[task 2022-03-30T12:26:32.032Z] 12:26:32     INFO -  03-30 12:25:53.763 D/GeckoViewProgress( 3467): onSecurityChange
[task 2022-03-30T12:26:32.032Z] 12:26:32     INFO -  03-30 12:25:53.764 D/GeckoSession( 3467): handleMessage GeckoView:SecurityChanged uri=null
[task 2022-03-30T12:26:32.032Z] 12:26:32     INFO -  03-30 12:25:53.812 D/GeckoViewContent( 3467): handleEvent: pagetitlechanged
[task 2022-03-30T12:26:32.033Z] 12:26:32     INFO -  03-30 12:25:53.814 D/GeckoViewContentDelegateChild[C]( 1371): handleEvent: DOMContentLoaded
[task 2022-03-30T12:26:32.033Z] 12:26:32     INFO -  03-30 12:25:53.817 D/GeckoViewProgressDelegate[C]( 1371): handleEvent: DOMContentLoaded
[task 2022-03-30T12:26:32.033Z] 12:26:32     INFO -  03-30 12:25:53.818 D/GeckoViewProgress( 3467): receiveMessage: DOMContentLoaded
[task 2022-03-30T12:26:32.033Z] 12:26:32     INFO -  03-30 12:25:53.818 D/GeckoViewProgress( 3467): ProgressTracker handleEvent: DOMContentLoaded
[task 2022-03-30T12:26:32.033Z] 12:26:32     INFO -  03-30 12:25:53.818 D/GeckoViewProgress( 3467): ProgressTracker updateProgress
[task 2022-03-30T12:26:32.034Z] 12:26:32     INFO -  03-30 12:25:53.818 D/GeckoViewProgress( 3467): ProgressTracker updateProgress data={"prev":15,"uri":"https://example.com/","locationChange":true,"pageStart":true,"pageStop":false,"firstPaint":false,"pageShow":false,"parsed":true} progress=55
[task 2022-03-30T12:26:32.034Z] 12:26:32     INFO -  03-30 12:25:53.819 D/GeckoSession( 3467): handleMessage GeckoView:ProgressChanged uri=null
[task 2022-03-30T12:26:32.034Z] 12:26:32     INFO -  03-30 12:26:23.820 E/GeckoSessionTestRule( 3467): Error
[task 2022-03-30T12:26:32.034Z] 12:26:32     INFO -  03-30 12:26:23.820 E/GeckoSessionTestRule( 3467): org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-03-30T12:26:32.034Z] 12:26:32     INFO -  03-30 12:26:23.820 E/GeckoSessionTestRule( 3467): 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-03-30T12:26:32.034Z] 12:26:32     INFO -  03-30 12:26:23.820 E/GeckoSessionTestRule( 3467): 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-03-30T12:26:32.034Z] 12:26:32     INFO -  03-30 12:26:23.820 E/GeckoSessionTestRule( 3467): 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-03-30T12:26:32.035Z] 12:26:32     INFO -  03-30 12:26:23.820 E/GeckoSessionTestRule( 3467): 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-03-30T12:26:32.035Z] 12:26:32     INFO -  03-30 12:26:23.820 E/GeckoSessionTestRule( 3467): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1741)
[task 2022-03-30T12:26:32.035Z] 12:26:32     INFO -  03-30 12:26:23.820 E/GeckoSessionTestRule( 3467): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1665)
[task 2022-03-30T12:26:32.035Z] 12:26:32     INFO -  03-30 12:26:23.820 E/GeckoSessionTestRule( 3467): 	at org.mozilla.geckoview.test.BaseSessionTest.waitUntilCalled(BaseSessionTest.kt:190)
[task 2022-03-30T12:26:32.035Z] 12:26:32     INFO -  03-30 12:26:23.820 E/GeckoSessionTestRule( 3467): 	at org.mozilla.geckoview.test.WebExtensionTest.loadWebExtensionPage(WebExtensionTest.kt:1627)
[task 2022-03-30T12:26:32.035Z] 12:26:32     INFO -  03-30 12:26:23.820 E/GeckoSessionTestRule( 3467): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2022-03-30T12:26:32.036Z] 12:26:32     INFO -  03-30 12:26:23.820 E/GeckoSessionTestRule( 3467): 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2022-03-30T12:26:32.036Z] 12:26:32     INFO -  03-30 12:26:23.820 E/GeckoSessionTestRule( 3467): 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2022-03-30T12:26:32.036Z] 12:26:32     INFO -  03-30 12:26:23.820 E/GeckoSessionTestRule( 3467): 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2022-03-30T12:26:32.036Z] 12:26:32     INFO -  03-30 12:26:23.820 E/GeckoSessionTestRule( 3467): 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2022-03-30T12:26:32.036Z] 12:26:32     INFO -  03-30 12:26:23.820 E/GeckoSessionTestRule( 3467): 	at androidx.test.internal.runner.junit4.statement.RunBefores.evaluate(RunBefores.java:80)
[task 2022-03-30T12:26:32.036Z] 12:26:32     INFO -  03-30 12:26:23.820 E/GeckoSessionTestRule( 3467): 	at org.junit.rules.Verifier$1.evaluate(Verifier.java:35)
[task 2022-03-30T12:26:32.037Z] 12:26:32     INFO -  03-30 12:26:23.820 E/GeckoSessionTestRule( 3467): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$org-mozilla-geckoview-test-rule-GeckoSessionTestRule$2(GeckoSessionTestRule.java:1451)
[task 2022-03-30T12:26:32.037Z] 12:26:32     INFO -  03-30 12:26:23.820 E/GeckoSessionTestRule( 3467): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2$$ExternalSyntheticLambda0.run(Unknown Source)
[task 2022-03-30T12:26:32.037Z] 12:26:32     INFO -  03-30 12:26:23.820 E/GeckoSessionTestRule( 3467): 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:428)
[task 2022-03-30T12:26:32.037Z] 12:26:32     INFO -  03-30 12:26:23.820 E/GeckoSessionTestRule( 3467): 	at java.util.concurrent.FutureTask.run(FutureTask.java:237)
[task 2022-03-30T12:26:32.037Z] 12:26:32     INFO -  03-30 12:26:23.820 E/GeckoSessionTestRule( 3467): 	at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2022-03-30T12:26:32.037Z] 12:26:32     INFO -  03-30 12:26:23.820 E/GeckoSessionTestRule( 3467): 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-03-30T12:26:32.038Z] 12:26:32     INFO -  03-30 12:26:23.820 E/GeckoSessionTestRule( 3467): 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-03-30T12:26:32.038Z] 12:26:32     INFO -  03-30 12:26:23.820 E/GeckoSessionTestRule( 3467): 	at android.os.Looper.loop(Looper.java:154)
[task 2022-03-30T12:26:32.038Z] 12:26:32     INFO -  03-30 12:26:23.820 E/GeckoSessionTestRule( 3467): 	at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2022-03-30T12:26:32.038Z] 12:26:32     INFO -  03-30 12:26:23.820 E/GeckoSessionTestRule( 3467): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2022-03-30T12:26:32.038Z] 12:26:32     INFO -  03-30 12:26:23.820 E/GeckoSessionTestRule( 3467): 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2022-03-30T12:26:32.038Z] 12:26:32     INFO -  03-30 12:26:23.820 E/GeckoSessionTestRule( 3467): 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2022-03-30T12:26:32.039Z] 12:26:32     INFO -  03-30 12:26:23.822 D/GeckoViewConsole( 3467): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"1bf04f4f-c648-4366-8a3c-26def7e428dd","args":{"oldPrefs":{"extensions.isembedded":null}},"type":"RestorePrefs"}}
[task 2022-03-30T12:26:32.039Z] 12:26:32     INFO -  03-30 12:26:23.831 D/GeckoViewContent( 3467): handleEvent: DOMWindowClose
[task 2022-03-30T12:26:32.039Z] 12:26:32     INFO -  03-30 12:26:23.833 D/GeckoViewConsole( 3467): onEvent GeckoView:WebExtension:List null
[task 2022-03-30T12:26:32.039Z] 12:26:32     INFO -  03-30 12:26:23.837 D/GeckoViewNavigation( 3467): onDisable
[task 2022-03-30T12:26:32.039Z] 12:26:32     INFO -  03-30 12:26:23.838 D/GeckoViewProcessHangMonitor( 3467): onDisable
[task 2022-03-30T12:26:32.039Z] 12:26:32     INFO -  03-30 12:26:23.838 D/GeckoViewModule( 3467): unregisterListener
[task 2022-03-30T12:26:32.039Z] 12:26:32     INFO -  03-30 12:26:23.838 D/GeckoViewProcessHangMonitor( 3467): onDestroy
[task 2022-03-30T12:26:32.039Z] 12:26:32     INFO -  03-30 12:26:23.841 D/GeckoViewProgress( 3467): onDisable
[task 2022-03-30T12:26:32.039Z] 12:26:32     INFO -  03-30 12:26:23.841 D/GeckoViewModule( 3467): unregisterListener
[task 2022-03-30T12:26:32.039Z] 12:26:32     INFO -  03-30 12:26:23.842 D/GeckoViewModule( 3467): unregisterListener
[task 2022-03-30T12:26:32.039Z] 12:26:32     INFO -  03-30 12:26:23.842 D/GeckoViewMediaControl( 3467): onDisable
[task 2022-03-30T12:26:32.040Z] 12:26:32     INFO -  03-30 12:26:23.843 D/GeckoViewModule( 3467): unregisterListener
[task 2022-03-30T12:26:32.040Z] 12:26:32     INFO -  03-30 12:26:23.847 E/eglCodecCommon(23414): glUtilsParamSize: unknow param 0x00008caa
[task 2022-03-30T12:26:32.040Z] 12:26:32     INFO -  03-30 12:26:23.850 D/GeckoViewConsole( 3467): onEvent GeckoView:WebExtension:Uninstall {"webExtensionId":"[e-mail]"}
[task 2022-03-30T12:26:32.040Z] 12:26:32     INFO -  03-30 12:26:26.847 D/ServiceAllocator( 3467): org.mozilla.gecko.process.GeckoChildProcessServices$tab19 updateBindings: IDLE priority, 6 importance, 0 successful binds, 0 failed binds, 2 successful unbinds
[task 2022-03-30T12:26:32.040Z] 12:26:32     INFO -  03-30 12:26:31.858 E/GeckoConsole( 3467): [JavaScript Error: "Timeout while waiting for extension child to shutdown: "Mozilla Android Components - Tabs Update Test" (ID: [e-mail], moz-extension://6245154f-1392-417d-bc1b-10f0e8d16c84/)" {file: "resource://gre/modules/Extension.jsm" line: 3110}]
[task 2022-03-30T12:26:32.041Z] 12:26:32     INFO -  03-30 12:26:31.858 E/GeckoConsole( 3467): shutdown@resource://gre/modules/Extension.jsm:3110:10
[task 2022-03-30T12:26:32.041Z] 12:26:32     INFO -  03-30 12:26:31.870 E/GeckoSessionTestRule( 3467): test_end c5ee677f-bc83-49bd-9e28-2d35f3d0f059 loadWebExtensionPage(org.mozilla.geckoview.test.WebExtensionTest)
[task 2022-03-30T12:26:32.041Z] 12:26:32  WARNING -  TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.WebExtensionTest#loadWebExtensionPage | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-03-30T12:26:32.041Z] 12:26:32     INFO -  TEST-INFO took 38566ms
[task 2022-03-30T12:26:32.041Z] 12:26:32     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=884
[task 2022-03-30T12:26:32.041Z] 12:26:32     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2022-03-30T12:26:32.041Z] 12:26:32     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2022-03-30T12:26:32.041Z] 12:26:32     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=backgroundPortDisconnectFromApp
[task 2022-03-30T12:26:32.042Z] 12:26:32     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.WebExtensionTest
[task 2022-03-30T12:26:32.042Z] 12:26:32     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=843
[task 2022-03-30T12:26:32.042Z] 12:26:32     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: 1
[task 2022-03-30T12:26:32.042Z] 12:26:32     INFO -  TEST-START | org.mozilla.geckoview.test.WebExtensionTest#backgroundPortDisconnectFromApp

Comment 1

[Intermittent Failures Robot]

1 failures in 3323 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=1762184&startday=2022-03-28&endday=2022-04-03&tree=all

Comment 2

[Natalia Csoregi [:nataliaCs]]

First occurrence here: https://treeherder.mozilla.org/jobs?repo=autoland&searchStr=Android%2C7.0%2Cx86-64%2CLite%2CWebRender%2Copt%2Ctest-android-em-7.0-x86_64-lite-qr%2Fopt-geckoview-junit-fis-e10s%2Cgv-junit-fis&tochange=f6ae1c1c198fbaed115f6c74c9e54b38ffb8350f&fromchange=ce95f2726565684f6ac8a491c3ddf6e182e28832&selectedTaskRun=UKp5PgFqQ4qQynfttJ7v7A.0

Comment 3

[Intermittent Failures Robot]

1 failures in 2922 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=1762184&startday=2022-04-04&endday=2022-04-10&tree=all

Comment 4

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

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

Comment 5

[Treeherder Bug Filer]

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

Comment 6

[Intermittent Failures Robot]

1 failures in 3075 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-qr: 1
  • debug-isolated-process: 1

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

Comment 7

[Chris Peterson [:cpeterson]]

Moving some WebExtension bugs to the GeckoView::Extensions component.

Comment 8

[Intermittent Failures Robot]

1 failures in 3245 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-qr: 1
  • debug-isolated-process: 1

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

Comment 9

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

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