Closed Bug 1790551 Opened 3 years ago Closed 3 years ago

Intermittent org.mozilla.geckoview.test.ScreenshotTest#screenshotScaledToSize | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms

Categories

(GeckoView :: General, defect, P5)

All
Android
defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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


[task 2022-09-13T06:21:45.193Z] 06:21:45     INFO -  TEST-START | org.mozilla.geckoview.test.ScreenshotTest#screenshotScaledToSize
[task 2022-09-13T06:22:15.435Z] 06:22:15     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=960
[task 2022-09-13T06:22:15.436Z] 06:22:15     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2022-09-13T06:22:15.436Z] 06:22:15     INFO -  org.mozilla.geckoview.test | Error in screenshotScaledToSize(org.mozilla.geckoview.test.ScreenshotTest):
[task 2022-09-13T06:22:15.436Z] 06:22:15     INFO -  org.mozilla.geckoview.test | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-09-13T06:22:15.436Z] 06:22:15     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-09-13T06:22:15.436Z] 06:22:15     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-09-13T06:22:15.437Z] 06:22:15     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-09-13T06:22:15.437Z] 06:22:15     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-09-13T06:22:15.437Z] 06:22:15     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1782)
[task 2022-09-13T06:22:15.438Z] 06:22:15     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1679)
[task 2022-09-13T06:22:15.438Z] 06:22:15     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1633)
[task 2022-09-13T06:22:15.438Z] 06:22:15     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.ScreenshotTest.screenshotScaledToSize(ScreenshotTest.kt:273)
[task 2022-09-13T06:22:15.439Z] 06:22:15     INFO -  org.mozilla.geckoview.test |
[task 2022-09-13T06:22:15.439Z] 06:22:15     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2022-09-13T06:22:15.439Z] 06:22:15     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=screenshotScaledToSize
[task 2022-09-13T06:22:15.439Z] 06:22:15     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.ScreenshotTest
[task 2022-09-13T06:22:15.440Z] 06:22:15     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-09-13T06:22:15.440Z] 06:22:15     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-09-13T06:22:15.440Z] 06:22:15     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-09-13T06:22:15.440Z] 06:22:15     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-09-13T06:22:15.441Z] 06:22:15     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-09-13T06:22:15.441Z] 06:22:15     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1782)
[task 2022-09-13T06:22:15.441Z] 06:22:15     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1679)
[task 2022-09-13T06:22:15.441Z] 06:22:15     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1633)
[task 2022-09-13T06:22:15.442Z] 06:22:15     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.ScreenshotTest.screenshotScaledToSize(ScreenshotTest.kt:273)
[task 2022-09-13T06:22:15.442Z] 06:22:15     INFO -  org.mozilla.geckoview.test |
[task 2022-09-13T06:22:15.442Z] 06:22:15     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=558
[task 2022-09-13T06:22:15.442Z] 06:22:15     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: -2
[task 2022-09-13T06:22:15.442Z] 06:22:15     INFO -  Printing logcat for test:
[task 2022-09-13T06:22:15.528Z] 06:22:15     INFO -  09-13 06:21:44.584 E/GeckoSessionTestRule( 3356): test_start 1f0befec-3ff2-40ff-89cf-b127eb38b1ec screenshotScaledToSize(org.mozilla.geckoview.test.ScreenshotTest)
[task 2022-09-13T06:22:15.528Z] 06:22:15     INFO -  09-13 06:21:44.584 E/GeckoSessionTestRule( 3356): before prepareStatement screenshotScaledToSize(org.mozilla.geckoview.test.ScreenshotTest)
[task 2022-09-13T06:22:15.528Z] 06:22:15     INFO -  09-13 06:21:44.584 D/GeckoViewSelectionAction( 3356): onDisable
<...>
[task 2022-09-13T06:22:15.539Z] 06:22:15     INFO -  09-13 06:21:51.260 D/ServiceAllocator( 3356): org.mozilla.gecko.process.GeckoChildProcessServices$tab30 updateBindings: IDLE priority, 6 importance, 0 successful binds, 0 failed binds, 2 successful unbinds
[task 2022-09-13T06:22:15.539Z] 06:22:15     INFO -  09-13 06:21:58.503 I/Gecko   ( 3356): nsWindow 0x74f6e6f60d00 destructor
[task 2022-09-13T06:22:15.539Z] 06:22:15     INFO -  09-13 06:21:58.503 I/Gecko   ( 3356): nsWindow 0x74f6dc842d00 destructor
[task 2022-09-13T06:22:15.540Z] 06:22:15     INFO -  09-13 06:22:14.807 E/GeckoSessionTestRule( 3356): Error
[task 2022-09-13T06:22:15.540Z] 06:22:15     INFO -  09-13 06:22:14.807 E/GeckoSessionTestRule( 3356): org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-09-13T06:22:15.540Z] 06:22:15     INFO -  09-13 06:22:14.807 E/GeckoSessionTestRule( 3356): 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-09-13T06:22:15.540Z] 06:22:15     INFO -  09-13 06:22:14.807 E/GeckoSessionTestRule( 3356): 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-09-13T06:22:15.540Z] 06:22:15     INFO -  09-13 06:22:14.807 E/GeckoSessionTestRule( 3356): 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-09-13T06:22:15.540Z] 06:22:15     INFO -  09-13 06:22:14.807 E/GeckoSessionTestRule( 3356): 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-09-13T06:22:15.540Z] 06:22:15     INFO -  09-13 06:22:14.807 E/GeckoSessionTestRule( 3356): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1782)
[task 2022-09-13T06:22:15.540Z] 06:22:15     INFO -  09-13 06:22:14.807 E/GeckoSessionTestRule( 3356): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1679)
[task 2022-09-13T06:22:15.541Z] 06:22:15     INFO -  09-13 06:22:14.807 E/GeckoSessionTestRule( 3356): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1633)
[task 2022-09-13T06:22:15.541Z] 06:22:15     INFO -  09-13 06:22:14.807 E/GeckoSessionTestRule( 3356): 	at org.mozilla.geckoview.test.ScreenshotTest.screenshotScaledToSize(ScreenshotTest.kt:273)
[task 2022-09-13T06:22:15.541Z] 06:22:15     INFO -  09-13 06:22:14.807 E/GeckoSessionTestRule( 3356): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2022-09-13T06:22:15.541Z] 06:22:15     INFO -  09-13 06:22:14.807 E/GeckoSessionTestRule( 3356): 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2022-09-13T06:22:15.541Z] 06:22:15     INFO -  09-13 06:22:14.807 E/GeckoSessionTestRule( 3356): 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2022-09-13T06:22:15.541Z] 06:22:15     INFO -  09-13 06:22:14.807 E/GeckoSessionTestRule( 3356): 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2022-09-13T06:22:15.541Z] 06:22:15     INFO -  09-13 06:22:14.807 E/GeckoSessionTestRule( 3356): 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2022-09-13T06:22:15.542Z] 06:22:15     INFO -  09-13 06:22:14.807 E/GeckoSessionTestRule( 3356): 	at org.junit.rules.ExpectedException$ExpectedExceptionStatement.evaluate(ExpectedException.java:239)
[task 2022-09-13T06:22:15.542Z] 06:22:15     INFO -  09-13 06:22:14.807 E/GeckoSessionTestRule( 3356): 	at org.junit.rules.Verifier$1.evaluate(Verifier.java:35)
[task 2022-09-13T06:22:15.542Z] 06:22:15     INFO -  09-13 06:22:14.807 E/GeckoSessionTestRule( 3356): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$org-mozilla-geckoview-test-rule-GeckoSessionTestRule$2(GeckoSessionTestRule.java:1467)
[task 2022-09-13T06:22:15.542Z] 06:22:15     INFO -  09-13 06:22:14.807 E/GeckoSessionTestRule( 3356): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2$$ExternalSyntheticLambda0.run(D8$$SyntheticClass)
[task 2022-09-13T06:22:15.542Z] 06:22:15     INFO -  09-13 06:22:14.807 E/GeckoSessionTestRule( 3356): 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:428)
[task 2022-09-13T06:22:15.542Z] 06:22:15     INFO -  09-13 06:22:14.807 E/GeckoSessionTestRule( 3356): 	at java.util.concurrent.FutureTask.run(FutureTask.java:237)
[task 2022-09-13T06:22:15.542Z] 06:22:15     INFO -  09-13 06:22:14.807 E/GeckoSessionTestRule( 3356): 	at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2022-09-13T06:22:15.543Z] 06:22:15     INFO -  09-13 06:22:14.807 E/GeckoSessionTestRule( 3356): 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-09-13T06:22:15.543Z] 06:22:15     INFO -  09-13 06:22:14.807 E/GeckoSessionTestRule( 3356): 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-09-13T06:22:15.543Z] 06:22:15     INFO -  09-13 06:22:14.807 E/GeckoSessionTestRule( 3356): 	at android.os.Looper.loop(Looper.java:154)
[task 2022-09-13T06:22:15.543Z] 06:22:15     INFO -  09-13 06:22:14.807 E/GeckoSessionTestRule( 3356): 	at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2022-09-13T06:22:15.543Z] 06:22:15     INFO -  09-13 06:22:14.807 E/GeckoSessionTestRule( 3356): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2022-09-13T06:22:15.543Z] 06:22:15     INFO -  09-13 06:22:14.807 E/GeckoSessionTestRule( 3356): 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2022-09-13T06:22:15.543Z] 06:22:15     INFO -  09-13 06:22:14.807 E/GeckoSessionTestRule( 3356): 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2022-09-13T06:22:15.543Z] 06:22:15     INFO -  09-13 06:22:14.808 D/EGL_emulation(15020): eglMakeCurrent: 0x74f728a7e560: ver 3 0 (tinfo 0x74f728b1d7c0)
[task 2022-09-13T06:22:15.544Z] 06:22:15     INFO -  09-13 06:22:14.814 D/GeckoViewContent( 3356): handleEvent: DOMWindowClose
[task 2022-09-13T06:22:15.544Z] 06:22:15     INFO -  09-13 06:22:14.819 D/GeckoViewConsole( 3356): onEvent GeckoView:WebExtension:List null
[task 2022-09-13T06:22:15.544Z] 06:22:15     INFO -  09-13 06:22:14.825 E/GeckoSessionTestRule( 3356): test_end c5ee677f-bc83-49bd-9e28-2d35f3d0f059 screenshotScaledToSize(org.mozilla.geckoview.test.ScreenshotTest)
[task 2022-09-13T06:22:15.544Z] 06:22:15  WARNING -  TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.ScreenshotTest#screenshotScaledToSize | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-09-13T06:22:15.544Z] 06:22:15     INFO -  TEST-INFO took 30338ms
[task 2022-09-13T06:22:15.544Z] 06:22:15     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=960
[task 2022-09-13T06:22:15.544Z] 06:22:15     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2022-09-13T06:22:15.544Z] 06:22:15     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2022-09-13T06:22:15.545Z] 06:22:15     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=screenShotScaledWithScale
[task 2022-09-13T06:22:15.545Z] 06:22:15     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.ScreenshotTest
[task 2022-09-13T06:22:15.545Z] 06:22:15     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=559
[task 2022-09-13T06:22:15.545Z] 06:22:15     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: 1
[task 2022-09-13T06:22:15.545Z] 06:22:15     INFO -  TEST-START | org.mozilla.geckoview.test.ScreenshotTest#screenShotScaledWithScale
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.