Open Bug 1866457 Opened 2 years ago Updated 8 days ago

Intermittent org.mozilla.geckoview.test.ExtensionActionTest#testPopupMessaging[#pageAction] | single tracking bug

Categories

(GeckoView :: General, defect, P5)

All
Android
defect

Tracking

(Not tracked)

REOPENED

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, intermittent-testcase)

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


[task 2023-11-24T09:54:34.294Z] 09:54:34     INFO -  TEST-START | org.mozilla.geckoview.test.ExtensionActionTest#testPopupMessaging[#pageAction]
[task 2023-11-24T09:55:05.170Z] 09:55:05     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=1092
[task 2023-11-24T09:55:05.171Z] 09:55:05     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2023-11-24T09:55:05.171Z] 09:55:05     INFO -  org.mozilla.geckoview.test | Error in testPopupMessaging[#pageAction](org.mozilla.geckoview.test.ExtensionActionTest):
[task 2023-11-24T09:55:05.171Z] 09:55:05     INFO -  org.mozilla.geckoview.test | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2023-11-24T09:55:05.171Z] 09:55:05     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2023-11-24T09:55:05.171Z] 09:55:05     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2023-11-24T09:55:05.171Z] 09:55:05     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2023-11-24T09:55:05.171Z] 09:55:05     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2023-11-24T09:55:05.171Z] 09:55:05     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForResult(UiThreadUtils.java:79)
[task 2023-11-24T09:55:05.171Z] 09:55:05     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForResult(GeckoSessionTestRule.java:2957)
[task 2023-11-24T09:55:05.171Z] 09:55:05     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForResult(GeckoSessionTestRule.java:2942)
[task 2023-11-24T09:55:05.171Z] 09:55:05     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.ExtensionActionTest.testActionApi(ExtensionActionTest.kt:261)
[task 2023-11-24T09:55:05.172Z] 09:55:05     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.ExtensionActionTest.setup(ExtensionActionTest.kt:120)
[task 2023-11-24T09:55:05.172Z] 09:55:05     INFO -  org.mozilla.geckoview.test |
[task 2023-11-24T09:55:05.172Z] 09:55:05     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2023-11-24T09:55:05.172Z] 09:55:05     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=testPopupMessaging[#pageAction]
[task 2023-11-24T09:55:05.172Z] 09:55:05     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.ExtensionActionTest
[task 2023-11-24T09:55:05.172Z] 09:55:05     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2023-11-24T09:55:05.172Z] 09:55:05     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2023-11-24T09:55:05.172Z] 09:55:05     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2023-11-24T09:55:05.172Z] 09:55:05     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2023-11-24T09:55:05.172Z] 09:55:05     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2023-11-24T09:55:05.172Z] 09:55:05     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForResult(UiThreadUtils.java:79)
[task 2023-11-24T09:55:05.172Z] 09:55:05     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForResult(GeckoSessionTestRule.java:2957)
[task 2023-11-24T09:55:05.172Z] 09:55:05     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForResult(GeckoSessionTestRule.java:2942)
[task 2023-11-24T09:55:05.173Z] 09:55:05     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.ExtensionActionTest.testActionApi(ExtensionActionTest.kt:261)
[task 2023-11-24T09:55:05.173Z] 09:55:05     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.ExtensionActionTest.setup(ExtensionActionTest.kt:120)
[task 2023-11-24T09:55:05.173Z] 09:55:05     INFO -  org.mozilla.geckoview.test |
[task 2023-11-24T09:55:05.173Z] 09:55:05     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=175
[task 2023-11-24T09:55:05.173Z] 09:55:05     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: -2
[task 2023-11-24T09:55:05.173Z] 09:55:05     INFO -  Printing logcat for test:
[task 2023-11-24T09:55:05.683Z] 09:55:05     INFO -  11-24 09:54:33.447 E/GeckoSessionTestRule( 3473): test_start 1f0befec-3ff2-40ff-89cf-b127eb38b1ec testPopupMessaging[#pageAction](org.mozilla.geckoview.test.ExtensionActionTest)
[task 2023-11-24T09:55:05.683Z] 09:55:05     INFO -  11-24 09:54:33.447 E/GeckoSessionTestRule( 3473): before prepareStatement testPopupMessaging[#pageAction](org.mozilla.geckoview.test.ExtensionActionTest)
[task 2023-11-24T09:55:05.683Z] 09:55:05     INFO -  11-24 09:54:33.450 D/GeckoViewSelectionActionDelegate[C]( 4852): handleEvent: visibilitychange
[task 2023-11-24T09:55:05.683Z] 09:55:05     INFO -  11-24 09:54:33.451 D/GeckoViewClipboardPermissionChild[C]( 4852): handleEvent: deactivate
[task 2023-11-24T09:55:05.683Z] 09:55:05     INFO -  11-24 09:54:33.454 E/eglCodecCommon( 3544): glUtilsParamSize: unknow param 0x00008caa
[task 2023-11-24T09:55:05.684Z] 09:55:05     INFO -  11-24 09:54:33.460 D/GeckoViewSelectionActionDelegate[C]( 4852): handleEvent: visibilitychange
[task 2023-11-24T09:55:05.684Z] 09:55:05     INFO -  11-24 09:54:33.460 D/GeckoViewClipboardPermissionChild[C]( 4852): handleEvent: pagehide
[task 2023-11-24T09:55:05.684Z] 09:55:05     INFO -  11-24 09:54:33.461 D/GeckoViewAutoFill[C]( 4852): handleEvent: pagehide
[task 2023-11-24T09:55:05.684Z] 09:55:05     INFO -  11-24 09:54:33.466 I/Gecko   ( 4852): [Child 4852, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:61
[task 2023-11-24T09:55:05.684Z] 09:55:05     INFO -  11-24 09:54:33.466 I/Gecko   ( 4852): [Child 4852, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:61
[task 2023-11-24T09:55:05.684Z] 09:55:05     INFO -  11-24 09:54:33.470 I/Gecko   ( 3544): [GPU 3544, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:371
[task 2023-11-24T09:55:05.684Z] 09:55:05     INFO -  11-24 09:54:33.474 D/GeckoViewStartup( 3473): onEvent GeckoView:StorageDelegate:Attached
[task 2023-11-24T09:55:05.684Z] 09:55:05     INFO -  11-24 09:54:33.474 I/Gecko   ( 3473): nsWindow[0x77b372172200]::Create 0x0 [0 0 1 1]
[task 2023-11-24T09:55:05.684Z] 09:55:05     INFO -  11-24 09:54:33.485 D/GeckoViewClipboardPermissionParent( 3473): receiveMessage: DispatcherMessage
[task 2023-11-24T09:55:05.684Z] 09:55:05     INFO -  11-24 09:54:33.485 D/GeckoViewActorParent( 3473): receiveMessage window destroyed DispatcherMessage GeckoView:DismissClipboardPermissionRequest
[task 2023-11-24T09:55:05.685Z] 09:55:05     INFO -  11-24 09:54:33.489 D/GeckoViewClipboardPermissionParent( 3473): receiveMessage: DispatcherMessage
[task 2023-11-24T09:55:05.685Z] 09:55:05     INFO -  11-24 09:54:33.489 D/GeckoViewActorParent( 3473): receiveMessage window destroyed DispatcherMessage GeckoView:DismissClipboardPermissionRequest
[task 2023-11-24T09:55:05.685Z] 09:55:05     INFO -  11-24 09:54:33.490 D/GeckoViewAutoFill( 3473): receiveMessage Clear
[task 2023-11-24T09:55:05.685Z] 09:55:05     INFO -  11-24 09:54:33.521 D/GeckoViewNavigation( 3473): sessionContextId=null
[task 2023-11-24T09:55:05.685Z] 09:55:05     INFO -  11-24 09:54:33.530 E/eglCodecCommon( 3544): glUtilsParamSize: unknow param 0x00008caa
[task 2023-11-24T09:55:05.685Z] 09:55:05     INFO -  11-24 09:54:33.594 W/webrender::device::gl( 3544): Missing optimized shader source for gpu_cache_update
[task 2023-11-24T09:55:05.685Z] 09:55:05     INFO -  11-24 09:54:33.602 E/eglCodecCommon( 3544): glUtilsParamSize: unknow param 0x00008741
[task 2023-11-24T09:55:05.685Z] 09:55:05     INFO -  11-24 09:54:33.614 D/GeckoViewModule( 3473): registerListener ["GeckoViewContent:ExitFullScreen","GeckoView:ClearMatches","GeckoView:DisplayMatches","GeckoView:FindInPage","GeckoView:HasCookieBannerRuleForBrowsingContextTree","GeckoView:RestoreState","GeckoView:ContainsFormData","GeckoView:RequestCreateAnalysis","GeckoView:RequestAnalysisCreationStatus","GeckoView:PollForAnalysisCompleted","GeckoView:SendClickAttributionEvent","GeckoView:SendImpressionAttributionEvent","GeckoView:RequestAnalysis","GeckoView:RequestRecommendations","GeckoView:ScrollBy","GeckoView:ScrollTo","GeckoView:SetActive","GeckoView:SetFocused","GeckoView:SetPriorityHint","GeckoView:UpdateInitData","GeckoView:ZoomToInput","GeckoView:IsPdfJs"]
[task 2023-11-24T09:55:05.686Z] 09:55:05     INFO -  11-24 09:54:33.615 D/GeckoViewNavigation( 3473): onInit
[task 2023-11-24T09:55:05.686Z] 09:55:05     INFO -  11-24 09:54:33.616 D/GeckoViewModule( 3473): registerListener ["GeckoView:GoBack","GeckoView:GoForward","GeckoView:GotoHistoryIndex","GeckoView:LoadUri","GeckoView:Reload","GeckoView:Stop","GeckoView:PurgeHistory","GeckoView:DotPrintFinish"]
[task 2023-11-24T09:55:05.686Z] 09:55:05     INFO -  11-24 09:54:33.616 D/GeckoViewNavigation( 3473): onEnable
[task 2023-11-24T09:55:05.686Z] 09:55:05     INFO -  11-24 09:54:33.616 D/GeckoViewProcessHangMonitor( 3473): onInit
[task 2023-11-24T09:55:05.686Z] 09:55:05     INFO -  11-24 09:54:33.616 D/GeckoViewProcessHangMonitor( 3473): onEnable
[task 2023-11-24T09:55:05.686Z] 09:55:05     INFO -  11-24 09:54:33.617 D/GeckoViewModule( 3473): registerListener ["GeckoView:HangReportStop","GeckoView:HangReportWait"]
[task 2023-11-24T09:55:05.686Z] 09:55:05     INFO -  11-24 09:54:33.617 D/GeckoViewProgress( 3473): onEnable
[task 2023-11-24T09:55:05.686Z] 09:55:05     INFO -  11-24 09:54:33.618 D/GeckoViewModule( 3473): registerListener GeckoView:FlushSessionState
[task 2023-11-24T09:55:05.686Z] 09:55:05     INFO -  11-24 09:54:33.618 D/GeckoSession( 3473): handleMessage GeckoView:PageStart uri=about:blank
[task 2023-11-24T09:55:05.686Z] 09:55:05     INFO -  11-24 09:54:33.618 E/GeckoSessionTestRule( 3473): method: public abstract void org.mozilla.geckoview.GeckoSession$ProgressDelegate.onPageStart(org.mozilla.geckoview.GeckoSession,java.lang.String)
[task 2023-11-24T09:55:05.686Z] 09:55:05     INFO -  11-24 09:54:33.618 D/GeckoSession( 3473): handleMessage GeckoView:LocationChange uri=about:blank
[task 2023-11-24T09:55:05.687Z] 09:55:05     INFO -  11-24 09:54:33.618 E/GeckoSessionTestRule( 3473): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onLocationChange(org.mozilla.geckoview.GeckoSession,java.lang.String,java.util.List)
[task 2023-11-24T09:55:05.687Z] 09:55:05     INFO -  11-24 09:54:33.618 E/GeckoSessionTestRule( 3473): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onCanGoBack(org.mozilla.geckoview.GeckoSession,boolean)
[task 2023-11-24T09:55:05.687Z] 09:55:05     INFO -  11-24 09:54:33.619 E/GeckoSessionTestRule( 3473): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onCanGoForward(org.mozilla.geckoview.GeckoSession,boolean)
[task 2023-11-24T09:55:05.687Z] 09:55:05     INFO -  11-24 09:54:33.619 D/GeckoViewSelectionActionDelegate[C]( 4852): handleEvent: visibilitychange
[task 2023-11-24T09:55:05.687Z] 09:55:05     INFO -  11-24 09:54:33.619 D/GeckoSession( 3473): handleMessage GeckoView:PageStop uri=null
[task 2023-11-24T09:55:05.687Z] 09:55:05     INFO -  11-24 09:54:33.619 E/GeckoSessionTestRule( 3473): method: public abstract void org.mozilla.geckoview.GeckoSession$ProgressDelegate.onPageStop(org.mozilla.geckoview.GeckoSession,boolean)
[task 2023-11-24T09:55:05.687Z] 09:55:05     INFO -  11-24 09:54:33.619 E/GeckoSessionTestRule( 3473): after prepareStatement
[task 2023-11-24T09:55:05.687Z] 09:55:05     INFO -  11-24 09:54:33.619 D/GeckoViewSelectionAction( 3473): onEnable
[task 2023-11-24T09:55:05.687Z] 09:55:05     INFO -  11-24 09:54:33.619 D/GeckoViewModule( 3473): registerListener ["GeckoView:ExecuteSelectionAction"]
[task 2023-11-24T09:55:05.687Z] 09:55:05     INFO -  11-24 09:54:33.619 D/GeckoViewClipboardPermissionChild[C]( 4852): handleEvent: deactivate
[task 2023-11-24T09:55:05.687Z] 09:55:05     INFO -  11-24 09:54:33.620 D/GeckoViewSettings( 3473): onInit
[task 2023-11-24T09:55:05.687Z] 09:55:05     INFO -  11-24 09:54:33.620 D/GeckoViewModule( 3473): registerListener ["GeckoView:GetUserAgent"]
[task 2023-11-24T09:55:05.688Z] 09:55:05     INFO -  11-24 09:54:33.620 D/GeckoViewSettings( 3473): 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 2023-11-24T09:55:05.688Z] 09:55:05     INFO -  11-24 09:54:33.621 D/GeckoViewModule( 3473): registerListener ["GeckoView:WebExtension:SetTabActive"]
[task 2023-11-24T09:55:05.688Z] 09:55:05     INFO -  11-24 09:54:33.621 D/GeckoViewModule( 3473): registerListener ["ContentBlocking:RequestLog"]
[task 2023-11-24T09:55:05.688Z] 09:55:05     INFO -  11-24 09:54:33.622 D/GeckoViewMediaControl( 3473): onInitBrowser
[task 2023-11-24T09:55:05.688Z] 09:55:05     INFO -  11-24 09:54:33.622 D/GeckoViewMediaControl( 3473): onEnable
[task 2023-11-24T09:55:05.688Z] 09:55:05     INFO -  11-24 09:54:33.622 D/GeckoViewModule( 3473): 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 2023-11-24T09:55:05.688Z] 09:55:05     INFO -  11-24 09:54:33.623 D/GeckoViewTranslations( 3473): onInit
[task 2023-11-24T09:55:05.688Z] 09:55:05     INFO -  11-24 09:54:33.623 D/GeckoViewModule( 3473): registerListener ["GeckoView:Translations:Translate","GeckoView:Translations:RestorePage","GeckoView:Translations:GetNeverTranslateSite","GeckoView:Translations:SetNeverTranslateSite"]
[task 2023-11-24T09:55:05.688Z] 09:55:05     INFO -  11-24 09:54:33.623 D/GeckoViewTranslations( 3473): onEnable
[task 2023-11-24T09:55:05.689Z] 09:55:05     INFO -  11-24 09:54:33.624 D/GeckoViewSettings[C]( 4852): receiveMessage: SettingsUpdate
[task 2023-11-24T09:55:05.689Z] 09:55:05     INFO -  11-24 09:54:33.627 I/GeckoSession( 3473): zerdatime 215331 - chrome startup finished
[task 2023-11-24T09:55:05.689Z] 09:55:05     INFO -  11-24 09:54:33.628 I/Gecko   ( 3473): nsWindow[0x77b372172200]::Show 1
[task 2023-11-24T09:55:05.689Z] 09:55:05     INFO -  11-24 09:54:33.638 W/GeckoEditableChild( 4852): No editable parent
[task 2023-11-24T09:55:05.689Z] 09:55:05     INFO -  11-24 09:54:33.644 D/GeckoViewConsole( 3473): onEvent GeckoView:WebExtension:InstallBuiltIn {"locationUri":"resource://android/assets/web_extensions/actions/"}
[task 2023-11-24T09:55:05.689Z] 09:55:05     INFO -  11-24 09:54:33.646 D/GeckoViewClipboardPermissionParent( 3473): receiveMessage: DispatcherMessage
[task 2023-11-24T09:55:05.689Z] 09:55:05     INFO -  11-24 09:54:33.647 D/GeckoSession( 3473): handleMessage: GeckoView:DismissClipboardPermissionRequest
[task 2023-11-24T09:55:05.689Z] 09:55:05     INFO -  11-24 09:54:33.649 D/GeckoViewXUL( 3473): onEvent GeckoView:UpdateModuleState {"enabled":true,"module":"GeckoViewExperiment"}
[task 2023-11-24T09:55:05.689Z] 09:55:05     INFO -  11-24 09:54:33.650 D/GeckoViewModule( 3473): dispatch GeckoView:WebExtension:SetTabActive, data={"active":true}
[task 2023-11-24T09:55:05.689Z] 09:55:05     INFO -  11-24 09:54:33.651 D/GeckoViewTab( 3473): onEvent: event=GeckoView:WebExtension:SetTabActive, data={"active":true}
[task 2023-11-24T09:55:05.689Z] 09:55:05     INFO -  11-24 09:54:33.652 D/GeckoViewXUL( 3473): receiveMessage GeckoView:ContentModuleLoaded {"module":"SessionStateAggregator"}
[task 2023-11-24T09:55:05.689Z] 09:55:05     INFO -  11-24 09:54:33.672 D/GeckoViewConsole( 3473): onInstalling actions@tests.mozilla.org
[task 2023-11-24T09:55:05.690Z] 09:55:05     INFO -  11-24 09:54:33.676 W/GeckoEventDispatcher( 3473): No listener for GeckoView:WebExtension:OnInstalling
[task 2023-11-24T09:55:05.690Z] 09:55:05     INFO -  11-24 09:54:33.707 D/WebExtension( 3473): handleMessage GeckoView:BrowserAction:Update
[task 2023-11-24T09:55:05.690Z] 09:55:05     INFO -  11-24 09:54:33.707 D/WebExtension( 3473): handleMessage GeckoView:PageAction:Update
[task 2023-11-24T09:55:05.690Z] 09:55:05     INFO -  11-24 09:54:33.717 D/GeckoViewConsole( 3473): onEvent GeckoView:WebExtension:Get {"extensionId":"actions@tests.mozilla.org"}
[task 2023-11-24T09:55:05.690Z] 09:55:05     INFO -  11-24 09:54:33.721 D/GeckoViewConsole( 3473): onEvent GeckoView:WebExtension:Get {"extensionId":"actions@tests.mozilla.org"}
[task 2023-11-24T09:55:05.690Z] 09:55:05     INFO -  11-24 09:54:33.836 D/GeckoViewClipboardPermissionChild[C]( 3473): handleEvent: pagehide
[task 2023-11-24T09:55:05.690Z] 09:55:05     INFO -  11-24 09:54:33.841 I/Gecko   ( 3473): [Parent 3473, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1225
[task 2023-11-24T09:55:05.690Z] 09:55:05     INFO -  11-24 09:54:33.843 D/GeckoViewClipboardPermissionParent( 3473): receiveMessage: DispatcherMessage
[task 2023-11-24T09:55:05.691Z] 09:55:05     INFO -  11-24 09:54:33.843 E/GeckoConsole( 3473): [JavaScript Error: "TypeError: can't access property "eventDispatcher", this.window.moduleManager is undefined" {file: "resource://gre/modules/GeckoViewActorParent.sys.mjs" line: 28}]
[task 2023-11-24T09:55:05.691Z] 09:55:05     INFO -  11-24 09:54:33.843 E/GeckoConsole( 3473): get eventDispatcher@resource://gre/modules/GeckoViewActorParent.sys.mjs:28:5
[task 2023-11-24T09:55:05.691Z] 09:55:05     INFO -  11-24 09:54:33.843 E/GeckoConsole( 3473): receiveMessage@resource://gre/modules/GeckoViewActorParent.sys.mjs:41:9
[task 2023-11-24T09:55:05.691Z] 09:55:05     INFO -  11-24 09:54:33.843 E/GeckoConsole( 3473): receiveMessage@resource:///actors/GeckoViewClipboardPermissionParent.sys.mjs:37:22
[task 2023-11-24T09:55:05.691Z] 09:55:05     INFO -  11-24 09:54:33.876 D/WebExtension( 3473): handleMessage GeckoView:WebExtension:Connect
[task 2023-11-24T09:55:05.691Z] 09:55:05     INFO -  11-24 09:54:33.889 D/GeckoViewConsole( 3473): onExtensionReady actions@tests.mozilla.org
[task 2023-11-24T09:55:05.691Z] 09:55:05     INFO -  11-24 09:54:33.890 D/GeckoViewConsole( 3473): onInstalled actions@tests.mozilla.org
[task 2023-11-24T09:55:05.691Z] 09:55:05     INFO -  11-24 09:54:33.895 W/GeckoEventDispatcher( 3473): No listener for GeckoView:WebExtension:OnInstalled
[task 2023-11-24T09:55:05.692Z] 09:55:05     INFO -  11-24 09:54:33.896 W/GeckoEventDispatcher( 3473): No listener for GeckoView:WebExtension:OnReady
[task 2023-11-24T09:55:05.692Z] 09:55:05     INFO -  11-24 09:54:33.898 D/GeckoViewConsole( 3473): onEvent GeckoView:WebExtension:InstallBuiltIn {"locationUri":"resource://android/assets/web_extensions/dummy/"}
[task 2023-11-24T09:55:05.692Z] 09:55:05     INFO -  11-24 09:54:33.914 D/GeckoViewConsole( 3473): onInstalling dummy@tests.mozilla.org
[task 2023-11-24T09:55:05.692Z] 09:55:05     INFO -  11-24 09:54:33.917 W/GeckoEventDispatcher( 3473): No listener for GeckoView:WebExtension:OnInstalling
[task 2023-11-24T09:55:05.692Z] 09:55:05     INFO -  11-24 09:54:33.930 D/GeckoViewConsole( 3473): onExtensionReady dummy@tests.mozilla.org
[task 2023-11-24T09:55:05.692Z] 09:55:05     INFO -  11-24 09:54:33.931 D/GeckoViewConsole( 3473): onInstalled dummy@tests.mozilla.org
[task 2023-11-24T09:55:05.692Z] 09:55:05     INFO -  11-24 09:54:33.932 W/GeckoEventDispatcher( 3473): No listener for GeckoView:WebExtension:OnInstalled
[task 2023-11-24T09:55:05.692Z] 09:55:05     INFO -  11-24 09:54:33.935 W/GeckoEventDispatcher( 3473): No listener for GeckoView:WebExtension:OnReady
[task 2023-11-24T09:55:05.692Z] 09:55:05     INFO -  11-24 09:54:33.935 I/WebExtension( 3473): releasePendingMessages: extension=actions@tests.mozilla.org nativeApp=browser session=org.mozilla.geckoview.GeckoSession@61ecbf6
[task 2023-11-24T09:55:05.693Z] 09:55:05     INFO -  11-24 09:54:33.935 I/WebExtension( 3473): releasePendingMessages: extension=actions@tests.mozilla.org nativeApp=browser session=null
[task 2023-11-24T09:55:05.693Z] 09:55:05     INFO -  11-24 09:54:33.935 D/WebExtension( 3473): handleMessage GeckoView:WebExtension:Connect
[task 2023-11-24T09:55:05.693Z] 09:55:05     INFO -  11-24 09:54:33.953 D/GeckoViewConsole( 3473): onEvent GeckoView:ActionDelegate:Attached {"extensionId":"actions@tests.mozilla.org"}
[task 2023-11-24T09:55:05.693Z] 09:55:05     INFO -  11-24 09:54:33.954 D/WebExtension( 3473): handleMessage GeckoView:BrowserAction:Update
[task 2023-11-24T09:55:05.693Z] 09:55:05     INFO -  11-24 09:54:33.954 D/WebExtension( 3473): handleMessage GeckoView:PageAction:Update
[task 2023-11-24T09:55:05.693Z] 09:55:05     INFO -  11-24 09:54:33.955 D/GeckoViewModule( 3473): dispatch GeckoView:LoadUri, data={"uri":"http://example.com","flags":0,"headerFilter":1}
[task 2023-11-24T09:55:05.693Z] 09:55:05     INFO -  11-24 09:54:33.956 D/GeckoViewNavigation( 3473): onEvent: event=GeckoView:LoadUri, data={"uri":"http://example.com","flags":0,"headerFilter":1}
[task 2023-11-24T09:55:05.693Z] 09:55:05     INFO -  11-24 09:54:33.967 D/GeckoViewProgress( 3473): ProgressTracker onStateChange: isTopLevel=true, flags=0xf0001, status=NS_OK
[task 2023-11-24T09:55:05.694Z] 09:55:05     INFO -  11-24 09:54:33.967 D/GeckoViewProgress( 3473): ProgressTracker onStateChange: uri=http://example.com/
[task 2023-11-24T09:55:05.694Z] 09:55:05     INFO -  11-24 09:54:33.968 D/GeckoViewProgress( 3473): ProgressTracker start http://example.com/
[task 2023-11-24T09:55:05.694Z] 09:55:05     INFO -  11-24 09:54:33.968 D/GeckoViewProgress( 3473): ProgressTracker updateProgress
[task 2023-11-24T09:55:05.694Z] 09:55:05     INFO -  11-24 09:54:33.969 D/GeckoViewProgress( 3473): ProgressTracker updateProgress data={"prev":0,"uri":"http://example.com/","locationChange":false,"pageStart":true,"pageStop":false,"firstPaint":false,"pageShow":false,"parsed":false} progress=15
[task 2023-11-24T09:55:05.694Z] 09:55:05     INFO -  11-24 09:54:33.969 D/GeckoSession( 3473): handleMessage GeckoView:ProgressChanged uri=null
[task 2023-11-24T09:55:05.694Z] 09:55:05     INFO -  11-24 09:54:33.969 D/GeckoViewProgress( 3473): StateTracker onStateChange: isTopLevel=true, flags=0xf0001, status=NS_OK loadType=1
[task 2023-11-24T09:55:05.694Z] 09:55:05     INFO -  11-24 09:54:33.970 D/GeckoSession( 3473): handleMessage GeckoView:PageStart uri=http://example.com/
[task 2023-11-24T09:55:05.694Z] 09:55:05     INFO -  11-24 09:54:33.995 D/GeckoViewSelectionActionDelegate[C]( 4852): handleEvent: visibilitychange
[task 2023-11-24T09:55:05.695Z] 09:55:05     INFO -  11-24 09:54:33.995 D/GeckoViewClipboardPermissionChild[C]( 4852): handleEvent: pagehide
[task 2023-11-24T09:55:05.695Z] 09:55:05     INFO -  11-24 09:54:33.997 D/GeckoViewAutoFill[C]( 4852): handleEvent: pagehide
[task 2023-11-24T09:55:05.695Z] 09:55:05     INFO -  11-24 09:54:33.998 D/GeckoViewClipboardPermissionParent( 3473): receiveMessage: DispatcherMessage
[task 2023-11-24T09:55:05.695Z] 09:55:05     INFO -  11-24 09:54:33.998 D/GeckoSession( 3473): handleMessage: GeckoView:DismissClipboardPermissionRequest
[task 2023-11-24T09:55:05.695Z] 09:55:05     INFO -  11-24 09:54:33.999 D/GeckoViewAutoFill( 3473): receiveMessage Clear
[task 2023-11-24T09:55:05.695Z] 09:55:05     INFO -  11-24 09:54:34.004 D/GeckoViewContentBlocking( 3473): onContentBlockingEvent 8000
[task 2023-11-24T09:55:05.695Z] 09:55:05     INFO -  11-24 09:54:34.004 D/GeckoViewContentBlocking( 3473): onContentBlockingEvent matchedList: null
[task 2023-11-24T09:55:05.695Z] 09:55:05     INFO -  11-24 09:54:34.005 D/GeckoViewContentBlocking( 3473): onContentBlockingEvent matchedTrackingLists: []
[task 2023-11-24T09:55:05.695Z] 09:55:05     INFO -  11-24 09:54:34.012 D/GeckoViewNavigation( 3473): onLocationChange
[task 2023-11-24T09:55:05.696Z] 09:55:05     INFO -  11-24 09:54:34.014 D/GeckoViewTranslations( 3473): handleEvent: TranslationsParent:LanguageState
[task 2023-11-24T09:55:05.696Z] 09:55:05     INFO -  11-24 09:54:34.014 D/GeckoSession( 3473): handleMessage GeckoView:LocationChange uri=http://example.com/
[task 2023-11-24T09:55:05.696Z] 09:55:05     INFO -  11-24 09:54:34.015 D/GeckoViewProgress( 3473): SecurityTracker onLocationChange: location=http://example.com/, flags=0
[task 2023-11-24T09:55:05.696Z] 09:55:05     INFO -  11-24 09:54:34.016 D/GeckoViewProgress( 3473): ProgressTracker onLocationChange: location=http://example.com/, flags=0
[task 2023-11-24T09:55:05.696Z] 09:55:05     INFO -  11-24 09:54:34.016 D/GeckoViewProgress( 3473): ProgressTracker changeLocation http://example.com/
[task 2023-11-24T09:55:05.696Z] 09:55:05     INFO -  11-24 09:54:34.016 W/GeckoEventDispatcher( 3473): No listener for GeckoView:BrowserAction:Update
[task 2023-11-24T09:55:05.696Z] 09:55:05     INFO -  11-24 09:54:34.017 W/GeckoEventDispatcher( 3473): No listener for GeckoView:PageAction:Update
[task 2023-11-24T09:55:05.696Z] 09:55:05     INFO -  11-24 09:54:34.017 D/GeckoViewProgress( 3473): onSecurityChange
[task 2023-11-24T09:55:05.696Z] 09:55:05     INFO -  11-24 09:54:34.018 D/GeckoSession( 3473): handleMessage GeckoView:SecurityChanged uri=null
[task 2023-11-24T09:55:05.697Z] 09:55:05     INFO -  11-24 09:54:34.036 D/WebExtension( 3473): handleMessage GeckoView:WebExtension:Connect
[task 2023-11-24T09:55:05.697Z] 09:55:05     INFO -  11-24 09:54:34.049 D/GeckoViewContent( 3473): handleEvent: pagetitlechanged
[task 2023-11-24T09:55:05.697Z] 09:55:05     INFO -  11-24 09:54:34.087 D/GeckoViewContentDelegateChild[C]( 4852): handleEvent: DOMContentLoaded
[task 2023-11-24T09:55:05.697Z] 09:55:05     INFO -  11-24 09:54:34.090 D/GeckoViewProgressDelegate[C]( 4852): handleEvent: DOMContentLoaded
[task 2023-11-24T09:55:05.697Z] 09:55:05     INFO -  11-24 09:54:34.093 D/GeckoViewProgress( 3473): receiveMessage: DOMContentLoaded
[task 2023-11-24T09:55:05.697Z] 09:55:05     INFO -  11-24 09:54:34.096 D/GeckoViewProgress( 3473): ProgressTracker handleEvent: DOMContentLoaded
[task 2023-11-24T09:55:05.697Z] 09:55:05     INFO -  11-24 09:54:34.096 D/GeckoViewProgress( 3473): ProgressTracker updateProgress
[task 2023-11-24T09:55:05.697Z] 09:55:05     INFO -  11-24 09:54:34.097 D/GeckoViewProgress( 3473): ProgressTracker updateProgress data={"prev":15,"uri":"http://example.com/","locationChange":true,"pageStart":true,"pageStop":false,"firstPaint":false,"pageShow":false,"parsed":true} progress=55
[task 2023-11-24T09:55:05.697Z] 09:55:05     INFO -  11-24 09:54:34.097 D/GeckoSession( 3473): handleMessage GeckoView:ProgressChanged uri=null
[task 2023-11-24T09:55:05.698Z] 09:55:05     INFO -  11-24 09:54:34.102 I/Web Content( 4852): Hi, I'm a dummy.
[task 2023-11-24T09:55:05.698Z] 09:55:05     INFO -  11-24 09:54:34.103 D/GeckoViewContent[C]( 4852): handleEvent: pageshow
[task 2023-11-24T09:55:05.698Z] 09:55:05     INFO -  11-24 09:54:34.104 D/WebExtension( 3473): handleMessage GeckoView:WebExtension:Connect
[task 2023-11-24T09:55:05.698Z] 09:55:05     INFO -  11-24 09:54:34.104 D/GeckoViewProgressDelegate[C]( 4852): handleEvent: pageshow
[task 2023-11-24T09:55:05.698Z] 09:55:05     INFO -  11-24 09:54:34.105 D/GeckoViewAutoFill[C]( 4852): handleEvent: pageshow
[task 2023-11-24T09:55:05.698Z] 09:55:05     INFO -  11-24 09:54:34.106 D/GeckoViewProgress( 3473): receiveMessage: pageshow
[task 2023-11-24T09:55:05.698Z] 09:55:05     INFO -  11-24 09:54:34.106 D/GeckoViewProgress( 3473): ProgressTracker handleEvent: pageshow
[task 2023-11-24T09:55:05.698Z] 09:55:05     INFO -  11-24 09:54:34.106 D/GeckoViewProgress( 3473): ProgressTracker updateProgress
[task 2023-11-24T09:55:05.698Z] 09:55:05     INFO -  11-24 09:54:34.106 D/GeckoViewProgress( 3473): ProgressTracker updateProgress data={"prev":55,"uri":"http://example.com/","locationChange":true,"pageStart":true,"pageStop":false,"firstPaint":false,"pageShow":true,"parsed":true} progress=100
[task 2023-11-24T09:55:05.699Z] 09:55:05     INFO -  11-24 09:54:34.107 D/GeckoSession( 3473): handleMessage GeckoView:ProgressChanged uri=null
[task 2023-11-24T09:55:05.699Z] 09:55:05     INFO -  11-24 09:54:34.162 D/GeckoViewProgress( 3473): ProgressTracker onStateChange: isTopLevel=true, flags=0xc0010, status=NS_OK
[task 2023-11-24T09:55:05.699Z] 09:55:05     INFO -  11-24 09:54:34.163 D/GeckoViewProgress( 3473): ProgressTracker onStateChange: uri=http://example.com/
[task 2023-11-24T09:55:05.699Z] 09:55:05     INFO -  11-24 09:54:34.163 D/GeckoViewProgress( 3473): ProgressTracker stop
[task 2023-11-24T09:55:05.699Z] 09:55:05     INFO -  11-24 09:54:34.164 D/GeckoViewProgress( 3473): ProgressTracker updateProgress
[task 2023-11-24T09:55:05.699Z] 09:55:05     INFO -  11-24 09:54:34.165 D/GeckoViewProgress( 3473): StateTracker onStateChange: isTopLevel=true, flags=0xc0010, status=NS_OK loadType=1
[task 2023-11-24T09:55:05.699Z] 09:55:05     INFO -  11-24 09:54:34.165 D/GeckoSession( 3473): handleMessage GeckoView:PageStop uri=null
[task 2023-11-24T09:55:05.699Z] 09:55:05     INFO -  11-24 09:54:34.169 D/GeckoViewContentDelegateParent( 3473): receiveMessage: DispatcherMessage
[task 2023-11-24T09:55:05.699Z] 09:55:05     INFO -  11-24 09:54:34.170 D/GeckoViewConsole( 3473): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"action":"enable","type":"pageAction"}}
[task 2023-11-24T09:55:05.700Z] 09:55:05     INFO -  11-24 09:54:34.182 W/GeckoEventDispatcher( 3473): No listener for GeckoView:PageAction:Update
[task 2023-11-24T09:55:05.700Z] 09:55:05     INFO -  11-24 09:54:35.003 W/GeckoSession( 3473): No history entries found.
[task 2023-11-24T09:55:05.700Z] 09:55:05     INFO -  11-24 09:54:35.004 D/GeckoSession( 3473): handleMessage GeckoView:StateUpdated uri=null
[task 2023-11-24T09:55:05.700Z] 09:55:05     INFO -  11-24 09:54:36.501 I/Gecko   ( 3473): nsWindow 0x77b372170400 destructor
[task 2023-11-24T09:55:05.700Z] 09:55:05     INFO -  11-24 09:54:36.501 I/Gecko   ( 3473): nsWindow 0x77b372172b00 destructor
[task 2023-11-24T09:55:05.700Z] 09:55:05     INFO -  11-24 09:54:36.501 I/Gecko   ( 3473): nsWindow 0x77b372173400 destructor
[task 2023-11-24T09:55:05.700Z] 09:55:05     INFO -  11-24 09:55:04.190 D/GeckoViewConsole( 3473): onEvent GeckoView:WebExtension:Uninstall {"webExtensionId":"actions@tests.mozilla.org"}
[task 2023-11-24T09:55:05.700Z] 09:55:05     INFO -  11-24 09:55:04.198 D/GeckoViewConsole( 3473): onUninstalling actions@tests.mozilla.org
[task 2023-11-24T09:55:05.700Z] 09:55:05     INFO -  11-24 09:55:04.212 D/GeckoViewClipboardPermissionChild[C]( 3473): handleEvent: pagehide
[task 2023-11-24T09:55:05.701Z] 09:55:05     INFO -  11-24 09:55:04.219 W/GeckoEventDispatcher( 3473): No listener for GeckoView:WebExtension:OnUninstalling
[task 2023-11-24T09:55:05.701Z] 09:55:05     INFO -  11-24 09:55:04.226 D/GeckoViewClipboardPermissionParent( 3473): receiveMessage: DispatcherMessage
[task 2023-11-24T09:55:05.701Z] 09:55:05     INFO -  11-24 09:55:04.226 D/GeckoViewActorParent( 3473): receiveMessage window destroyed DispatcherMessage GeckoView:DismissClipboardPermissionRequest
[task 2023-11-24T09:55:05.701Z] 09:55:05     INFO -  11-24 09:55:04.228 I/Gecko   ( 3473): [Parent 3473, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:61
[task 2023-11-24T09:55:05.701Z] 09:55:05     INFO -  11-24 09:55:04.228 I/Gecko   ( 3473): [Parent 3473, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:61
[task 2023-11-24T09:55:05.701Z] 09:55:05     INFO -  11-24 09:55:04.229 I/Gecko   ( 3473): [Parent 3473, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:61
[task 2023-11-24T09:55:05.702Z] 09:55:05     INFO -  11-24 09:55:04.247 D/GeckoViewConsole( 3473): onUninstalled actions@tests.mozilla.org
[task 2023-11-24T09:55:05.702Z] 09:55:05     INFO -  11-24 09:55:04.263 W/GeckoEventDispatcher( 3473): No listener for GeckoView:WebExtension:OnUninstalled
[task 2023-11-24T09:55:05.702Z] 09:55:05     INFO -  11-24 09:55:04.270 D/GeckoViewConsole( 3473): onEvent GeckoView:WebExtension:Uninstall {"webExtensionId":"dummy@tests.mozilla.org"}
[task 2023-11-24T09:55:05.702Z] 09:55:05     INFO -  11-24 09:55:04.273 D/GeckoViewConsole( 3473): onUninstalling dummy@tests.mozilla.org
[task 2023-11-24T09:55:05.702Z] 09:55:05     INFO -  11-24 09:55:04.278 W/GeckoEventDispatcher( 3473): No listener for GeckoView:WebExtension:OnUninstalling
[task 2023-11-24T09:55:05.702Z] 09:55:05     INFO -  11-24 09:55:04.290 D/GeckoViewConsole( 3473): onUninstalled dummy@tests.mozilla.org
[task 2023-11-24T09:55:05.702Z] 09:55:05     INFO -  11-24 09:55:04.298 W/GeckoEventDispatcher( 3473): No listener for GeckoView:WebExtension:OnUninstalled
[task 2023-11-24T09:55:05.702Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): Error
[task 2023-11-24T09:55:05.702Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2023-11-24T09:55:05.703Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2023-11-24T09:55:05.703Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2023-11-24T09:55:05.703Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2023-11-24T09:55:05.703Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2023-11-24T09:55:05.703Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForResult(UiThreadUtils.java:79)
[task 2023-11-24T09:55:05.703Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForResult(GeckoSessionTestRule.java:2957)
[task 2023-11-24T09:55:05.703Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForResult(GeckoSessionTestRule.java:2942)
[task 2023-11-24T09:55:05.703Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): 	at org.mozilla.geckoview.test.ExtensionActionTest.testActionApi(ExtensionActionTest.kt:261)
[task 2023-11-24T09:55:05.704Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): 	at org.mozilla.geckoview.test.ExtensionActionTest.setup(ExtensionActionTest.kt:120)
[task 2023-11-24T09:55:05.704Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2023-11-24T09:55:05.704Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:59)
[task 2023-11-24T09:55:05.704Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2023-11-24T09:55:05.704Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:56)
[task 2023-11-24T09:55:05.704Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): 	at org.junit.internal.runners.statements.RunBefores.invokeMethod(RunBefores.java:33)
[task 2023-11-24T09:55:05.704Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): 	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:24)
[task 2023-11-24T09:55:05.705Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): 	at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
[task 2023-11-24T09:55:05.705Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): 	at org.junit.rules.Verifier$1.evaluate(Verifier.java:35)
[task 2023-11-24T09:55:05.705Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0(GeckoSessionTestRule.java:1509)
[task 2023-11-24T09:55:05.705Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.$r8$lambda$eGWMAiU-In-hdc_ISYHPMUhHPKA(GeckoSessionTestRule.java)
[task 2023-11-24T09:55:05.705Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2$$ExternalSyntheticLambda1.run(D8$$SyntheticClass)
[task 2023-11-24T09:55:05.705Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:428)
[task 2023-11-24T09:55:05.705Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): 	at java.util.concurrent.FutureTask.run(FutureTask.java:237)
[task 2023-11-24T09:55:05.705Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): 	at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2023-11-24T09:55:05.706Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2023-11-24T09:55:05.706Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2023-11-24T09:55:05.706Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): 	at android.os.Looper.loop(Looper.java:154)
[task 2023-11-24T09:55:05.706Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): 	at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2023-11-24T09:55:05.706Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2023-11-24T09:55:05.706Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2023-11-24T09:55:05.706Z] 09:55:05     INFO -  11-24 09:55:04.300 E/GeckoSessionTestRule( 3473): 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2023-11-24T09:55:05.706Z] 09:55:05     INFO -  11-24 09:55:04.307 D/GeckoViewContent( 3473): handleEvent: DOMWindowClose
[task 2023-11-24T09:55:05.707Z] 09:55:05     INFO -  11-24 09:55:04.309 D/GeckoViewConsole( 3473): onEvent GeckoView:WebExtension:List null
[task 2023-11-24T09:55:05.707Z] 09:55:05     INFO -  11-24 09:55:04.317 E/GeckoSessionTestRule( 3473): test_end c5ee677f-bc83-49bd-9e28-2d35f3d0f059 testPopupMessaging[#pageAction](org.mozilla.geckoview.test.ExtensionActionTest)
[task 2023-11-24T09:55:05.707Z] 09:55:05  WARNING -  TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.ExtensionActionTest#testPopupMessaging[#pageAction] | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2023-11-24T09:55:05.707Z] 09:55:05     INFO -  TEST-INFO took 31390ms
[task 2023-11-24T09:55:05.707Z] 09:55:05     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=1092
[task 2023-11-24T09:55:05.707Z] 09:55:05     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2023-11-24T09:55:05.707Z] 09:55:05     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2023-11-24T09:55:05.707Z] 09:55:05     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=setDefaultTitle[#pageAction]
[task 2023-11-24T09:55:05.707Z] 09:55:05     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.ExtensionActionTest
[task 2023-11-24T09:55:05.708Z] 09:55:05     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=176
[task 2023-11-24T09:55:05.708Z] 09:55:05     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: 1
[task 2023-11-24T09:55:05.708Z] 09:55:05     INFO -  TEST-START | org.mozilla.geckoview.test.ExtensionActionTest#setDefaultTitle[#pageAction]
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 2 years ago → 2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 2 years ago → 2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 2 years ago → 1 year ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 1 year ago → 1 year ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 1 year ago → 1 year ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 1 year ago → 8 months ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 8 months ago → 6 months ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
You need to log in before you can comment on or make changes to this bug.