1773289 - Intermittent org.mozilla.geckoview.test.SelectionActionDelegateTest#collapseToEnd[#input] | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms

Status: RESOLVED INCOMPLETE

(GeckoView :: IME, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2022-06-07T19:52:20.662Z] 19:52:20     INFO -  06-07 19:51:49.835 D/ServiceAllocator( 3340): org.mozilla.gecko.process.GeckoChildProcessServices$tab39 updateBindings: IDLE priority, 6 importance, 0 successful binds, 0 failed binds, 2 successful unbinds
[task 2022-06-07T19:52:20.662Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): Error
[task 2022-06-07T19:52:20.662Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-06-07T19:52:20.662Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-06-07T19:52:20.662Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-06-07T19:52:20.662Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-06-07T19:52:20.662Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-06-07T19:52:20.662Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1775)
[task 2022-06-07T19:52:20.663Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForPageStops(GeckoSessionTestRule.java:1531)
[task 2022-06-07T19:52:20.663Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForPageStop(GeckoSessionTestRule.java:1506)
[task 2022-06-07T19:52:20.663Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at org.mozilla.geckoview.test.BaseSessionTest.waitForPageStop(BaseSessionTest.kt:184)
[task 2022-06-07T19:52:20.663Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at org.mozilla.geckoview.test.SelectionActionDelegateTest.testThat(SelectionActionDelegateTest.kt:271)
[task 2022-06-07T19:52:20.663Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at org.mozilla.geckoview.test.SelectionActionDelegateTest.access$testThat(SelectionActionDelegateTest.kt:37)
[task 2022-06-07T19:52:20.663Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at org.mozilla.geckoview.test.SelectionActionDelegateTest$collapseToEnd$1.invoke(SelectionActionDelegateTest.kt:211)
[task 2022-06-07T19:52:20.663Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at org.mozilla.geckoview.test.SelectionActionDelegateTest$collapseToEnd$1.invoke(SelectionActionDelegateTest.kt:210)
[task 2022-06-07T19:52:20.664Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at org.mozilla.geckoview.test.SelectionActionDelegateTest.assumingEditable(SelectionActionDelegateTest.kt:393)
[task 2022-06-07T19:52:20.664Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at org.mozilla.geckoview.test.SelectionActionDelegateTest.collapseToEnd(SelectionActionDelegateTest.kt:210)
[task 2022-06-07T19:52:20.664Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2022-06-07T19:52:20.664Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2022-06-07T19:52:20.664Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2022-06-07T19:52:20.664Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2022-06-07T19:52:20.664Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2022-06-07T19:52:20.664Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
[task 2022-06-07T19:52:20.664Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$org-mozilla-geckoview-test-rule-GeckoSessionTestRule$2(GeckoSessionTestRule.java:1460)
[task 2022-06-07T19:52:20.664Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2$$ExternalSyntheticLambda0.run(Unknown Source)
[task 2022-06-07T19:52:20.665Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:428)
[task 2022-06-07T19:52:20.665Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at java.util.concurrent.FutureTask.run(FutureTask.java:237)
[task 2022-06-07T19:52:20.665Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2022-06-07T19:52:20.665Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-06-07T19:52:20.665Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-06-07T19:52:20.665Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at android.os.Looper.loop(Looper.java:154)
[task 2022-06-07T19:52:20.665Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2022-06-07T19:52:20.665Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2022-06-07T19:52:20.666Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2022-06-07T19:52:20.666Z] 19:52:20     INFO -  06-07 19:52:19.709 E/GeckoSessionTestRule( 3340): 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2022-06-07T19:52:20.666Z] 19:52:20     INFO -  06-07 19:52:19.710 D/EGL_emulation(24368): eglMakeCurrent: 0x7a62ca78c300: ver 3 0 (tinfo 0x7a62ca79e300)
[task 2022-06-07T19:52:20.666Z] 19:52:20     INFO -  06-07 19:52:19.713 D/GeckoViewContent( 3340): handleEvent: DOMWindowClose
[task 2022-06-07T19:52:20.666Z] 19:52:20     INFO -  06-07 19:52:19.715 D/GeckoViewConsole( 3340): onEvent GeckoView:WebExtension:List null
[task 2022-06-07T19:52:20.666Z] 19:52:20     INFO -  06-07 19:52:19.719 E/GeckoSessionTestRule( 3340): test_end c5ee677f-bc83-49bd-9e28-2d35f3d0f059 collapseToEnd[#input](org.mozilla.geckoview.test.SelectionActionDelegateTest)
[task 2022-06-07T19:52:20.666Z] 19:52:20  WARNING -  TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.SelectionActionDelegateTest#collapseToEnd[#input] | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-06-07T19:52:20.666Z] 19:52:20     INFO -  TEST-INFO took 30614ms
[task 2022-06-07T19:52:20.684Z] 19:52:20     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=918
[task 2022-06-07T19:52:20.684Z] 19:52:20     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2022-06-07T19:52:20.684Z] 19:52:20     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2022-06-07T19:52:20.684Z] 19:52:20     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=deactivate[#input]
[task 2022-06-07T19:52:20.685Z] 19:52:20     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.SelectionActionDelegateTest
[task 2022-06-07T19:52:20.685Z] 19:52:20     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=566
[task 2022-06-07T19:52:20.685Z] 19:52:20     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: 1

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 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=1773289&startday=2022-06-06&endday=2022-06-12&tree=all

Comment 2

[Intermittent Failures Robot]

1 failures in 3075 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=1773289&startday=2022-06-20&endday=2022-06-26&tree=all

Comment 3

[Chris Peterson [:cpeterson]]

Moving some input bugs to the new GeckoView::IME component.

Comment 4

[Intermittent Failures Robot]

1 failures in 3072 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=1773289&startday=2022-07-04&endday=2022-07-10&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=394964962&repo=autoland

Comment 7

[Intermittent Failures Robot]

1 failures in 3626 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=1773289&startday=2022-10-31&endday=2022-11-06&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.