1788451 - Intermittent org.mozilla.geckoview.test.ExtensionActionTest#setOverridenTitle[#pageAction] | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms

Status: RESOLVED INCOMPLETE

(GeckoView :: General, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2022-08-31T18:41:09.017Z] 18:41:09     INFO -  TEST-START | org.mozilla.geckoview.test.ExtensionActionTest#setOverridenTitle[#pageAction]
[task 2022-08-31T18:41:39.341Z] 18:41:39     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=937
[task 2022-08-31T18:41:39.341Z] 18:41:39     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2022-08-31T18:41:39.341Z] 18:41:39     INFO -  org.mozilla.geckoview.test | Error in setOverridenTitle[#pageAction](org.mozilla.geckoview.test.ExtensionActionTest):
[task 2022-08-31T18:41:39.342Z] 18:41:39     INFO -  org.mozilla.geckoview.test | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-08-31T18:41:39.342Z] 18:41:39     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-08-31T18:41:39.342Z] 18:41:39     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-08-31T18:41:39.342Z] 18:41:39     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-08-31T18:41:39.343Z] 18:41:39     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-08-31T18:41:39.343Z] 18:41:39     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForResult(UiThreadUtils.java:79)
[task 2022-08-31T18:41:39.343Z] 18:41:39     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForResult(GeckoSessionTestRule.java:2766)
[task 2022-08-31T18:41:39.344Z] 18:41:39     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForResult(GeckoSessionTestRule.java:2751)
[task 2022-08-31T18:41:39.344Z] 18:41:39     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.ExtensionActionTest.testActionApi(ExtensionActionTest.kt:240)
[task 2022-08-31T18:41:39.344Z] 18:41:39     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.ExtensionActionTest.setup(ExtensionActionTest.kt:111)
[task 2022-08-31T18:41:39.345Z] 18:41:39     INFO -  org.mozilla.geckoview.test |
[task 2022-08-31T18:41:39.345Z] 18:41:39     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2022-08-31T18:41:39.345Z] 18:41:39     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=setOverridenTitle[#pageAction]
[task 2022-08-31T18:41:39.345Z] 18:41:39     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.ExtensionActionTest
[task 2022-08-31T18:41:39.346Z] 18:41:39     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-08-31T18:41:39.346Z] 18:41:39     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-08-31T18:41:39.346Z] 18:41:39     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-08-31T18:41:39.347Z] 18:41:39     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-08-31T18:41:39.347Z] 18:41:39     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-08-31T18:41:39.347Z] 18:41:39     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForResult(UiThreadUtils.java:79)
[task 2022-08-31T18:41:39.348Z] 18:41:39     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForResult(GeckoSessionTestRule.java:2766)
[task 2022-08-31T18:41:39.348Z] 18:41:39     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForResult(GeckoSessionTestRule.java:2751)
[task 2022-08-31T18:41:39.348Z] 18:41:39     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.ExtensionActionTest.testActionApi(ExtensionActionTest.kt:240)
[task 2022-08-31T18:41:39.349Z] 18:41:39     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.ExtensionActionTest.setup(ExtensionActionTest.kt:111)
[task 2022-08-31T18:41:39.349Z] 18:41:39     INFO -  org.mozilla.geckoview.test |
[task 2022-08-31T18:41:39.349Z] 18:41:39     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=135
[task 2022-08-31T18:41:39.349Z] 18:41:39     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: -2
[task 2022-08-31T18:41:39.349Z] 18:41:39     INFO -  Printing logcat for test:
[task 2022-08-31T18:41:39.387Z] 18:41:39     INFO -  08-31 18:41:08.417 E/GeckoSessionTestRule( 3396): test_start 1f0befec-3ff2-40ff-89cf-b127eb38b1ec setOverridenTitle[#pageAction](org.mozilla.geckoview.test.ExtensionActionTest)
[task 2022-08-31T18:41:39.388Z] 18:41:39     INFO -  08-31 18:41:08.417 E/GeckoSessionTestRule( 3396): before prepareStatement setOverridenTitle[#pageAction](org.mozilla.geckoview.test.ExtensionActionTest)
[task 2022-08-31T18:41:39.388Z] 18:41:39     INFO -  08-31 18:41:08.418 D/GeckoViewSelectionActionDelegate[C]( 9780): handleEvent: visibilitychange
<...>
[task 2022-08-31T18:41:39.420Z] 18:41:39     INFO -  08-31 18:41:38.737 E/GeckoSessionTestRule( 3396): org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-08-31T18:41:39.420Z] 18:41:39     INFO -  08-31 18:41:38.737 E/GeckoSessionTestRule( 3396): 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-08-31T18:41:39.420Z] 18:41:39     INFO -  08-31 18:41:38.737 E/GeckoSessionTestRule( 3396): 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-08-31T18:41:39.420Z] 18:41:39     INFO -  08-31 18:41:38.737 E/GeckoSessionTestRule( 3396): 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-08-31T18:41:39.420Z] 18:41:39     INFO -  08-31 18:41:38.737 E/GeckoSessionTestRule( 3396): 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-08-31T18:41:39.421Z] 18:41:39     INFO -  08-31 18:41:38.737 E/GeckoSessionTestRule( 3396): 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForResult(UiThreadUtils.java:79)
[task 2022-08-31T18:41:39.421Z] 18:41:39     INFO -  08-31 18:41:38.737 E/GeckoSessionTestRule( 3396): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForResult(GeckoSessionTestRule.java:2766)
[task 2022-08-31T18:41:39.421Z] 18:41:39     INFO -  08-31 18:41:38.737 E/GeckoSessionTestRule( 3396): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForResult(GeckoSessionTestRule.java:2751)
[task 2022-08-31T18:41:39.421Z] 18:41:39     INFO -  08-31 18:41:38.737 E/GeckoSessionTestRule( 3396): 	at org.mozilla.geckoview.test.ExtensionActionTest.testActionApi(ExtensionActionTest.kt:240)
[task 2022-08-31T18:41:39.421Z] 18:41:39     INFO -  08-31 18:41:38.737 E/GeckoSessionTestRule( 3396): 	at org.mozilla.geckoview.test.ExtensionActionTest.setup(ExtensionActionTest.kt:111)
[task 2022-08-31T18:41:39.421Z] 18:41:39     INFO -  08-31 18:41:38.737 E/GeckoSessionTestRule( 3396): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2022-08-31T18:41:39.422Z] 18:41:39     INFO -  08-31 18:41:38.737 E/GeckoSessionTestRule( 3396): 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2022-08-31T18:41:39.422Z] 18:41:39     INFO -  08-31 18:41:38.737 E/GeckoSessionTestRule( 3396): 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2022-08-31T18:41:39.422Z] 18:41:39     INFO -  08-31 18:41:38.737 E/GeckoSessionTestRule( 3396): 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2022-08-31T18:41:39.422Z] 18:41:39     INFO -  08-31 18:41:38.737 E/GeckoSessionTestRule( 3396): 	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:24)
[task 2022-08-31T18:41:39.422Z] 18:41:39     INFO -  08-31 18:41:38.737 E/GeckoSessionTestRule( 3396): 	at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
[task 2022-08-31T18:41:39.422Z] 18:41:39     INFO -  08-31 18:41:38.737 E/GeckoSessionTestRule( 3396): 	at org.junit.rules.Verifier$1.evaluate(Verifier.java:35)
[task 2022-08-31T18:41:39.422Z] 18:41:39     INFO -  08-31 18:41:38.737 E/GeckoSessionTestRule( 3396): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$org-mozilla-geckoview-test-rule-GeckoSessionTestRule$2(GeckoSessionTestRule.java:1465)
[task 2022-08-31T18:41:39.423Z] 18:41:39     INFO -  08-31 18:41:38.737 E/GeckoSessionTestRule( 3396): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2$$ExternalSyntheticLambda0.run(Unknown Source)
[task 2022-08-31T18:41:39.423Z] 18:41:39     INFO -  08-31 18:41:38.737 E/GeckoSessionTestRule( 3396): 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:428)
[task 2022-08-31T18:41:39.423Z] 18:41:39     INFO -  08-31 18:41:38.737 E/GeckoSessionTestRule( 3396): 	at java.util.concurrent.FutureTask.run(FutureTask.java:237)
[task 2022-08-31T18:41:39.423Z] 18:41:39     INFO -  08-31 18:41:38.737 E/GeckoSessionTestRule( 3396): 	at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2022-08-31T18:41:39.423Z] 18:41:39     INFO -  08-31 18:41:38.737 E/GeckoSessionTestRule( 3396): 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-08-31T18:41:39.423Z] 18:41:39     INFO -  08-31 18:41:38.737 E/GeckoSessionTestRule( 3396): 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-08-31T18:41:39.423Z] 18:41:39     INFO -  08-31 18:41:38.737 E/GeckoSessionTestRule( 3396): 	at android.os.Looper.loop(Looper.java:154)
[task 2022-08-31T18:41:39.423Z] 18:41:39     INFO -  08-31 18:41:38.737 E/GeckoSessionTestRule( 3396): 	at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2022-08-31T18:41:39.423Z] 18:41:39     INFO -  08-31 18:41:38.737 E/GeckoSessionTestRule( 3396): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2022-08-31T18:41:39.424Z] 18:41:39     INFO -  08-31 18:41:38.737 E/GeckoSessionTestRule( 3396): 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2022-08-31T18:41:39.424Z] 18:41:39     INFO -  08-31 18:41:38.737 E/GeckoSessionTestRule( 3396): 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2022-08-31T18:41:39.424Z] 18:41:39     INFO -  08-31 18:41:38.738 D/GeckoViewContent( 3396): handleEvent: DOMWindowClose
[task 2022-08-31T18:41:39.424Z] 18:41:39     INFO -  08-31 18:41:38.739 D/GeckoViewConsole( 3396): onEvent GeckoView:WebExtension:List null
[task 2022-08-31T18:41:39.424Z] 18:41:39     INFO -  08-31 18:41:38.740 E/GeckoSessionTestRule( 3396): test_end c5ee677f-bc83-49bd-9e28-2d35f3d0f059 setOverridenTitle[#pageAction](org.mozilla.geckoview.test.ExtensionActionTest)
[task 2022-08-31T18:41:39.424Z] 18:41:39  WARNING -  TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.ExtensionActionTest#setOverridenTitle[#pageAction] | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-08-31T18:41:39.424Z] 18:41:39     INFO -  TEST-INFO took 30372ms
[task 2022-08-31T18:41:39.424Z] 18:41:39     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=937
[task 2022-08-31T18:41:39.425Z] 18:41:39     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2022-08-31T18:41:39.425Z] 18:41:39     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2022-08-31T18:41:39.425Z] 18:41:39     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=testPopupsCanCloseThemselves[#pageAction]
[task 2022-08-31T18:41:39.425Z] 18:41:39     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.ExtensionActionTest
[task 2022-08-31T18:41:39.425Z] 18:41:39     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=136
[task 2022-08-31T18:41:39.425Z] 18:41:39     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: 1
[task 2022-08-31T18:41:39.425Z] 18:41:39     INFO -  TEST-START | org.mozilla.geckoview.test.ExtensionActionTest#testPopupsCanCloseThemselves[#pageAction]

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1788451&startday=2022-08-29&endday=2022-09-04&tree=all

Comment 2

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

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

Comment 3

[Treeherder Bug Filer]

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

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1788451&startday=2023-05-22&endday=2023-05-28&tree=all

Comment 5

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

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

Comment 6

[Treeherder Bug Filer]

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

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1788451&startday=2023-09-11&endday=2023-09-17&tree=all

Comment 8

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

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

Comment 9

[Treeherder Bug Filer]

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

Comment 10

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1788451&startday=2024-01-08&endday=2024-01-14&tree=all

Comment 11

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

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

Comment 12

[Treeherder Bug Filer]

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

Comment 13

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform and build breakdown:

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

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

Comment 14

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

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

Comment 15

[Treeherder Bug Filer]

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

Comment 16

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 1
  • debug-isolated-process: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1788451&startday=2024-06-24&endday=2024-06-30&tree=all

Comment 17

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1788451&startday=2024-07-01&endday=2024-07-07&tree=all

Comment 18

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform and build breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1788451&startday=2024-07-22&endday=2024-07-28&tree=all

Comment 19

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

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