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

Status: RESOLVED FIXED

(GeckoView :: General, defect, P2)

Description

[Treeherder Bug Filer]

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

---

Comment 1

[Andreea Pavel [:apavel]]

Log snippet:

[task 2020-05-02T17:44:04.691Z] 17:44:04 INFO - TEST-START | org.mozilla.geckoview.test.SessionLifecycleTest.open_repeated
[task 2020-05-02T17:44:36.558Z] 17:44:36 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=718
[task 2020-05-02T17:44:36.558Z] 17:44:36 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2020-05-02T17:44:36.558Z] 17:44:36 INFO - org.mozilla.geckoview.test | Error in open_repeated(org.mozilla.geckoview.test.SessionLifecycleTest):
[task 2020-05-02T17:44:36.558Z] 17:44:36 INFO - org.mozilla.geckoview.test | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2020-05-02T17:44:36.558Z] 17:44:36 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:52)
[task 2020-05-02T17:44:36.562Z] 17:44:36 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2020-05-02T17:44:36.562Z] 17:44:36 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2020-05-02T17:44:36.562Z] 17:44:36 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:155)
[task 2020-05-02T17:44:36.562Z] 17:44:36 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.openSession(GeckoSessionTestRule.java:1142)
[task 2020-05-02T17:44:36.562Z] 17:44:36 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.BaseSessionTest.open(BaseSessionTest.kt:139)
[task 2020-05-02T17:44:36.563Z] 17:44:36 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.SessionLifecycleTest.open_repeated(SessionLifecycleTest.kt:60)
[task 2020-05-02T17:44:36.563Z] 17:44:36 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2020-05-02T17:44:36.563Z] 17:44:36 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2020-05-02T17:44:36.563Z] 17:44:36 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2020-05-02T17:44:36.563Z] 17:44:36 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2020-05-02T17:44:36.563Z] 17:44:36 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2020-05-02T17:44:36.564Z] 17:44:36 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1284)
[task 2020-05-02T17:44:36.564Z] 17:44:36 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$sIbRNaZJgAu-QrUVWSGD8JbPSWM.run(lambda)
[task 2020-05-02T17:44:36.564Z] 17:44:36 INFO - org.mozilla.geckoview.test | at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2020-05-02T17:44:36.564Z] 17:44:36 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2020-05-02T17:44:36.564Z] 17:44:36 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2020-05-02T17:44:36.564Z] 17:44:36 INFO - org.mozilla.geckoview.test | at android.os.Looper.loop(Looper.java:154)
[task 2020-05-02T17:44:36.564Z] 17:44:36 INFO - org.mozilla.geckoview.test | at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2020-05-02T17:44:36.564Z] 17:44:36 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2020-05-02T17:44:36.564Z] 17:44:36 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2020-05-02T17:44:36.565Z] 17:44:36 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2020-05-02T17:44:36.565Z] 17:44:36 INFO - org.mozilla.geckoview.test |
[task 2020-05-02T17:44:36.565Z] 17:44:36 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2020-05-02T17:44:36.565Z] 17:44:36 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=open_repeated
[task 2020-05-02T17:44:36.565Z] 17:44:36 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.SessionLifecycleTest
[task 2020-05-02T17:44:36.565Z] 17:44:36 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2020-05-02T17:44:36.565Z] 17:44:36 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:52)
[task 2020-05-02T17:44:36.565Z] 17:44:36 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2020-05-02T17:44:36.565Z] 17:44:36 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2020-05-02T17:44:36.565Z] 17:44:36 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:155)
[task 2020-05-02T17:44:36.566Z] 17:44:36 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.openSession(GeckoSessionTestRule.java:1142)
[task 2020-05-02T17:44:36.566Z] 17:44:36 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.BaseSessionTest.open(BaseSessionTest.kt:139)
[task 2020-05-02T17:44:36.566Z] 17:44:36 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.SessionLifecycleTest.open_repeated(SessionLifecycleTest.kt:60)
[task 2020-05-02T17:44:36.566Z] 17:44:36 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2020-05-02T17:44:36.567Z] 17:44:36 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2020-05-02T17:44:36.567Z] 17:44:36 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2020-05-02T17:44:36.568Z] 17:44:36 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2020-05-02T17:44:36.568Z] 17:44:36 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2020-05-02T17:44:36.568Z] 17:44:36 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1284)
[task 2020-05-02T17:44:36.569Z] 17:44:36 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$sIbRNaZJgAu-QrUVWSGD8JbPSWM.run(lambda)
[task 2020-05-02T17:44:36.570Z] 17:44:36 INFO - org.mozilla.geckoview.test | at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2020-05-02T17:44:36.570Z] 17:44:36 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2020-05-02T17:44:36.571Z] 17:44:36 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2020-05-02T17:44:36.571Z] 17:44:36 INFO - org.mozilla.geckoview.test | at android.os.Looper.loop(Looper.java:154)
[task 2020-05-02T17:44:36.571Z] 17:44:36 INFO - org.mozilla.geckoview.test | at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2020-05-02T17:44:36.571Z] 17:44:36 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2020-05-02T17:44:36.572Z] 17:44:36 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2020-05-02T17:44:36.572Z] 17:44:36 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2020-05-02T17:44:36.572Z] 17:44:36 INFO - org.mozilla.geckoview.test |
[task 2020-05-02T17:44:36.572Z] 17:44:36 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=561
[task 2020-05-02T17:44:36.573Z] 17:44:36 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: -2
[task 2020-05-02T17:44:36.573Z] 17:44:36 WARNING - TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.SessionLifecycleTest.open_repeated | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2020-05-02T17:44:36.573Z] 17:44:36 INFO - TEST-INFO took 31873ms
[task 2020-05-02T17:44:36.573Z] 17:44:36 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=718
[task 2020-05-02T17:44:36.574Z] 17:44:36 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2020-05-02T17:44:36.574Z] 17:44:36 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2020-05-02T17:44:36.574Z] 17:44:36 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=restoreInstanceState_noSessionOntoNoSession
[task 2020-05-02T17:44:36.574Z] 17:44:36 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.SessionLifecycleTest
[task 2020-05-02T17:44:36.575Z] 17:44:36 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=562
[task 2020-05-02T17:44:36.575Z] 17:44:36 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: 1

Comment 2

[(No longer employed by Mozilla) Aaron Klotz]

This is not a regression. gv-junit-e10s-multi is a new test suite.

Comment 3

[Intermittent Failures Robot]

16 failures in 5423 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 2
• autoland: 14

Platform breakdown:

• android-em-7-0-x86_64: 16

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1633600&startday=2020-04-27&endday=2020-05-03&tree=all

Comment 4

[Intermittent Failures Robot]

43 failures in 5276 pushes (0.008 failures/push) were associated with this bug in the last 7 days.

This is the #32 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• try: 1
• mozilla-central: 6
• autoland: 36

Platform breakdown:

• android-em-7-0-x86_64: 43

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1633600&startday=2020-05-04&endday=2020-05-10&tree=all

Comment 5

[Bogdan Tara[:bogdan_tara | bogdant]]

In the last 7 days there have been 37 occurrences on android-em-7-0-x86_64 opt.

Recent failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=302572948&repo=autoland&lineNumber=11222

[task 2020-05-16T10:42:05.912Z] 10:42:05     INFO -  TEST-START | org.mozilla.geckoview.test.SessionLifecycleTest.open_repeated
[task 2020-05-16T10:42:37.079Z] 10:42:37     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=722
[task 2020-05-16T10:42:37.079Z] 10:42:37     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2020-05-16T10:42:37.079Z] 10:42:37     INFO -  org.mozilla.geckoview.test | Error in open_repeated(org.mozilla.geckoview.test.SessionLifecycleTest):
[task 2020-05-16T10:42:37.079Z] 10:42:37     INFO -  org.mozilla.geckoview.test | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2020-05-16T10:42:37.079Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:52)
[task 2020-05-16T10:42:37.080Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2020-05-16T10:42:37.080Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2020-05-16T10:42:37.080Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:155)
[task 2020-05-16T10:42:37.080Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.openSession(GeckoSessionTestRule.java:1145)
[task 2020-05-16T10:42:37.080Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.BaseSessionTest.open(BaseSessionTest.kt:139)
[task 2020-05-16T10:42:37.080Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.SessionLifecycleTest.open_repeated(SessionLifecycleTest.kt:60)
[task 2020-05-16T10:42:37.081Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at java.lang.reflect.Method.invoke(Native Method)
[task 2020-05-16T10:42:37.081Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2020-05-16T10:42:37.081Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2020-05-16T10:42:37.081Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2020-05-16T10:42:37.081Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2020-05-16T10:42:37.082Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1293)
[task 2020-05-16T10:42:37.082Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$sIbRNaZJgAu-QrUVWSGD8JbPSWM.run(lambda)
[task 2020-05-16T10:42:37.082Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2020-05-16T10:42:37.082Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2020-05-16T10:42:37.082Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2020-05-16T10:42:37.082Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at android.os.Looper.loop(Looper.java:154)
[task 2020-05-16T10:42:37.083Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2020-05-16T10:42:37.083Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at java.lang.reflect.Method.invoke(Native Method)
[task 2020-05-16T10:42:37.083Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2020-05-16T10:42:37.083Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2020-05-16T10:42:37.083Z] 10:42:37     INFO -  org.mozilla.geckoview.test |
[task 2020-05-16T10:42:37.083Z] 10:42:37     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2020-05-16T10:42:37.084Z] 10:42:37     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=open_repeated
[task 2020-05-16T10:42:37.084Z] 10:42:37     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.SessionLifecycleTest
[task 2020-05-16T10:42:37.084Z] 10:42:37     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2020-05-16T10:42:37.084Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:52)
[task 2020-05-16T10:42:37.084Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2020-05-16T10:42:37.084Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2020-05-16T10:42:37.085Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:155)
[task 2020-05-16T10:42:37.085Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.openSession(GeckoSessionTestRule.java:1145)
[task 2020-05-16T10:42:37.085Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.BaseSessionTest.open(BaseSessionTest.kt:139)
[task 2020-05-16T10:42:37.085Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.SessionLifecycleTest.open_repeated(SessionLifecycleTest.kt:60)
[task 2020-05-16T10:42:37.085Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at java.lang.reflect.Method.invoke(Native Method)
[task 2020-05-16T10:42:37.085Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2020-05-16T10:42:37.086Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2020-05-16T10:42:37.086Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2020-05-16T10:42:37.086Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2020-05-16T10:42:37.086Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1293)
[task 2020-05-16T10:42:37.087Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$sIbRNaZJgAu-QrUVWSGD8JbPSWM.run(lambda)
[task 2020-05-16T10:42:37.087Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2020-05-16T10:42:37.087Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2020-05-16T10:42:37.087Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2020-05-16T10:42:37.087Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at android.os.Looper.loop(Looper.java:154)
[task 2020-05-16T10:42:37.087Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2020-05-16T10:42:37.087Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at java.lang.reflect.Method.invoke(Native Method)
[task 2020-05-16T10:42:37.088Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2020-05-16T10:42:37.088Z] 10:42:37     INFO -  org.mozilla.geckoview.test | 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2020-05-16T10:42:37.088Z] 10:42:37     INFO -  org.mozilla.geckoview.test |
[task 2020-05-16T10:42:37.088Z] 10:42:37     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=561
[task 2020-05-16T10:42:37.088Z] 10:42:37     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: -2
[task 2020-05-16T10:42:37.088Z] 10:42:37  WARNING -  TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.SessionLifecycleTest.open_repeated | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2020-05-16T10:42:37.089Z] 10:42:37     INFO -  TEST-INFO took 31173ms

Comment 6

[Intermittent Failures Robot]

39 failures in 5488 pushes (0.007 failures/push) were associated with this bug in the last 7 days.

This is the #38 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 31
• mozilla-beta: 1
• try: 2
• mozilla-central: 5

Platform breakdown:

• android-em-7-0-x86_64: 39

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1633600&startday=2020-05-11&endday=2020-05-17&tree=all

Comment 7

[Intermittent Failures Robot]

20 failures in 718 pushes (0.028 failures/push) were associated with this bug yesterday.

Repository breakdown:

• mozilla-central: 2
• autoland: 16
• try: 1
• mozilla-beta: 1

Platform breakdown:

• android-em-7-0-x86_64: 20

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1633600&startday=2020-05-18&endday=2020-05-18&tree=all

Comment 8

[Dorel Luca [:dluca]]

This bug failed 30 times in the last 7 days. Occurs on android-em-7-0-x86_64 on opt and debug build types.

Recent log:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=303439675&repo=autoland&lineNumber=11261

Emily: Can you please take a look at this bug?

Comment 9

[Intermittent Failures Robot]

35 failures in 4727 pushes (0.007 failures/push) were associated with this bug in the last 7 days.

This is the #40 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• mozilla-central: 6
• autoland: 24
• try: 4
• mozilla-beta: 1

Platform breakdown:

• android-em-7-0-x86_64: 34
• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1633600&startday=2020-05-18&endday=2020-05-24&tree=all

Comment 10

[Intermittent Failures Robot]

23 failures in 5458 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 5
• autoland: 17
• mozilla-release: 1

Platform breakdown:

• android-em-7-0-x86_64: 23

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1633600&startday=2020-05-25&endday=2020-05-31&tree=all

Comment 11

[Intermittent Failures Robot]

24 failures in 4949 pushes (0.005 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 19
• mozilla-central: 5

Platform breakdown:

• android-em-7-0-x86_64: 24

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1633600&startday=2020-06-01&endday=2020-06-07&tree=all

Comment 12

[(No longer employed by Mozilla) Aaron Klotz]

Essentially, the problem with this test is that it is slow; so slow, in fact, that it can blow the 30s timeout that we use by default.

When the test passes, it typically takes 21+ seconds to finish with an opt GV running in an emulator. Considering the fact that the test is simply to close and open a session five times in succession, and then reload, we should profile this and see what we can do.

(I was able to run a repeating loop with this test without a single issue once I bumped the timeout above 30s. I suppose that could be a short-term fix, but I would say that these timeouts do indicate a perf issue.)

Comment 13

[Intermittent Failures Robot]

34 failures in 5211 pushes (0.007 failures/push) were associated with this bug in the last 7 days.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 33
• mozilla-central: 1

Platform breakdown:

• android-em-7-0-x86_64: 22
• android-em-7-0-x86_64-shippable: 12

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1633600&startday=2020-06-08&endday=2020-06-14&tree=all

Comment 14

[(No longer employed by Mozilla) Aaron Klotz]

I think we can call this one resolved.

Comment 15

[[ex-Mozilla] Agi Sferro | :agi]

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