Closed
Bug 1734060
Opened 4 years ago
Closed 3 years ago
Intermittent org.mozilla.geckoview.test.ExtensionActionTest#testClickWhenPopupIsNotDefined[#pageAction] | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
Categories
(GeckoView :: Extensions, defect, P5)
GeckoView
Extensions
Tracking
(Not tracked)
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=353649377&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/KHOfuGu4RxKkLyT0AHb4sw/runs/1/artifacts/public/logs/live_backing.log
[task 2021-10-04T23:54:27.510Z] 23:54:27 INFO - TEST-START | org.mozilla.geckoview.test.ExtensionActionTest#testClickWhenPopupIsNotDefined[#pageAction]
[task 2021-10-04T23:54:57.707Z] 23:54:57 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=802
[task 2021-10-04T23:54:57.707Z] 23:54:57 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2021-10-04T23:54:57.707Z] 23:54:57 INFO - org.mozilla.geckoview.test | Error in testClickWhenPopupIsNotDefined[#pageAction](org.mozilla.geckoview.test.ExtensionActionTest):
[task 2021-10-04T23:54:57.707Z] 23:54:57 INFO - org.mozilla.geckoview.test | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2021-10-04T23:54:57.707Z] 23:54:57 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:50)
[task 2021-10-04T23:54:57.707Z] 23:54:57 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2021-10-04T23:54:57.708Z] 23:54:57 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2021-10-04T23:54:57.708Z] 23:54:57 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:153)
[task 2021-10-04T23:54:57.708Z] 23:54:57 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils.waitForResult(UiThreadUtils.java:78)
[task 2021-10-04T23:54:57.708Z] 23:54:57 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForResult(GeckoSessionTestRule.java:2471)
[task 2021-10-04T23:54:57.708Z] 23:54:57 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.ExtensionActionTest.testActionApi(ExtensionActionTest.kt:194)
[task 2021-10-04T23:54:57.709Z] 23:54:57 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.ExtensionActionTest.setup(ExtensionActionTest.kt:106)
[task 2021-10-04T23:54:57.709Z] 23:54:57 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2021-10-04T23:54:57.709Z] 23:54:57 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2021-10-04T23:54:57.709Z] 23:54:57 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2021-10-04T23:54:57.709Z] 23:54:57 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2021-10-04T23:54:57.710Z] 23:54:57 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:24)
[task 2021-10-04T23:54:57.710Z] 23:54:57 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
[task 2021-10-04T23:54:57.710Z] 23:54:57 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1429)
[task 2021-10-04T23:54:57.710Z] 23:54:57 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$TNUWVrkQlCzXHIjIbcLih1Q8TRE.run(lambda)
[task 2021-10-04T23:54:57.710Z] 23:54:57 INFO - org.mozilla.geckoview.test | at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2021-10-04T23:54:57.710Z] 23:54:57 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2021-10-04T23:54:57.711Z] 23:54:57 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2021-10-04T23:54:57.711Z] 23:54:57 INFO - org.mozilla.geckoview.test | at android.os.Looper.loop(Looper.java:154)
[task 2021-10-04T23:54:57.711Z] 23:54:57 INFO - org.mozilla.geckoview.test | at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2021-10-04T23:54:57.711Z] 23:54:57 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2021-10-04T23:54:57.711Z] 23:54:57 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2021-10-04T23:54:57.712Z] 23:54:57 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2021-10-04T23:54:57.712Z] 23:54:57 INFO - org.mozilla.geckoview.test |
[task 2021-10-04T23:54:57.712Z] 23:54:57 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2021-10-04T23:54:57.712Z] 23:54:57 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=testClickWhenPopupIsNotDefined[#pageAction]
[task 2021-10-04T23:54:57.712Z] 23:54:57 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.ExtensionActionTest
[task 2021-10-04T23:54:57.712Z] 23:54:57 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2021-10-04T23:54:57.713Z] 23:54:57 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:50)
[task 2021-10-04T23:54:57.713Z] 23:54:57 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2021-10-04T23:54:57.713Z] 23:54:57 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2021-10-04T23:54:57.713Z] 23:54:57 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:153)
[task 2021-10-04T23:54:57.714Z] 23:54:57 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils.waitForResult(UiThreadUtils.java:78)
[task 2021-10-04T23:54:57.714Z] 23:54:57 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForResult(GeckoSessionTestRule.java:2471)
[task 2021-10-04T23:54:57.714Z] 23:54:57 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.ExtensionActionTest.testActionApi(ExtensionActionTest.kt:194)
[task 2021-10-04T23:54:57.714Z] 23:54:57 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.ExtensionActionTest.setup(ExtensionActionTest.kt:106)
[task 2021-10-04T23:54:57.715Z] 23:54:57 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2021-10-04T23:54:57.715Z] 23:54:57 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2021-10-04T23:54:57.715Z] 23:54:57 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2021-10-04T23:54:57.715Z] 23:54:57 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2021-10-04T23:54:57.716Z] 23:54:57 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:24)
[task 2021-10-04T23:54:57.716Z] 23:54:57 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
[task 2021-10-04T23:54:57.716Z] 23:54:57 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1429)
[task 2021-10-04T23:54:57.716Z] 23:54:57 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$TNUWVrkQlCzXHIjIbcLih1Q8TRE.run(lambda)
[task 2021-10-04T23:54:57.716Z] 23:54:57 INFO - org.mozilla.geckoview.test | at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2021-10-04T23:54:57.717Z] 23:54:57 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2021-10-04T23:54:57.717Z] 23:54:57 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2021-10-04T23:54:57.717Z] 23:54:57 INFO - org.mozilla.geckoview.test | at android.os.Looper.loop(Looper.java:154)
[task 2021-10-04T23:54:57.717Z] 23:54:57 INFO - org.mozilla.geckoview.test | at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2021-10-04T23:54:57.717Z] 23:54:57 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2021-10-04T23:54:57.718Z] 23:54:57 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2021-10-04T23:54:57.718Z] 23:54:57 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2021-10-04T23:54:57.718Z] 23:54:57 INFO - org.mozilla.geckoview.test |
[task 2021-10-04T23:54:57.718Z] 23:54:57 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=107
[task 2021-10-04T23:54:57.718Z] 23:54:57 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: -2
[task 2021-10-04T23:54:57.718Z] 23:54:57 INFO - Printing logcat for test:
[task 2021-10-04T23:54:57.772Z] 23:54:57 INFO - 10-04 23:54:27.098 E/GeckoSessionTestRule( 3462): test_start 1f0befec-3ff2-40ff-89cf-b127eb38b1ec testClickWhenPopupIsNotDefined[#pageAction](org.mozilla.geckoview.test.ExtensionActionTest)
[task 2021-10-04T23:54:57.772Z] 23:54:57 INFO - 10-04 23:54:27.098 E/GeckoSessionTestRule( 3462): before prepareStatement testClickWhenPopupIsNotDefined[#pageAction](org.mozilla.geckoview.test.ExtensionActionTest)
[task 2021-10-04T23:54:57.773Z] 23:54:57 INFO - 10-04 23:54:27.100 D/GeckoViewNavigation( 3462): onDisable
[task 2021-10-04T23:54:57.773Z] 23:54:57 INFO - 10-04 23:54:27.100 D/GeckoViewProcessHangMonitor( 3462): onDisable
[task 2021-10-04T23:54:57.773Z] 23:54:57 INFO - 10-04 23:54:27.100 D/GeckoViewSelectionActionDelegate[C]( 4663): handleEvent: visibilitychange
[task 2021-10-04T23:54:57.773Z] 23:54:57 INFO - 10-04 23:54:27.100 D/GeckoViewModule( 3462): unregisterListener
[task 2021-10-04T23:54:57.773Z] 23:54:57 INFO - 10-04 23:54:27.101 D/GeckoViewProcessHangMonitor( 3462): onDestroy
[task 2021-10-04T23:54:57.773Z] 23:54:57 INFO - 10-04 23:54:27.101 D/GeckoViewProgress( 3462): onDisable
[task 2021-10-04T23:54:57.773Z] 23:54:57 INFO - 10-04 23:54:27.101 D/GeckoViewModule( 3462): unregisterListener
[task 2021-10-04T23:54:57.774Z] 23:54:57 INFO - 10-04 23:54:27.101 D/GeckoViewModule( 3462): unregisterListener
[task 2021-10-04T23:54:57.774Z] 23:54:57 INFO - 10-04 23:54:27.101 D/GeckoViewMediaControl( 3462): onDisable
[task 2021-10-04T23:54:57.774Z] 23:54:57 INFO - 10-04 23:54:27.102 D/GeckoViewModule( 3462): unregisterListener
[task 2021-10-04T23:54:57.774Z] 23:54:57 INFO - 10-04 23:54:27.102 D/GeckoViewMediaControl[C]( 4663): onDisable
[task 2021-10-04T23:54:57.774Z] 23:54:57 INFO - 10-04 23:54:27.103 D/GeckoViewSelectionActionDelegate[C]( 4663): handleEvent: visibilitychange
[task 2021-10-04T23:54:57.775Z] 23:54:57 INFO - 10-04 23:54:27.103 D/GeckoViewAutoFill[C]( 4663): handleEvent: pagehide
[task 2021-10-04T23:54:57.775Z] 23:54:57 INFO - 10-04 23:54:27.103 D/GeckoViewAutofill( 4663): Clearing auto-fill
[task 2021-10-04T23:54:57.775Z] 23:54:57 INFO - 10-04 23:54:27.104 E/eglCodecCommon( 3462): glUtilsParamSize: unknow param 0x00008caa
[task 2021-10-04T23:54:57.775Z] 23:54:57 INFO - 10-04 23:54:27.108 E/eglCodecCommon( 3462): glUtilsParamSize: unknow param 0x00008caa
[task 2021-10-04T23:54:57.777Z] 23:54:57 INFO - 10-04 23:54:27.115 E/eglCodecCommon( 3462): glUtilsParamSize: unknow param 0x00008741
[task 2021-10-04T23:54:57.777Z] 23:54:57 INFO - 10-04 23:54:27.125 I/ActivityManager( 1658): Process org.mozilla.geckoview.test:tab25 (pid 4784) has died
[task 2021-10-04T23:54:57.777Z] 23:54:57 INFO - 10-04 23:54:27.125 D/ActivityManager( 1658): cleanUpApplicationRecord -- 4784
[task 2021-10-04T23:54:57.777Z] 23:54:57 INFO - 10-04 23:54:27.133 D/GeckoViewNavigation( 3462): sessionContextId=null
[task 2021-10-04T23:54:57.777Z] 23:54:57 INFO - 10-04 23:54:27.136 D/GeckoViewModule( 3462): registerListener ["GeckoViewContent:ExitFullScreen","GeckoView:ClearMatches","GeckoView:DisplayMatches","GeckoView:FindInPage","GeckoView:RestoreState","GeckoView:ScrollBy","GeckoView:ScrollTo","GeckoView:SetActive","GeckoView:SetFocused","GeckoView:UpdateInitData","GeckoView:ZoomToInput"]
[task 2021-10-04T23:54:57.777Z] 23:54:57 INFO - 10-04 23:54:27.136 D/GeckoViewNavigation( 3462): onInit
[task 2021-10-04T23:54:57.777Z] 23:54:57 INFO - 10-04 23:54:27.136 D/GeckoViewModule( 3462): registerListener ["GeckoView:GoBack","GeckoView:GoForward","GeckoView:GotoHistoryIndex","GeckoView:LoadUri","GeckoView:Reload","GeckoView:Stop","GeckoView:PurgeHistory"]
[task 2021-10-04T23:54:57.777Z] 23:54:57 INFO - 10-04 23:54:27.136 D/GeckoViewNavigation( 3462): onEnable
[task 2021-10-04T23:54:57.778Z] 23:54:57 INFO - 10-04 23:54:27.137 D/GeckoViewProcessHangMonitor( 3462): onInit
[task 2021-10-04T23:54:57.778Z] 23:54:57 INFO - 10-04 23:54:27.137 D/GeckoViewProcessHangMonitor( 3462): onEnable
[task 2021-10-04T23:54:57.778Z] 23:54:57 INFO - 10-04 23:54:27.137 D/GeckoViewModule( 3462): registerListener ["GeckoView:HangReportStop","GeckoView:HangReportWait"]
[task 2021-10-04T23:54:57.779Z] 23:54:57 INFO - 10-04 23:54:27.137 D/GeckoViewProgress( 3462): onEnable
[task 2021-10-04T23:54:57.779Z] 23:54:57 INFO - 10-04 23:54:27.137 D/GeckoSession( 3462): handleMessage GeckoView:PageStart uri=about:blank
[task 2021-10-04T23:54:57.779Z] 23:54:57 INFO - 10-04 23:54:27.137 E/GeckoSessionTestRule( 3462): method: public abstract void org.mozilla.geckoview.GeckoSession$ProgressDelegate.onPageStart(org.mozilla.geckoview.GeckoSession,java.lang.String)
[task 2021-10-04T23:54:57.779Z] 23:54:57 INFO - 10-04 23:54:27.137 D/GeckoSession( 3462): handleMessage GeckoView:LocationChange uri=about:blank
[task 2021-10-04T23:54:57.780Z] 23:54:57 INFO - 10-04 23:54:27.137 E/GeckoSessionTestRule( 3462): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onLocationChange(org.mozilla.geckoview.GeckoSession,java.lang.String,java.util.List)
[task 2021-10-04T23:54:57.780Z] 23:54:57 INFO - 10-04 23:54:27.137 E/GeckoSessionTestRule( 3462): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onLocationChange(org.mozilla.geckoview.GeckoSession,java.lang.String)
[task 2021-10-04T23:54:57.780Z] 23:54:57 INFO - 10-04 23:54:27.137 E/GeckoSessionTestRule( 3462): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onCanGoBack(org.mozilla.geckoview.GeckoSession,boolean)
[task 2021-10-04T23:54:57.780Z] 23:54:57 INFO - 10-04 23:54:27.137 D/GeckoViewModule( 3462): registerListener GeckoView:FlushSessionState
[task 2021-10-04T23:54:57.781Z] 23:54:57 INFO - 10-04 23:54:27.137 E/GeckoSessionTestRule( 3462): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onCanGoForward(org.mozilla.geckoview.GeckoSession,boolean)
[task 2021-10-04T23:54:57.781Z] 23:54:57 INFO - 10-04 23:54:27.137 D/GeckoSession( 3462): handleMessage GeckoView:PageStop uri=null
[task 2021-10-04T23:54:57.781Z] 23:54:57 INFO - 10-04 23:54:27.138 E/GeckoSessionTestRule( 3462): method: public abstract void org.mozilla.geckoview.GeckoSession$ProgressDelegate.onPageStop(org.mozilla.geckoview.GeckoSession,boolean)
[task 2021-10-04T23:54:57.781Z] 23:54:57 INFO - 10-04 23:54:27.138 E/GeckoSessionTestRule( 3462): after prepareStatement
[task 2021-10-04T23:54:57.781Z] 23:54:57 INFO - 10-04 23:54:27.138 D/GeckoViewSettings( 3462): onInit
[task 2021-10-04T23:54:57.782Z] 23:54:57 INFO - 10-04 23:54:27.138 D/GeckoViewModule( 3462): registerListener ["GeckoView:GetUserAgent"]
[task 2021-10-04T23:54:57.782Z] 23:54:57 INFO - 10-04 23:54:27.138 D/GeckoViewSettings( 3462): 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 2021-10-04T23:54:57.782Z] 23:54:57 INFO - 10-04 23:54:27.139 D/GeckoViewModule( 3462): registerListener ["GeckoView:WebExtension:SetTabActive"]
[task 2021-10-04T23:54:57.782Z] 23:54:57 INFO - 10-04 23:54:27.139 D/GeckoViewModule( 3462): registerListener ["ContentBlocking:RequestLog"]
[task 2021-10-04T23:54:57.783Z] 23:54:57 INFO - 10-04 23:54:27.139 D/GeckoViewModule( 3462): enableQueuing true
[task 2021-10-04T23:54:57.783Z] 23:54:57 INFO - 10-04 23:54:27.139 D/GeckoViewSelectionActionDelegate[C]( 4663): handleEvent: visibilitychange
[task 2021-10-04T23:54:57.783Z] 23:54:57 INFO - 10-04 23:54:27.139 D/GeckoViewMediaControl( 3462): onEnable
[task 2021-10-04T23:54:57.784Z] 23:54:57 INFO - 10-04 23:54:27.139 D/GeckoViewModule( 3462): 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 2021-10-04T23:54:57.784Z] 23:54:57 INFO - 10-04 23:54:27.140 D/GeckoViewSettings[C]( 4663): receiveMessage: SettingsUpdate
[task 2021-10-04T23:54:57.784Z] 23:54:57 INFO - 10-04 23:54:27.140 I/GeckoSession( 3462): zerdatime 114555 - chrome startup finished
[task 2021-10-04T23:54:57.784Z] 23:54:57 INFO - 10-04 23:54:27.141 D/GeckoViewMediaControl[C]( 4663): onEnable
[task 2021-10-04T23:54:57.784Z] 23:54:57 INFO - 10-04 23:54:27.141 D/GeckoViewMediaControl[C]( 4663): onEnable
[task 2021-10-04T23:54:57.784Z] 23:54:57 INFO - 10-04 23:54:27.142 W/GeckoEditableChild( 4663): No editable parent
[task 2021-10-04T23:54:57.785Z] 23:54:57 INFO - 10-04 23:54:27.142 D/GeckoViewConsole( 3462): onEvent GeckoView:WebExtension:InstallBuiltIn {"locationUri":"resource://android/assets/web_extensions/actions/"}
[task 2021-10-04T23:54:57.785Z] 23:54:57 INFO - 10-04 23:54:27.143 D/GeckoViewModule( 3462): dispatch GeckoView:WebExtension:SetTabActive, data={"active":true}
[task 2021-10-04T23:54:57.785Z] 23:54:57 INFO - 10-04 23:54:27.143 D/GeckoViewTab( 3462): onEvent: event=GeckoView:WebExtension:SetTabActive, data={"active":true}
[task 2021-10-04T23:54:57.785Z] 23:54:57 INFO - 10-04 23:54:27.145 D/GeckoViewXUL( 3462): receiveMessage GeckoView:ContentModuleLoaded {"module":"SessionStateAggregator"}
[task 2021-10-04T23:54:57.785Z] 23:54:57 INFO - 10-04 23:54:27.145 D/GeckoViewXUL( 3462): receiveMessage GeckoView:ContentModuleLoaded {"module":"GeckoViewMediaControl"}
[task 2021-10-04T23:54:57.785Z] 23:54:57 INFO - 10-04 23:54:27.145 D/GeckoViewModule( 3462): enableQueuing false
[task 2021-10-04T23:54:57.785Z] 23:54:57 INFO - 10-04 23:54:27.145 D/GeckoViewModule( 3462): dispatchQueued
[task 2021-10-04T23:54:57.786Z] 23:54:57 INFO - 10-04 23:54:27.169 D/WebExtension( 3462): handleMessage GeckoView:BrowserAction:Update
[task 2021-10-04T23:54:57.786Z] 23:54:57 INFO - 10-04 23:54:27.169 D/WebExtension( 3462): handleMessage GeckoView:PageAction:Update
[task 2021-10-04T23:54:57.786Z] 23:54:57 INFO - 10-04 23:54:27.171 D/GeckoViewConsole( 3462): onEvent GeckoView:WebExtension:Get {"extensionId":"actions@tests.mozilla.org"}
[task 2021-10-04T23:54:57.786Z] 23:54:57 INFO - 10-04 23:54:27.172 D/GeckoViewConsole( 3462): onEvent GeckoView:WebExtension:Get {"extensionId":"actions@tests.mozilla.org"}
[task 2021-10-04T23:54:57.787Z] 23:54:57 INFO - 10-04 23:54:27.174 D/GeckoViewSelectionActionDelegate[C]( 3462): handleEvent: visibilitychange
[task 2021-10-04T23:54:57.787Z] 23:54:57 INFO - 10-04 23:54:27.174 D/GeckoViewAutoFill[C]( 3462): handleEvent: pagehide
[task 2021-10-04T23:54:57.787Z] 23:54:57 INFO - 10-04 23:54:27.174 D/GeckoViewAutofill( 3462): Clearing auto-fill
[task 2021-10-04T23:54:57.787Z] 23:54:57 INFO - 10-04 23:54:27.174 E/GeckoConsole( 3462): [JavaScript Error: "TypeError: can't access property "sendRequest", this._eventDispatcher is null" {file: "resource://gre/modules/GeckoViewAutofill.jsm" line: 323}]
[task 2021-10-04T23:54:57.787Z] 23:54:57 INFO - 10-04 23:54:27.174 E/GeckoConsole( 3462): clearElements@resource://gre/modules/GeckoViewAutofill.jsm:323:5
[task 2021-10-04T23:54:57.787Z] 23:54:57 INFO - 10-04 23:54:27.174 E/GeckoConsole( 3462): handleEvent@resource:///actors/GeckoViewAutoFillChild.jsm:65:26
[task 2021-10-04T23:54:57.787Z] 23:54:57 INFO - 10-04 23:54:27.187 D/WebExtension( 3462): handleMessage GeckoView:WebExtension:Connect
[task 2021-10-04T23:54:57.788Z] 23:54:57 INFO - 10-04 23:54:27.189 D/GeckoViewContentDelegateChild[C]( 3462): handleEvent: DOMContentLoaded
[task 2021-10-04T23:54:57.788Z] 23:54:57 INFO - 10-04 23:54:27.189 D/GeckoViewActor[C]( 3462): webextension-view-type: background
[task 2021-10-04T23:54:57.788Z] 23:54:57 INFO - 10-04 23:54:27.190 D/GeckoViewProgressDelegate[C]( 3462): handleEvent: DOMContentLoaded
[task 2021-10-04T23:54:57.788Z] 23:54:57 INFO - 10-04 23:54:27.190 D/GeckoViewActor[C]( 3462): webextension-view-type: background
[task 2021-10-04T23:54:57.788Z] 23:54:57 INFO - 10-04 23:54:27.190 D/GeckoViewProgressDelegate[C]( 3462): handleEvent: pageshow
[task 2021-10-04T23:54:57.788Z] 23:54:57 INFO - 10-04 23:54:27.190 D/GeckoViewActor[C]( 3462): webextension-view-type: background
[task 2021-10-04T23:54:57.788Z] 23:54:57 INFO - 10-04 23:54:27.191 D/GeckoViewAutoFill[C]( 3462): handleEvent: pageshow
[task 2021-10-04T23:54:57.789Z] 23:54:57 INFO - 10-04 23:54:27.191 D/GeckoViewContent[C]( 3462): handleEvent: pageshow
[task 2021-10-04T23:54:57.789Z] 23:54:57 INFO - 10-04 23:54:27.191 D/GeckoViewActor[C]( 3462): webextension-view-type: background
[task 2021-10-04T23:54:57.789Z] 23:54:57 INFO - 10-04 23:54:27.193 I/WebExtension( 3462): releasePendingMessages: extension=actions@tests.mozilla.org nativeApp=browser session=org.mozilla.geckoview.GeckoSession@d193c18
[task 2021-10-04T23:54:57.789Z] 23:54:57 INFO - 10-04 23:54:27.193 I/WebExtension( 3462): releasePendingMessages: extension=actions@tests.mozilla.org nativeApp=browser session=null
[task 2021-10-04T23:54:57.789Z] 23:54:57 INFO - 10-04 23:54:27.193 D/WebExtension( 3462): handleMessage GeckoView:WebExtension:Connect
[task 2021-10-04T23:54:57.789Z] 23:54:57 INFO - 10-04 23:54:27.194 D/GeckoViewConsole( 3462): onEvent GeckoView:ActionDelegate:Attached {"extensionId":"actions@tests.mozilla.org"}
[task 2021-10-04T23:54:57.790Z] 23:54:57 INFO - 10-04 23:54:27.194 D/WebExtension( 3462): handleMessage GeckoView:BrowserAction:Update
[task 2021-10-04T23:54:57.790Z] 23:54:57 INFO - 10-04 23:54:27.195 D/WebExtension( 3462): handleMessage GeckoView:PageAction:Update
[task 2021-10-04T23:54:57.790Z] 23:54:57 INFO - 10-04 23:54:27.195 D/GeckoViewModule( 3462): dispatch GeckoView:LoadUri, data={"uri":"http://example.com","flags":0,"headerFilter":1}
[task 2021-10-04T23:54:57.790Z] 23:54:57 INFO - 10-04 23:54:27.195 D/GeckoViewNavigation( 3462): onEvent: event=GeckoView:LoadUri, data={"uri":"http://example.com","flags":0,"headerFilter":1}
[task 2021-10-04T23:54:57.790Z] 23:54:57 INFO - 10-04 23:54:27.198 D/GeckoViewProgress( 3462): ProgressTracker onStateChange: isTopLevel=true, flags=0xf0001, status=NS_OK
[task 2021-10-04T23:54:57.790Z] 23:54:57 INFO - 10-04 23:54:27.198 D/GeckoViewProgress( 3462): ProgressTracker onStateChange: uri=http://example.com/
[task 2021-10-04T23:54:57.790Z] 23:54:57 INFO - 10-04 23:54:27.198 D/GeckoViewProgress( 3462): ProgressTracker start http://example.com/
[task 2021-10-04T23:54:57.790Z] 23:54:57 INFO - 10-04 23:54:27.199 D/GeckoViewProgress( 3462): ProgressTracker updateProgress
[task 2021-10-04T23:54:57.791Z] 23:54:57 INFO - 10-04 23:54:27.199 D/GeckoViewProgress( 3462): ProgressTracker updateProgress data={"prev":0,"uri":"http://example.com/","locationChange":false,"pageStart":true,"pageStop":false,"firstPaint":false,"pageShow":false,"parsed":false} progress=15
[task 2021-10-04T23:54:57.791Z] 23:54:57 INFO - 10-04 23:54:27.199 D/GeckoSession( 3462): handleMessage GeckoView:ProgressChanged uri=null
[task 2021-10-04T23:54:57.791Z] 23:54:57 INFO - 10-04 23:54:27.199 D/GeckoViewProgress( 3462): StateTracker onStateChange: isTopLevel=true, flags=0xf0001, status=NS_OK loadType=1
[task 2021-10-04T23:54:57.791Z] 23:54:57 INFO - 10-04 23:54:27.199 D/GeckoSession( 3462): handleMessage GeckoView:PageStart uri=http://example.com/
[task 2021-10-04T23:54:57.791Z] 23:54:57 INFO - 10-04 23:54:27.207 D/GeckoViewSelectionActionDelegate[C]( 4663): handleEvent: visibilitychange
[task 2021-10-04T23:54:57.791Z] 23:54:57 INFO - 10-04 23:54:27.208 D/GeckoViewAutoFill[C]( 4663): handleEvent: pagehide
[task 2021-10-04T23:54:57.791Z] 23:54:57 INFO - 10-04 23:54:27.208 D/GeckoViewAutofill( 4663): Clearing auto-fill
[task 2021-10-04T23:54:57.791Z] 23:54:57 INFO - 10-04 23:54:27.214 D/GeckoViewNavigation( 3462): onLocationChange
[task 2021-10-04T23:54:57.791Z] 23:54:57 INFO - 10-04 23:54:27.214 D/GeckoSession( 3462): handleMessage GeckoView:LocationChange uri=http://example.com/
[task 2021-10-04T23:54:57.791Z] 23:54:57 INFO - 10-04 23:54:27.214 D/GeckoViewProgress( 3462): SecurityTracker onLocationChange: location=http://example.com/, flags=0
[task 2021-10-04T23:54:57.791Z] 23:54:57 INFO - 10-04 23:54:27.214 D/GeckoViewProgress( 3462): ProgressTracker onLocationChange: location=http://example.com/, flags=0
[task 2021-10-04T23:54:57.792Z] 23:54:57 INFO - 10-04 23:54:27.214 D/GeckoViewProgress( 3462): ProgressTracker changeLocation http://example.com/
[task 2021-10-04T23:54:57.792Z] 23:54:57 INFO - 10-04 23:54:27.215 W/GeckoEventDispatcher( 3462): No listener for GeckoView:BrowserAction:Update
[task 2021-10-04T23:54:57.792Z] 23:54:57 INFO - 10-04 23:54:27.215 W/GeckoEventDispatcher( 3462): No listener for GeckoView:PageAction:Update
[task 2021-10-04T23:54:57.792Z] 23:54:57 INFO - 10-04 23:54:27.215 D/GeckoViewProgress( 3462): onSecurityChange
[task 2021-10-04T23:54:57.792Z] 23:54:57 INFO - 10-04 23:54:27.215 D/GeckoSession( 3462): handleMessage GeckoView:SecurityChanged uri=null
[task 2021-10-04T23:54:57.792Z] 23:54:57 INFO - 10-04 23:54:27.236 D/GeckoViewContent( 3462): handleEvent: pagetitlechanged
[task 2021-10-04T23:54:57.792Z] 23:54:57 INFO - 10-04 23:54:27.237 D/GeckoViewContentDelegateChild[C]( 4663): handleEvent: DOMContentLoaded
[task 2021-10-04T23:54:57.792Z] 23:54:57 INFO - 10-04 23:54:27.237 D/GeckoViewProgressDelegate[C]( 4663): handleEvent: DOMContentLoaded
[task 2021-10-04T23:54:57.792Z] 23:54:57 INFO - 10-04 23:54:27.238 D/GeckoViewProgress( 3462): receiveMessage: DOMContentLoaded
[task 2021-10-04T23:54:57.792Z] 23:54:57 INFO - 10-04 23:54:27.238 D/GeckoViewProgress( 3462): ProgressTracker handleEvent: DOMContentLoaded
[task 2021-10-04T23:54:57.793Z] 23:54:57 INFO - 10-04 23:54:27.238 D/GeckoViewProgress( 3462): ProgressTracker updateProgress
[task 2021-10-04T23:54:57.793Z] 23:54:57 INFO - 10-04 23:54:27.238 D/GeckoViewProgress( 3462): ProgressTracker updateProgress data={"prev":15,"uri":"http://example.com/","locationChange":true,"pageStart":true,"pageStop":false,"firstPaint":false,"pageShow":false,"parsed":true} progress=55
[task 2021-10-04T23:54:57.793Z] 23:54:57 INFO - 10-04 23:54:27.238 D/GeckoSession( 3462): handleMessage GeckoView:ProgressChanged uri=null
[task 2021-10-04T23:54:57.793Z] 23:54:57 INFO - 10-04 23:54:27.250 D/WebExtension( 3462): handleMessage GeckoView:WebExtension:Connect
[task 2021-10-04T23:54:57.793Z] 23:54:57 INFO - 10-04 23:54:27.251 D/GeckoViewProgressDelegate[C]( 4663): handleEvent: pageshow
[task 2021-10-04T23:54:57.793Z] 23:54:57 INFO - 10-04 23:54:27.252 D/GeckoViewAutoFill[C]( 4663): handleEvent: pageshow
[task 2021-10-04T23:54:57.793Z] 23:54:57 INFO - 10-04 23:54:27.252 D/GeckoViewContent[C]( 4663): handleEvent: pageshow
[task 2021-10-04T23:54:57.793Z] 23:54:57 INFO - 10-04 23:54:27.252 D/WebExtension( 3462): handleMessage GeckoView:WebExtension:Connect
[task 2021-10-04T23:54:57.794Z] 23:54:57 INFO - 10-04 23:54:27.253 D/GeckoViewProgress( 3462): receiveMessage: pageshow
[task 2021-10-04T23:54:57.794Z] 23:54:57 INFO - 10-04 23:54:27.254 D/GeckoViewProgress( 3462): ProgressTracker handleEvent: pageshow
[task 2021-10-04T23:54:57.794Z] 23:54:57 INFO - 10-04 23:54:27.254 D/GeckoViewProgress( 3462): ProgressTracker updateProgress
[task 2021-10-04T23:54:57.794Z] 23:54:57 INFO - 10-04 23:54:27.254 D/GeckoViewProgress( 3462): ProgressTracker updateProgress data={"prev":55,"uri":"http://example.com/","locationChange":true,"pageStart":true,"pageStop":false,"firstPaint":false,"pageShow":true,"parsed":true} progress=100
[task 2021-10-04T23:54:57.794Z] 23:54:57 INFO - 10-04 23:54:27.255 D/GeckoSession( 3462): handleMessage GeckoView:ProgressChanged uri=null
[task 2021-10-04T23:54:57.794Z] 23:54:57 INFO - 10-04 23:54:27.260 D/GeckoViewProgress( 3462): ProgressTracker onStateChange: isTopLevel=true, flags=0xc0010, status=NS_OK
[task 2021-10-04T23:54:57.794Z] 23:54:57 INFO - 10-04 23:54:27.260 D/GeckoViewProgress( 3462): ProgressTracker onStateChange: uri=http://example.com/
[task 2021-10-04T23:54:57.795Z] 23:54:57 INFO - 10-04 23:54:27.260 D/GeckoViewProgress( 3462): ProgressTracker stop
[task 2021-10-04T23:54:57.795Z] 23:54:57 INFO - 10-04 23:54:27.260 D/GeckoViewProgress( 3462): ProgressTracker updateProgress
[task 2021-10-04T23:54:57.795Z] 23:54:57 INFO - 10-04 23:54:27.260 D/GeckoViewProgress( 3462): StateTracker onStateChange: isTopLevel=true, flags=0xc0010, status=NS_OK loadType=1
[task 2021-10-04T23:54:57.795Z] 23:54:57 INFO - 10-04 23:54:27.261 D/GeckoSession( 3462): handleMessage GeckoView:PageStop uri=null
[task 2021-10-04T23:54:57.795Z] 23:54:57 INFO - 10-04 23:54:27.261 D/GeckoViewConsole( 3462): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"action":"enable","type":"pageAction"}}
[task 2021-10-04T23:54:57.795Z] 23:54:57 INFO - 10-04 23:54:27.265 W/GeckoEventDispatcher( 3462): No listener for GeckoView:PageAction:Update
[task 2021-10-04T23:54:57.795Z] 23:54:57 INFO - 10-04 23:54:28.213 W/GeckoSession( 3462): No history entries found.
[task 2021-10-04T23:54:57.795Z] 23:54:57 INFO - 10-04 23:54:28.213 D/GeckoSession( 3462): handleMessage GeckoView:StateUpdated uri=null
[task 2021-10-04T23:54:57.796Z] 23:54:57 INFO - 10-04 23:54:29.287 D/ServiceAllocator( 3462): org.mozilla.gecko.process.GeckoChildProcessServices$tab10 updateBindings: IDLE priority, 6 importance, 0 successful binds, 0 failed binds, 2 successful unbinds
[task 2021-10-04T23:54:57.796Z] 23:54:57 INFO - 10-04 23:54:57.267 D/GeckoViewConsole( 3462): onEvent GeckoView:WebExtension:Uninstall {"webExtensionId":"actions@tests.mozilla.org"}
[task 2021-10-04T23:54:57.796Z] 23:54:57 INFO - 10-04 23:54:57.274 D/GeckoViewSelectionActionDelegate[C]( 3462): handleEvent: visibilitychange
[task 2021-10-04T23:54:57.796Z] 23:54:57 INFO - 10-04 23:54:57.275 D/GeckoViewAutoFill[C]( 3462): handleEvent: pagehide
[task 2021-10-04T23:54:57.796Z] 23:54:57 INFO - 10-04 23:54:57.275 D/GeckoViewAutofill( 3462): Clearing auto-fill
[task 2021-10-04T23:54:57.796Z] 23:54:57 INFO - 10-04 23:54:57.276 E/GeckoConsole( 3462): [JavaScript Error: "TypeError: can't access property "sendRequest", this._eventDispatcher is null" {file: "resource://gre/modules/GeckoViewAutofill.jsm" line: 323}]
[task 2021-10-04T23:54:57.796Z] 23:54:57 INFO - 10-04 23:54:57.276 E/GeckoConsole( 3462): clearElements@resource://gre/modules/GeckoViewAutofill.jsm:323:5
[task 2021-10-04T23:54:57.796Z] 23:54:57 INFO - 10-04 23:54:57.276 E/GeckoConsole( 3462): handleEvent@resource:///actors/GeckoViewAutoFillChild.jsm:65:26
[task 2021-10-04T23:54:57.797Z] 23:54:57 INFO - 10-04 23:54:57.276 E/GeckoConsole( 3462): _releaseBrowser@resource://gre/modules/ExtensionParent.jsm:1289:18
[task 2021-10-04T23:54:57.797Z] 23:54:57 INFO - 10-04 23:54:57.276 E/GeckoConsole( 3462): shutdown@resource://gre/modules/ExtensionParent.jsm:1284:12
[task 2021-10-04T23:54:57.797Z] 23:54:57 INFO - 10-04 23:54:57.276 E/GeckoConsole( 3462): shutdown@chrome://extensions/content/parent/ext-backgroundPage.js:107:11
[task 2021-10-04T23:54:57.797Z] 23:54:57 INFO - 10-04 23:54:57.276 E/GeckoConsole( 3462): onShutdown@chrome://extensions/content/parent/ext-backgroundPage.js:231:23
[task 2021-10-04T23:54:57.797Z] 23:54:57 INFO - 10-04 23:54:57.276 E/GeckoConsole( 3462): ExtensionAPI/<@resource://gre/modules/ExtensionCommon.jsm:358:14
[task 2021-10-04T23:54:57.797Z] 23:54:57 INFO - 10-04 23:54:57.276 E/GeckoConsole( 3462): wrapper@resource://gre/modules/ExtensionCommon.jsm:302:14
[task 2021-10-04T23:54:57.797Z] 23:54:57 INFO - 10-04 23:54:57.276 E/GeckoConsole( 3462): emit@resource://gre/modules/ExtensionCommon.jsm:329:32
[task 2021-10-04T23:54:57.797Z] 23:54:57 INFO - 10-04 23:54:57.276 E/GeckoConsole( 3462): emit@resource://gre/modules/Extension.jsm:2156:25
[task 2021-10-04T23:54:57.797Z] 23:54:57 INFO - 10-04 23:54:57.276 E/GeckoConsole( 3462): shutdown@resource://gre/modules/Extension.jsm:2872:10
[task 2021-10-04T23:54:57.797Z] 23:54:57 INFO - 10-04 23:54:57.286 E/GeckoSessionTestRule( 3462): Error
[task 2021-10-04T23:54:57.798Z] 23:54:57 INFO - 10-04 23:54:57.286 E/GeckoSessionTestRule( 3462): org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2021-10-04T23:54:57.798Z] 23:54:57 INFO - 10-04 23:54:57.286 E/GeckoSessionTestRule( 3462): at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:50)
[task 2021-10-04T23:54:57.798Z] 23:54:57 INFO - 10-04 23:54:57.286 E/GeckoSessionTestRule( 3462): at android.os.Handler.handleCallback(Handler.java:751)
[task 2021-10-04T23:54:57.798Z] 23:54:57 INFO - 10-04 23:54:57.286 E/GeckoSessionTestRule( 3462): at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2021-10-04T23:54:57.798Z] 23:54:57 INFO - 10-04 23:54:57.286 E/GeckoSessionTestRule( 3462): at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:153)
[task 2021-10-04T23:54:57.798Z] 23:54:57 INFO - 10-04 23:54:57.286 E/GeckoSessionTestRule( 3462): at org.mozilla.geckoview.test.util.UiThreadUtils.waitForResult(UiThreadUtils.java:78)
[task 2021-10-04T23:54:57.798Z] 23:54:57 INFO - 10-04 23:54:57.286 E/GeckoSessionTestRule( 3462): at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForResult(GeckoSessionTestRule.java:2471)
[task 2021-10-04T23:54:57.798Z] 23:54:57 INFO - 10-04 23:54:57.286 E/GeckoSessionTestRule( 3462): at org.mozilla.geckoview.test.ExtensionActionTest.testActionApi(ExtensionActionTest.kt:194)
[task 2021-10-04T23:54:57.799Z] 23:54:57 INFO - 10-04 23:54:57.286 E/GeckoSessionTestRule( 3462): at org.mozilla.geckoview.test.ExtensionActionTest.setup(ExtensionActionTest.kt:106)
[task 2021-10-04T23:54:57.799Z] 23:54:57 INFO - 10-04 23:54:57.286 E/GeckoSessionTestRule( 3462): at java.lang.reflect.Method.invoke(Native Method)
[task 2021-10-04T23:54:57.799Z] 23:54:57 INFO - 10-04 23:54:57.286 E/GeckoSessionTestRule( 3462): at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2021-10-04T23:54:57.799Z] 23:54:57 INFO - 10-04 23:54:57.286 E/GeckoSessionTestRule( 3462): at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2021-10-04T23:54:57.799Z] 23:54:57 INFO - 10-04 23:54:57.286 E/GeckoSessionTestRule( 3462): at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2021-10-04T23:54:57.799Z] 23:54:57 INFO - 10-04 23:54:57.286 E/GeckoSessionTestRule( 3462): at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:24)
[task 2021-10-04T23:54:57.799Z] 23:54:57 INFO - 10-04 23:54:57.286 E/GeckoSessionTestRule( 3462): at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
[task 2021-10-04T23:54:57.799Z] 23:54:57 INFO - 10-04 23:54:57.286 E/GeckoSessionTestRule( 3462): at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1429)
[task 2021-10-04T23:54:57.800Z] 23:54:57 INFO - 10-04 23:54:57.286 E/GeckoSessionTestRule( 3462): at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$TNUWVrkQlCzXHIjIbcLih1Q8TRE.run(lambda)
[task 2021-10-04T23:54:57.800Z] 23:54:57 INFO - 10-04 23:54:57.286 E/GeckoSessionTestRule( 3462): at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2021-10-04T23:54:57.800Z] 23:54:57 INFO - 10-04 23:54:57.286 E/GeckoSessionTestRule( 3462): at android.os.Handler.handleCallback(Handler.java:751)
[task 2021-10-04T23:54:57.800Z] 23:54:57 INFO - 10-04 23:54:57.286 E/GeckoSessionTestRule( 3462): at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2021-10-04T23:54:57.800Z] 23:54:57 INFO - 10-04 23:54:57.286 E/GeckoSessionTestRule( 3462): at android.os.Looper.loop(Looper.java:154)
[task 2021-10-04T23:54:57.800Z] 23:54:57 INFO - 10-04 23:54:57.286 E/GeckoSessionTestRule( 3462): at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2021-10-04T23:54:57.800Z] 23:54:57 INFO - 10-04 23:54:57.286 E/GeckoSessionTestRule( 3462): at java.lang.reflect.Method.invoke(Native Method)
[task 2021-10-04T23:54:57.800Z] 23:54:57 INFO - 10-04 23:54:57.286 E/GeckoSessionTestRule( 3462): at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2021-10-04T23:54:57.800Z] 23:54:57 INFO - 10-04 23:54:57.286 E/GeckoSessionTestRule( 3462): at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2021-10-04T23:54:57.800Z] 23:54:57 INFO - 10-04 23:54:57.288 D/GeckoViewContent( 3462): handleEvent: DOMWindowClose
[task 2021-10-04T23:54:57.800Z] 23:54:57 INFO - 10-04 23:54:57.289 D/GeckoViewConsole( 3462): onEvent GeckoView:WebExtension:List null
[task 2021-10-04T23:54:57.801Z] 23:54:57 INFO - 10-04 23:54:57.291 E/GeckoSessionTestRule( 3462): test_end c5ee677f-bc83-49bd-9e28-2d35f3d0f059 testClickWhenPopupIsNotDefined[#pageAction](org.mozilla.geckoview.test.ExtensionActionTest)
[task 2021-10-04T23:54:57.801Z] 23:54:57 WARNING - TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.ExtensionActionTest#testClickWhenPopupIsNotDefined[#pageAction] | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2021-10-04T23:54:57.801Z] 23:54:57 INFO - TEST-INFO took 30262ms
[task 2021-10-04T23:54:57.801Z] 23:54:57 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=802
[task 2021-10-04T23:54:57.801Z] 23:54:57 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2021-10-04T23:54:57.801Z] 23:54:57 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2021-10-04T23:54:57.801Z] 23:54:57 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=setBadgeText[#pageAction]
[task 2021-10-04T23:54:57.801Z] 23:54:57 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.ExtensionActionTest
[task 2021-10-04T23:54:57.801Z] 23:54:57 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=108
[task 2021-10-04T23:54:57.802Z] 23:54:57 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: 1
[task 2021-10-04T23:54:57.802Z] 23:54:57 INFO - TEST-START | org.mozilla.geckoview.test.ExtensionActionTest#setBadgeText[#pageAction]
|
||
Updated•4 years ago
|
Component: Task Configuration → General
Product: Firefox Build System → GeckoView
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•4 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
|
Reporter | |
Comment 3•4 years ago
|
||
| treeherder | ||
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=366667166&repo=autoland
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Comment 5•4 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: REOPENED → RESOLVED
Closed: 4 years ago → 4 years ago
Resolution: --- → INCOMPLETE
|
|
Reporter | |
Comment 6•4 years ago
|
||
| treeherder | ||
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=373484884&repo=autoland
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Comment 8•3 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: REOPENED → RESOLVED
Closed: 4 years ago → 3 years ago
Resolution: --- → INCOMPLETE
|
|
Reporter | |
Comment 9•3 years ago
|
||
| treeherder | ||
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=381476677&repo=autoland
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 11•3 years ago
|
||
Moving some extension bugs to the GeckoView::Extensions component.
Component: General → Extensions
|
|
||
Comment 12•3 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: REOPENED → RESOLVED
Closed: 3 years ago → 3 years ago
Resolution: --- → INCOMPLETE
|
|
Reporter | |
Comment 13•3 years ago
|
||
| treeherder | ||
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=405842570&repo=autoland
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Comment 15•3 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: REOPENED → RESOLVED
Closed: 3 years ago → 3 years ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•