Closed Bug 1609423 Opened 5 years ago Closed 5 years ago

Intermittent TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.LoginStorageDelegateTest#loginUpdateAccept | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms

Categories

(GeckoView :: General, defect)

Product:
GeckoView
Component:
General
Platform:
Unspecified
All
Type:
defect
Priority:
Not set
Severity:
normal

Tracking

(Not tracked)

Status:
RESOLVED FIXED

People

(Reporter: malexandru, Assigned: esawin)

Details

(Keywords: intermittent-failure)

Parsed log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=285042796&repo=autoland&lineNumber=5025

Raw log: https://firefoxci.taskcluster-artifacts.net/UkGdwFs2Tzmx7aFc0orcbw/0/public/logs/live_backing.log

[task 2020-01-15T14:17:47.533Z] 14:17:47 INFO - TEST-START | org.mozilla.geckoview.test.LoginStorageDelegateTest.loginUpdateAccept
[task 2020-01-15T14:18:18.086Z] 14:18:18 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=624
[task 2020-01-15T14:18:18.086Z] 14:18:18 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2020-01-15T14:18:18.086Z] 14:18:18 INFO - org.mozilla.geckoview.test | Error in loginUpdateAccept(org.mozilla.geckoview.test.LoginStorageDelegateTest):
[task 2020-01-15T14:18:18.086Z] 14:18:18 INFO - org.mozilla.geckoview.test | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2020-01-15T14:18:18.086Z] 14:18:18 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:52)
[task 2020-01-15T14:18:18.086Z] 14:18:18 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2020-01-15T14:18:18.087Z] 14:18:18 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2020-01-15T14:18:18.087Z] 14:18:18 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:155)
[task 2020-01-15T14:18:18.087Z] 14:18:18 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForMessage(GeckoSessionTestRule.java:1888)
[task 2020-01-15T14:18:18.087Z] 14:18:18 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.evaluateJS(GeckoSessionTestRule.java:1884)
[task 2020-01-15T14:18:18.087Z] 14:18:18 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.BaseSessionTest.evaluateJS(BaseSessionTest.kt:168)
[task 2020-01-15T14:18:18.087Z] 14:18:18 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.LoginStorageDelegateTest.loginUpdateAccept(LoginStorageDelegateTest.kt:422)
[task 2020-01-15T14:18:18.087Z] 14:18:18 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2020-01-15T14:18:18.087Z] 14:18:18 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2020-01-15T14:18:18.087Z] 14:18:18 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2020-01-15T14:18:18.088Z] 14:18:18 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2020-01-15T14:18:18.088Z] 14:18:18 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2020-01-15T14:18:18.088Z] 14:18:18 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1298)
[task 2020-01-15T14:18:18.088Z] 14:18:18 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$sIbRNaZJgAu-QrUVWSGD8JbPSWM.run(lambda)
[task 2020-01-15T14:18:18.088Z] 14:18:18 INFO - org.mozilla.geckoview.test | at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2020-01-15T14:18:18.088Z] 14:18:18 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2020-01-15T14:18:18.088Z] 14:18:18 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2020-01-15T14:18:18.088Z] 14:18:18 INFO - org.mozilla.geckoview.test | at android.os.Looper.loop(Looper.java:154)
[task 2020-01-15T14:18:18.089Z] 14:18:18 INFO - org.mozilla.geckoview.test | at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2020-01-15T14:18:18.089Z] 14:18:18 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2020-01-15T14:18:18.089Z] 14:18:18 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2020-01-15T14:18:18.089Z] 14:18:18 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2020-01-15T14:18:18.089Z] 14:18:18 INFO - org.mozilla.geckoview.test |
[task 2020-01-15T14:18:18.089Z] 14:18:18 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2020-01-15T14:18:18.089Z] 14:18:18 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=loginUpdateAccept
[task 2020-01-15T14:18:18.089Z] 14:18:18 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.LoginStorageDelegateTest
[task 2020-01-15T14:18:18.090Z] 14:18:18 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2020-01-15T14:18:18.090Z] 14:18:18 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:52)
[task 2020-01-15T14:18:18.090Z] 14:18:18 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2020-01-15T14:18:18.090Z] 14:18:18 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2020-01-15T14:18:18.090Z] 14:18:18 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:155)
[task 2020-01-15T14:18:18.090Z] 14:18:18 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForMessage(GeckoSessionTestRule.java:1888)
[task 2020-01-15T14:18:18.090Z] 14:18:18 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.evaluateJS(GeckoSessionTestRule.java:1884)
[task 2020-01-15T14:18:18.090Z] 14:18:18 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.BaseSessionTest.evaluateJS(BaseSessionTest.kt:168)
[task 2020-01-15T14:18:18.090Z] 14:18:18 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.LoginStorageDelegateTest.loginUpdateAccept(LoginStorageDelegateTest.kt:422)
[task 2020-01-15T14:18:18.091Z] 14:18:18 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2020-01-15T14:18:18.091Z] 14:18:18 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2020-01-15T14:18:18.091Z] 14:18:18 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2020-01-15T14:18:18.091Z] 14:18:18 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2020-01-15T14:18:18.091Z] 14:18:18 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2020-01-15T14:18:18.091Z] 14:18:18 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1298)
[task 2020-01-15T14:18:18.091Z] 14:18:18 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$sIbRNaZJgAu-QrUVWSGD8JbPSWM.run(lambda)
[task 2020-01-15T14:18:18.091Z] 14:18:18 INFO - org.mozilla.geckoview.test | at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2020-01-15T14:18:18.092Z] 14:18:18 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2020-01-15T14:18:18.092Z] 14:18:18 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2020-01-15T14:18:18.092Z] 14:18:18 INFO - org.mozilla.geckoview.test | at android.os.Looper.loop(Looper.java:154)
[task 2020-01-15T14:18:18.092Z] 14:18:18 INFO - org.mozilla.geckoview.test | at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2020-01-15T14:18:18.092Z] 14:18:18 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2020-01-15T14:18:18.092Z] 14:18:18 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2020-01-15T14:18:18.092Z] 14:18:18 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2020-01-15T14:18:18.092Z] 14:18:18 INFO - org.mozilla.geckoview.test |
[task 2020-01-15T14:18:18.092Z] 14:18:18 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=218
[task 2020-01-15T14:18:18.092Z] 14:18:18 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: -2
[task 2020-01-15T14:18:18.093Z] 14:18:18 WARNING - TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.LoginStorageDelegateTest.loginUpdateAccept | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2020-01-15T14:18:18.093Z] 14:18:18 INFO - TEST-INFO took 30558ms
[task 2020-01-15T14:18:18.093Z] 14:18:18 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=624
[task 2020-01-15T14:18:18.093Z] 14:18:18 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2020-01-15T14:18:18.093Z] 14:18:18 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2020-01-15T14:18:18.093Z] 14:18:18 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=loginSaveDismiss
[task 2020-01-15T14:18:18.093Z] 14:18:18 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.LoginStorageDelegateTest
[task 2020-01-15T14:18:18.093Z] 14:18:18 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=219
[task 2020-01-15T14:18:18.094Z] 14:18:18 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: 1
[task 2020-01-15T14:18:18.094Z] 14:18:18 INFO - TEST-START | org.mozilla.geckoview.test.LoginStorageDelegateTest.loginSaveDismiss

Eugen,
This looks to be related to Bug 1608514, could you please take a look?

Flags: needinfo?(esawin)

Fixed by backout

https://hg.mozilla.org/integration/autoland/rev/e7f80b0307ea5e7cd146f726dea501e0241e189a

Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → FIXED
Flags: needinfo?(esawin)
Assignee: nobody → esawin

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

Summary: Intermittent TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.LoginStorageDelegateTest.loginUpdateAccept | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms → Intermittent TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.LoginStorageDelegateTest#loginUpdateAccept | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
You need to log in before you can comment on or make changes to this bug.