Closed Bug 1693461 Opened 4 years ago Closed 2 years ago

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

Categories

(GeckoView :: General, defect, P5)

Unspecified
All
defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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


INFO -  TEST-START | org.mozilla.geckoview.test.SessionLifecycleTest.asyncScriptsSuspendedWhileInactive
[task 2021-02-18T04:58:57.791Z] 04:58:57     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=780
[task 2021-02-18T04:58:57.791Z] 04:58:57     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2021-02-18T04:58:57.791Z] 04:58:57     INFO -  org.mozilla.geckoview.test | Error in asyncScriptsSuspendedWhileInactive(org.mozilla.geckoview.test.SessionLifecycleTest):
[task 2021-02-18T04:58:57.791Z] 04:58:57     INFO -  org.mozilla.geckoview.test | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2021-02-18T04:58:57.791Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:52)
[task 2021-02-18T04:58:57.791Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2021-02-18T04:58:57.792Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2021-02-18T04:58:57.792Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:155)
[task 2021-02-18T04:58:57.792Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForMessage(GeckoSessionTestRule.java:1927)
[task 2021-02-18T04:58:57.792Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.evaluateJS(GeckoSessionTestRule.java:1912)
[task 2021-02-18T04:58:57.792Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.BaseSessionTest.evaluateJS(BaseSessionTest.kt:178)
[task 2021-02-18T04:58:57.792Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.SessionLifecycleTest.computeRequestAnimationFrameRate(SessionLifecycleTest.kt:127)
[task 2021-02-18T04:58:57.793Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.SessionLifecycleTest.asyncScriptsSuspendedWhileInactive(SessionLifecycleTest.kt:175)
[task 2021-02-18T04:58:57.793Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at java.lang.reflect.Method.invoke(Native Method)
[task 2021-02-18T04:58:57.793Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2021-02-18T04:58:57.793Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2021-02-18T04:58:57.793Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2021-02-18T04:58:57.793Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2021-02-18T04:58:57.793Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1313)
[task 2021-02-18T04:58:57.794Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$sIbRNaZJgAu-QrUVWSGD8JbPSWM.run(lambda)
[task 2021-02-18T04:58:57.794Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2021-02-18T04:58:57.794Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2021-02-18T04:58:57.794Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2021-02-18T04:58:57.794Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at android.os.Looper.loop(Looper.java:154)
[task 2021-02-18T04:58:57.794Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2021-02-18T04:58:57.794Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at java.lang.reflect.Method.invoke(Native Method)
[task 2021-02-18T04:58:57.795Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2021-02-18T04:58:57.795Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2021-02-18T04:58:57.795Z] 04:58:57     INFO -  org.mozilla.geckoview.test |
[task 2021-02-18T04:58:57.795Z] 04:58:57     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2021-02-18T04:58:57.795Z] 04:58:57     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=asyncScriptsSuspendedWhileInactive
[task 2021-02-18T04:58:57.795Z] 04:58:57     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.SessionLifecycleTest
[task 2021-02-18T04:58:57.795Z] 04:58:57     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2021-02-18T04:58:57.795Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:52)
[task 2021-02-18T04:58:57.795Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2021-02-18T04:58:57.796Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2021-02-18T04:58:57.796Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:155)
[task 2021-02-18T04:58:57.796Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForMessage(GeckoSessionTestRule.java:1927)
[task 2021-02-18T04:58:57.796Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.evaluateJS(GeckoSessionTestRule.java:1912)
[task 2021-02-18T04:58:57.796Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.BaseSessionTest.evaluateJS(BaseSessionTest.kt:178)
[task 2021-02-18T04:58:57.796Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.SessionLifecycleTest.computeRequestAnimationFrameRate(SessionLifecycleTest.kt:127)
[task 2021-02-18T04:58:57.797Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.SessionLifecycleTest.asyncScriptsSuspendedWhileInactive(SessionLifecycleTest.kt:175)
[task 2021-02-18T04:58:57.797Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at java.lang.reflect.Method.invoke(Native Method)
[task 2021-02-18T04:58:57.797Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2021-02-18T04:58:57.797Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2021-02-18T04:58:57.797Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2021-02-18T04:58:57.797Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2021-02-18T04:58:57.797Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1313)
[task 2021-02-18T04:58:57.798Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$sIbRNaZJgAu-QrUVWSGD8JbPSWM.run(lambda)
[task 2021-02-18T04:58:57.798Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2021-02-18T04:58:57.798Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2021-02-18T04:58:57.798Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2021-02-18T04:58:57.798Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at android.os.Looper.loop(Looper.java:154)
[task 2021-02-18T04:58:57.798Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2021-02-18T04:58:57.798Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at java.lang.reflect.Method.invoke(Native Method)
[task 2021-02-18T04:58:57.798Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2021-02-18T04:58:57.798Z] 04:58:57     INFO -  org.mozilla.geckoview.test | 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2021-02-18T04:58:57.799Z] 04:58:57     INFO -  org.mozilla.geckoview.test |
INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=583
[task 2021-02-18T04:58:57.799Z] 04:58:57     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: -2
[task 2021-02-18T04:58:57.799Z] 04:58:57  WARNING -  TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.SessionLifecycleTest.asyncScriptsSuspendedWhileInactive | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2021-02-18T04:58:57.799Z] 04:58:57     INFO -  TEST-INFO took 31765ms
[task 2021-02-18T04:58:57.799Z] 04:58:57     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=780
[task 2021-02-18T04:58:57.799Z] 04:58:57     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2021-02-18T04:58:57.799Z] 04:58:57     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2021-02-18T04:58:57.800Z] 04:58:57     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=restoreRuntimeSettings_noSession
[task 2021-02-18T04:58:57.800Z] 04:58:57     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.SessionLifecycleTest
[task 2021-02-18T04:58:57.800Z] 04:58:57     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=584
[task 2021-02-18T04:58:57.800Z] 04:58:57     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: 1
[task 2021-02-18T04:58:57.800Z] 04:58:57     INFO -  TEST-START | org.mozilla.geckoview.test.SessionLifecycleTest.restoreRuntimeSettings_noSession```

6c6f5da9-bf33-4082-a19d-7d99e8083d97 Mass rename intermittents due to Bug 1723034.

Summary: Intermittent org.mozilla.geckoview.test.SessionLifecycleTest.asyncScriptsSuspendedWhileInactive | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms → Intermittent org.mozilla.geckoview.test.SessionLifecycleTest#asyncScriptsSuspendedWhileInactive | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 3 years ago2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 2 years ago2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 2 years ago2 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.