1773188 - Intermittent org.mozilla.geckoview.test.SelectionActionDelegateTest#pagehide[#designmode] | 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=380536825&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/RiefKWWpThmvCfFSkQpSWg/runs/0/artifacts/public/logs/live_backing.log

---

[task 2022-06-08T00:09:48.755Z] 00:09:48     INFO -  TEST-START | org.mozilla.geckoview.test.SelectionActionDelegateTest#pagehide[#designmode]
[task 2022-06-08T00:10:19.399Z] 00:10:19     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=925
[task 2022-06-08T00:10:19.399Z] 00:10:19     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2022-06-08T00:10:19.399Z] 00:10:19     INFO -  org.mozilla.geckoview.test | Error in pagehide[#designmode](org.mozilla.geckoview.test.SelectionActionDelegateTest):
[task 2022-06-08T00:10:19.399Z] 00:10:19     INFO -  org.mozilla.geckoview.test | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-06-08T00:10:19.400Z] 00:10:19     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-06-08T00:10:19.400Z] 00:10:19     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-06-08T00:10:19.400Z] 00:10:19     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-06-08T00:10:19.400Z] 00:10:19     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-06-08T00:10:19.401Z] 00:10:19     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1775)
[task 2022-06-08T00:10:19.401Z] 00:10:19     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForPageStops(GeckoSessionTestRule.java:1531)
[task 2022-06-08T00:10:19.401Z] 00:10:19     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForPageStop(GeckoSessionTestRule.java:1506)
[task 2022-06-08T00:10:19.402Z] 00:10:19     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.BaseSessionTest.waitForPageStop(BaseSessionTest.kt:186)
[task 2022-06-08T00:10:19.402Z] 00:10:19     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.SelectionActionDelegateTest.testThat(SelectionActionDelegateTest.kt:271)
[task 2022-06-08T00:10:19.402Z] 00:10:19     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.SelectionActionDelegateTest.pagehide(SelectionActionDelegateTest.kt:217)
[task 2022-06-08T00:10:19.402Z] 00:10:19     INFO -  org.mozilla.geckoview.test |
[task 2022-06-08T00:10:19.403Z] 00:10:19     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2022-06-08T00:10:19.403Z] 00:10:19     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=pagehide[#designmode]
[task 2022-06-08T00:10:19.403Z] 00:10:19     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.SelectionActionDelegateTest
[task 2022-06-08T00:10:19.403Z] 00:10:19     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-06-08T00:10:19.404Z] 00:10:19     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-06-08T00:10:19.404Z] 00:10:19     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-06-08T00:10:19.404Z] 00:10:19     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-06-08T00:10:19.404Z] 00:10:19     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-06-08T00:10:19.404Z] 00:10:19     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1775)
[task 2022-06-08T00:10:19.405Z] 00:10:19     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForPageStops(GeckoSessionTestRule.java:1531)
[task 2022-06-08T00:10:19.405Z] 00:10:19     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForPageStop(GeckoSessionTestRule.java:1506)
[task 2022-06-08T00:10:19.405Z] 00:10:19     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.BaseSessionTest.waitForPageStop(BaseSessionTest.kt:186)
[task 2022-06-08T00:10:19.405Z] 00:10:19     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.SelectionActionDelegateTest.testThat(SelectionActionDelegateTest.kt:271)
[task 2022-06-08T00:10:19.406Z] 00:10:19     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.SelectionActionDelegateTest.pagehide(SelectionActionDelegateTest.kt:217)
[task 2022-06-08T00:10:19.406Z] 00:10:19     INFO -  org.mozilla.geckoview.test |
[task 2022-06-08T00:10:19.406Z] 00:10:19     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=652
[task 2022-06-08T00:10:19.406Z] 00:10:19     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: -2
[task 2022-06-08T00:10:19.406Z] 00:10:19     INFO -  Printing logcat for test:
[task 2022-06-08T00:10:19.470Z] 00:10:19     INFO -  06-08 00:09:48.473 E/GeckoSessionTestRule( 3421): test_start 1f0befec-3ff2-40ff-89cf-b127eb38b1ec pagehide[#designmode](org.mozilla.geckoview.test.SelectionActionDelegateTest)
[task 2022-06-08T00:10:19.470Z] 00:10:19     INFO -  06-08 00:09:48.474 E/GeckoSessionTestRule( 3421): before prepareStatement pagehide[#designmode](org.mozilla.geckoview.test.SelectionActionDelegateTest)
[task 2022-06-08T00:10:19.471Z] 00:10:19     INFO -  06-08 00:09:48.475 I/WindowManager( 1658): Destroying surface Surface(name=Starting org.mozilla.geckoview.test) called by com.android.server.wm.WindowStateAnimator.destroySurface:2014 com.android.server.wm.WindowStateAnimator.destroySurfaceLocked:881 com.android.server.wm.WindowState.removeLocked:1449 com.android.server.wm.WindowManagerService.removeWindowInnerLocked:2478 com.android.server.wm.WindowManagerService.removeWindowLocked:2436 com.android.server.wm.WindowManagerService.removeWindowLocked:2305 com.android.server.wm.WindowManagerService.removeWindow:2300 com.android.server.wm.Session.remove:193
[task 2022-06-08T00:10:19.471Z] 00:10:19     INFO -  06-08 00:09:48.475 D/GeckoViewStartup( 3421): onEvent GeckoView:StorageDelegate:Attached
<...>
[task 2022-06-08T00:10:19.491Z] 00:10:19     INFO -  06-08 00:10:18.830 E/GeckoSessionTestRule( 3421): 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-06-08T00:10:19.491Z] 00:10:19     INFO -  06-08 00:10:18.830 E/GeckoSessionTestRule( 3421): 	at android.os.Looper.loop(Looper.java:154)
[task 2022-06-08T00:10:19.491Z] 00:10:19     INFO -  06-08 00:10:18.830 E/GeckoSessionTestRule( 3421): 	at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2022-06-08T00:10:19.491Z] 00:10:19     INFO -  06-08 00:10:18.830 E/GeckoSessionTestRule( 3421): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2022-06-08T00:10:19.492Z] 00:10:19     INFO -  06-08 00:10:18.830 E/GeckoSessionTestRule( 3421): 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2022-06-08T00:10:19.492Z] 00:10:19     INFO -  06-08 00:10:18.830 E/GeckoSessionTestRule( 3421): 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2022-06-08T00:10:19.492Z] 00:10:19     INFO -  06-08 00:10:18.831 D/EGL_emulation(24497): eglMakeCurrent: 0x7cfeb07ba260: ver 3 0 (tinfo 0x7cfebb0f67e0)
[task 2022-06-08T00:10:19.492Z] 00:10:19     INFO -  06-08 00:10:18.833 D/GeckoViewContent( 3421): handleEvent: DOMWindowClose
[task 2022-06-08T00:10:19.492Z] 00:10:19     INFO -  06-08 00:10:18.834 D/GeckoViewConsole( 3421): onEvent GeckoView:WebExtension:List null
[task 2022-06-08T00:10:19.492Z] 00:10:19     INFO -  06-08 00:10:18.835 E/GeckoSessionTestRule( 3421): test_end c5ee677f-bc83-49bd-9e28-2d35f3d0f059 pagehide[#designmode](org.mozilla.geckoview.test.SelectionActionDelegateTest)
[task 2022-06-08T00:10:19.492Z] 00:10:19  WARNING -  TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.SelectionActionDelegateTest#pagehide[#designmode] | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-06-08T00:10:19.492Z] 00:10:19     INFO -  TEST-INFO took 30715ms
[task 2022-06-08T00:10:19.500Z] 00:10:19     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=925
[task 2022-06-08T00:10:19.500Z] 00:10:19     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2022-06-08T00:10:19.500Z] 00:10:19     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2022-06-08T00:10:19.500Z] 00:10:19     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=request[#designmode]
[task 2022-06-08T00:10:19.501Z] 00:10:19     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.SelectionActionDelegateTest
[task 2022-06-08T00:10:19.501Z] 00:10:19     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=653
[task 2022-06-08T00:10:19.501Z] 00:10:19     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: 1
[task 2022-06-08T00:10:19.501Z] 00:10:19     INFO -  TEST-START | org.mozilla.geckoview.test.SelectionActionDelegateTest#request[#designmode]

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:

• 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=1773188&startday=2022-06-06&endday=2022-06-12&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=387421353&repo=mozilla-central

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 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=1773188&startday=2022-08-15&endday=2022-08-21&tree=all

Comment 5

[Intermittent Failures Robot]

1 failures in 3293 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=1773188&startday=2022-08-22&endday=2022-08-28&tree=all

Comment 6

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

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