Closed Bug 1922136 Opened 9 months ago Closed 4 months ago

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

Categories

(GeckoView :: General, defect, P5)

Product:
GeckoView
Component:
General
Platform:
All
Android
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, intermittent-testcase)

Filed by: imoraru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=476465024&repo=mozilla-beta
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/LOjHXEZVQD-9qZ0vmbsknw/runs/0/artifacts/public/logs/live_backing.log

[task 2024-10-01T21:12:06.138Z] 21:12:06     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: 1
[task 2024-10-01T21:12:06.138Z] 21:12:06     INFO -  TEST-START | org.mozilla.geckoview.test.ExtensionActionTest#testPopupMessaging[#browserAction]
[task 2024-10-01T21:12:36.760Z] 21:12:36     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=1167
[task 2024-10-01T21:12:36.761Z] 21:12:36     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2024-10-01T21:12:36.761Z] 21:12:36     INFO -  org.mozilla.geckoview.test | Error in testPopupMessaging[#browserAction](org.mozilla.geckoview.test.ExtensionActionTest):
[task 2024-10-01T21:12:36.762Z] 21:12:36     INFO -  org.mozilla.geckoview.test | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2024-10-01T21:12:36.762Z] 21:12:36     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2024-10-01T21:12:36.762Z] 21:12:36     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2024-10-01T21:12:36.762Z] 21:12:36     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2024-10-01T21:12:36.763Z] 21:12:36     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2024-10-01T21:12:36.766Z] 21:12:36     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForResult(UiThreadUtils.java:79)
[task 2024-10-01T21:12:36.766Z] 21:12:36     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForResult(GeckoSessionTestRule.java:2985)
[task 2024-10-01T21:12:36.766Z] 21:12:36     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForResult(GeckoSessionTestRule.java:2970)
[task 2024-10-01T21:12:36.766Z] 21:12:36     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.ExtensionActionTest.testActionApi(ExtensionActionTest.kt:261)
[task 2024-10-01T21:12:36.766Z] 21:12:36     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.ExtensionActionTest.testPopupMessaging(ExtensionActionTest.kt:731)
[task 2024-10-01T21:12:36.766Z] 21:12:36     INFO -  org.mozilla.geckoview.test |
[task 2024-10-01T21:12:36.766Z] 21:12:36     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2024-10-01T21:12:36.766Z] 21:12:36     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=testPopupMessaging[#browserAction]
[task 2024-10-01T21:12:36.766Z] 21:12:36     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.ExtensionActionTest
[task 2024-10-01T21:12:36.766Z] 21:12:36     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2024-10-01T21:12:36.766Z] 21:12:36     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2024-10-01T21:12:36.766Z] 21:12:36     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2024-10-01T21:12:36.766Z] 21:12:36     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2024-10-01T21:12:36.766Z] 21:12:36     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2024-10-01T21:12:36.766Z] 21:12:36     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForResult(UiThreadUtils.java:79)
[task 2024-10-01T21:12:36.766Z] 21:12:36     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForResult(GeckoSessionTestRule.java:2985)
[task 2024-10-01T21:12:36.766Z] 21:12:36     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForResult(GeckoSessionTestRule.java:2970)
[task 2024-10-01T21:12:36.766Z] 21:12:36     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.ExtensionActionTest.testActionApi(ExtensionActionTest.kt:261)
[task 2024-10-01T21:12:36.766Z] 21:12:36     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.ExtensionActionTest.testPopupMessaging(ExtensionActionTest.kt:731)
[task 2024-10-01T21:12:36.766Z] 21:12:36     INFO -  org.mozilla.geckoview.test |
[task 2024-10-01T21:12:36.766Z] 21:12:36     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=211
[task 2024-10-01T21:12:36.766Z] 21:12:36     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: -2
[task 2024-10-01T21:12:36.766Z] 21:12:36     INFO -  Printing logcat for test:
[task 2024-10-01T21:12:37.177Z] 21:12:37     INFO -  10-01 21:12:06.125 E/GeckoSessionTestRule( 3483): test_start 1f0befec-3ff2-40ff-89cf-b127eb38b1ec testPopupMessaging[#browserAction](org.mozilla.geckoview.test.ExtensionActionTest)
[task 2024-10-01T21:12:37.177Z] 21:12:37     INFO -  10-01 21:12:06.125 E/GeckoSessionTestRule( 3483): before prepareStatement testPopupMessaging[#browserAction](org.mozilla.geckoview.test.ExtensionActionTest)
[task 2024-10-01T21:12:37.178Z] 21:12:37     INFO -  10-01 21:12:06.128 E/eglCodecCommon( 3552): glUtilsParamSize: unknow param 0x00008caa
<...>
[task 2024-10-01T21:12:37.191Z] 21:12:37     INFO -  10-01 21:12:16.464 D/GeckoSession( 3483): handleMessage GeckoView:StateUpdated uri=null
[task 2024-10-01T21:12:37.192Z] 21:12:37     INFO -  10-01 21:12:16.465 W/GeckoSession( 3483): No history entries found.
[task 2024-10-01T21:12:37.192Z] 21:12:37     INFO -  10-01 21:12:36.699 W/GeckoEventDispatcher( 3483): No listener for GeckoView:WebExtension:OnUninstalling
[task 2024-10-01T21:12:37.192Z] 21:12:37     INFO -  10-01 21:12:36.718 W/GeckoEventDispatcher( 3483): No listener for GeckoView:WebExtension:OnUninstalled
[task 2024-10-01T21:12:37.192Z] 21:12:37     INFO -  10-01 21:12:36.727 W/GeckoEventDispatcher( 3483): No listener for GeckoView:WebExtension:OnUninstalling
[task 2024-10-01T21:12:37.192Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): Error
[task 2024-10-01T21:12:37.192Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2024-10-01T21:12:37.192Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2024-10-01T21:12:37.192Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2024-10-01T21:12:37.193Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2024-10-01T21:12:37.193Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2024-10-01T21:12:37.193Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForResult(UiThreadUtils.java:79)
[task 2024-10-01T21:12:37.193Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForResult(GeckoSessionTestRule.java:2985)
[task 2024-10-01T21:12:37.193Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForResult(GeckoSessionTestRule.java:2970)
[task 2024-10-01T21:12:37.193Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): 	at org.mozilla.geckoview.test.ExtensionActionTest.testActionApi(ExtensionActionTest.kt:261)
[task 2024-10-01T21:12:37.194Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): 	at org.mozilla.geckoview.test.ExtensionActionTest.testPopupMessaging(ExtensionActionTest.kt:731)
[task 2024-10-01T21:12:37.194Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2024-10-01T21:12:37.194Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:59)
[task 2024-10-01T21:12:37.194Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2024-10-01T21:12:37.194Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:56)
[task 2024-10-01T21:12:37.194Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2024-10-01T21:12:37.194Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): 	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
[task 2024-10-01T21:12:37.195Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): 	at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
[task 2024-10-01T21:12:37.195Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): 	at org.junit.rules.Verifier$1.evaluate(Verifier.java:35)
[task 2024-10-01T21:12:37.195Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0(GeckoSessionTestRule.java:1502)
[task 2024-10-01T21:12:37.195Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.$r8$lambda$iCBopfpsbLU-HFWW53O-4m1PB6c(GeckoSessionTestRule.java)
[task 2024-10-01T21:12:37.195Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2$$ExternalSyntheticLambda1.run(D8$$SyntheticClass:0)
[task 2024-10-01T21:12:37.195Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:428)
[task 2024-10-01T21:12:37.196Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): 	at java.util.concurrent.FutureTask.run(FutureTask.java:237)
[task 2024-10-01T21:12:37.196Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): 	at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2024-10-01T21:12:37.196Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2024-10-01T21:12:37.196Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2024-10-01T21:12:37.196Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): 	at android.os.Looper.loop(Looper.java:154)
[task 2024-10-01T21:12:37.196Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): 	at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2024-10-01T21:12:37.197Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2024-10-01T21:12:37.197Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2024-10-01T21:12:37.197Z] 21:12:37     INFO -  10-01 21:12:36.737 E/GeckoSessionTestRule( 3483): 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2024-10-01T21:12:37.197Z] 21:12:37     INFO -  10-01 21:12:36.738 W/GeckoEventDispatcher( 3483): No listener for GeckoView:WebExtension:OnUninstalled
[task 2024-10-01T21:12:37.197Z] 21:12:37     INFO -  10-01 21:12:36.745 E/GeckoSessionTestRule( 3483): test_end c5ee677f-bc83-49bd-9e28-2d35f3d0f059 testPopupMessaging[#browserAction](org.mozilla.geckoview.test.ExtensionActionTest)
[task 2024-10-01T21:12:37.197Z] 21:12:37  WARNING -  TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.ExtensionActionTest#testPopupMessaging[#browserAction] | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2024-10-01T21:12:37.198Z] 21:12:37     INFO -  TEST-INFO took 31041ms
[task 2024-10-01T21:12:37.198Z] 21:12:37     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=1167
[task 2024-10-01T21:12:37.198Z] 21:12:37     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2024-10-01T21:12:37.198Z] 21:12:37     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2024-10-01T21:12:37.198Z] 21:12:37     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=setDefaultTitle[#browserAction]
[task 2024-10-01T21:12:37.198Z] 21:12:37     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.ExtensionActionTest
[task 2024-10-01T21:12:37.198Z] 21:12:37     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=212
[task 2024-10-01T21:12:37.198Z] 21:12:37     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: 1
[task 2024-10-01T21:12:37.199Z] 21:12:37     INFO -  TEST-START | org.mozilla.geckoview.test.ExtensionActionTest#setDefaultTitle[#browserAction]

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

Status: NEW → RESOLVED
Closed: 8 months ago
Resolution: --- → INCOMPLETE

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=493548737&repo=mozilla-beta

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

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

Status: REOPENED → RESOLVED
Closed: 8 months ago → 4 months ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.