1770389 - Intermittent org.mozilla.geckoview.test.PromptDelegateTest#colorTest | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms

Status: RESOLVED INCOMPLETE

(GeckoView :: General, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2022-05-20T09:47:02.112Z] 09:47:02     INFO -  TEST-START | org.mozilla.geckoview.test.PromptDelegateTest#colorTest
[task 2022-05-20T09:47:32.596Z] 09:47:32     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=916
[task 2022-05-20T09:47:32.597Z] 09:47:32     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2022-05-20T09:47:32.597Z] 09:47:32     INFO -  org.mozilla.geckoview.test | Error in colorTest(org.mozilla.geckoview.test.PromptDelegateTest):
[task 2022-05-20T09:47:32.597Z] 09:47:32     INFO -  org.mozilla.geckoview.test | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-05-20T09:47:32.597Z] 09:47:32     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-05-20T09:47:32.598Z] 09:47:32     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-05-20T09:47:32.598Z] 09:47:32     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-05-20T09:47:32.598Z] 09:47:32     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-05-20T09:47:32.598Z] 09:47:32     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1775)
[task 2022-05-20T09:47:32.599Z] 09:47:32     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForPageStops(GeckoSessionTestRule.java:1531)
[task 2022-05-20T09:47:32.599Z] 09:47:32     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForPageStop(GeckoSessionTestRule.java:1506)
[task 2022-05-20T09:47:32.599Z] 09:47:32     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.BaseSessionTest.waitForPageStop(BaseSessionTest.kt:183)
[task 2022-05-20T09:47:32.599Z] 09:47:32     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.PromptDelegateTest.colorTest(PromptDelegateTest.kt:512)
[task 2022-05-20T09:47:32.599Z] 09:47:32     INFO -  org.mozilla.geckoview.test |
[task 2022-05-20T09:47:32.600Z] 09:47:32     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2022-05-20T09:47:32.600Z] 09:47:32     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=colorTest
[task 2022-05-20T09:47:32.600Z] 09:47:32     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.PromptDelegateTest
[task 2022-05-20T09:47:32.600Z] 09:47:32     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-05-20T09:47:32.601Z] 09:47:32     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-05-20T09:47:32.601Z] 09:47:32     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-05-20T09:47:32.601Z] 09:47:32     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-05-20T09:47:32.601Z] 09:47:32     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-05-20T09:47:32.601Z] 09:47:32     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1775)
[task 2022-05-20T09:47:32.602Z] 09:47:32     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForPageStops(GeckoSessionTestRule.java:1531)
[task 2022-05-20T09:47:32.602Z] 09:47:32     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForPageStop(GeckoSessionTestRule.java:1506)
[task 2022-05-20T09:47:32.602Z] 09:47:32     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.BaseSessionTest.waitForPageStop(BaseSessionTest.kt:183)
[task 2022-05-20T09:47:32.602Z] 09:47:32     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.PromptDelegateTest.colorTest(PromptDelegateTest.kt:512)
[task 2022-05-20T09:47:32.602Z] 09:47:32     INFO -  org.mozilla.geckoview.test |
[task 2022-05-20T09:47:32.603Z] 09:47:32     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=521
[task 2022-05-20T09:47:32.603Z] 09:47:32     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: -2
[task 2022-05-20T09:47:32.603Z] 09:47:32     INFO -  Printing logcat for test:
[task 2022-05-20T09:47:32.666Z] 09:47:32     INFO -  05-20 09:47:01.532 E/GeckoSessionTestRule( 3415): test_start 1f0befec-3ff2-40ff-89cf-b127eb38b1ec colorTest(org.mozilla.geckoview.test.PromptDelegateTest)
[task 2022-05-20T09:47:32.666Z] 09:47:32     INFO -  05-20 09:47:01.532 E/GeckoSessionTestRule( 3415): before prepareStatement colorTest(org.mozilla.geckoview.test.PromptDelegateTest)
[task 2022-05-20T09:47:32.666Z] 09:47:32     INFO -  05-20 09:47:01.532 D/GeckoViewModule( 3415): unregisterListener
[task 2022-05-20T09:47:32.666Z] 09:47:32     INFO -  05-20 09:47:01.532 D/GeckoViewMediaControl( 3415): onDisable
<...>
[task 2022-05-20T09:47:32.695Z] 09:47:32     INFO -  05-20 09:47:31.991 E/GeckoSessionTestRule( 3415): 	at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2022-05-20T09:47:32.695Z] 09:47:32     INFO -  05-20 09:47:31.991 E/GeckoSessionTestRule( 3415): 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-05-20T09:47:32.695Z] 09:47:32     INFO -  05-20 09:47:31.991 E/GeckoSessionTestRule( 3415): 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-05-20T09:47:32.695Z] 09:47:32     INFO -  05-20 09:47:31.991 E/GeckoSessionTestRule( 3415): 	at android.os.Looper.loop(Looper.java:154)
[task 2022-05-20T09:47:32.695Z] 09:47:32     INFO -  05-20 09:47:31.991 E/GeckoSessionTestRule( 3415): 	at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2022-05-20T09:47:32.695Z] 09:47:32     INFO -  05-20 09:47:31.991 E/GeckoSessionTestRule( 3415): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2022-05-20T09:47:32.695Z] 09:47:32     INFO -  05-20 09:47:31.991 E/GeckoSessionTestRule( 3415): 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2022-05-20T09:47:32.695Z] 09:47:32     INFO -  05-20 09:47:31.991 E/GeckoSessionTestRule( 3415): 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2022-05-20T09:47:32.696Z] 09:47:32     INFO -  05-20 09:47:31.994 D/GeckoViewConsole( 3415): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"ccfe4a03-f1fd-4435-88ae-be809f282d9f","args":{"oldPrefs":{"dom.disable_open_during_load":false}},"type":"RestorePrefs"}}
[task 2022-05-20T09:47:32.696Z] 09:47:32     INFO -  05-20 09:47:32.007 D/GeckoViewContent( 3415): handleEvent: DOMWindowClose
[task 2022-05-20T09:47:32.696Z] 09:47:32     INFO -  05-20 09:47:32.009 D/GeckoViewConsole( 3415): onEvent GeckoView:WebExtension:List null
[task 2022-05-20T09:47:32.696Z] 09:47:32     INFO -  05-20 09:47:32.013 E/GeckoSessionTestRule( 3415): test_end c5ee677f-bc83-49bd-9e28-2d35f3d0f059 colorTest(org.mozilla.geckoview.test.PromptDelegateTest)
[task 2022-05-20T09:47:32.696Z] 09:47:32  WARNING -  TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.PromptDelegateTest#colorTest | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-05-20T09:47:32.696Z] 09:47:32     INFO -  TEST-INFO took 30555ms
[task 2022-05-20T09:47:32.696Z] 09:47:32     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=916
[task 2022-05-20T09:47:32.696Z] 09:47:32     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2022-05-20T09:47:32.696Z] 09:47:32     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2022-05-20T09:47:32.696Z] 09:47:32     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=selectTestSimple
[task 2022-05-20T09:47:32.696Z] 09:47:32     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.PromptDelegateTest
[task 2022-05-20T09:47:32.697Z] 09:47:32     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=522
[task 2022-05-20T09:47:32.697Z] 09:47:32     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: 1
[task 2022-05-20T09:47:32.697Z] 09:47:32     INFO -  TEST-START | org.mozilla.geckoview.test.PromptDelegateTest#selectTestSimple

Comment 1

[Intermittent Failures Robot]

1 failures in 2694 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=1770389&startday=2022-05-16&endday=2022-05-22&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=387484386&repo=autoland

Comment 4

[Intermittent Failures Robot]

2 failures in 2916 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1
• try: 1

Platform and build breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1770389&startday=2022-08-15&endday=2022-08-21&tree=all

Comment 5

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

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

Comment 6

[Treeherder Bug Filer]

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

Comment 7

[Intermittent Failures Robot]

1 failures in 2449 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=1770389&startday=2022-10-03&endday=2022-10-09&tree=all

Comment 8

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

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

Comment 9

[Treeherder Bug Filer]

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

Comment 10

[Intermittent Failures Robot]

1 failures in 3589 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=1770389&startday=2022-11-14&endday=2022-11-20&tree=all

Comment 11

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

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