1678275 - Intermittent org.mozilla.geckoview.test.MediaSessionTest#fullscreenVideoElementMetadata | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms

Status: RESOLVED DUPLICATE of bug 1678357

(GeckoView :: Media, defect, P5)

Description

[Treeherder Bug Filer]

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

---

"INFO -  TEST-START | org.mozilla.geckoview.test.MediaSessionTest.fullscreenVideoElementMetadata
[task 2020-11-19T11:04:06.918Z] 11:04:06     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=767
[task 2020-11-19T11:04:06.918Z] 11:04:06     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2020-11-19T11:04:06.919Z] 11:04:06     INFO -  org.mozilla.geckoview.test | Error in fullscreenVideoElementMetadata(org.mozilla.geckoview.test.MediaSessionTest):
[task 2020-11-19T11:04:06.919Z] 11:04:06     INFO -  org.mozilla.geckoview.test | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2020-11-19T11:04:06.919Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:52)
[task 2020-11-19T11:04:06.920Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2020-11-19T11:04:06.920Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2020-11-19T11:04:06.920Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:155)
[task 2020-11-19T11:04:06.921Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForResult(UiThreadUtils.java:80)
[task 2020-11-19T11:04:06.921Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForResult(GeckoSessionTestRule.java:2312)
[task 2020-11-19T11:04:06.921Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.MediaSessionTest.fullscreenVideoElementMetadata(MediaSessionTest.kt:800)
[task 2020-11-19T11:04:06.921Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at java.lang.reflect.Method.invoke(Native Method)
[task 2020-11-19T11:04:06.922Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2020-11-19T11:04:06.922Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2020-11-19T11:04:06.922Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2020-11-19T11:04:06.922Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2020-11-19T11:04:06.923Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at androidx.test.internal.runner.junit4.statement.RunBefores.evaluate(RunBefores.java:80)
[task 2020-11-19T11:04:06.923Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at androidx.test.internal.runner.junit4.statement.RunAfters.evaluate(RunAfters.java:61)
[task 2020-11-19T11:04:06.923Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1313)
[task 2020-11-19T11:04:06.923Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$sIbRNaZJgAu-QrUVWSGD8JbPSWM.run(lambda)
[task 2020-11-19T11:04:06.924Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2020-11-19T11:04:06.924Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2020-11-19T11:04:06.924Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2020-11-19T11:04:06.924Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at android.os.Looper.loop(Looper.java:154)
[task 2020-11-19T11:04:06.924Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2020-11-19T11:04:06.924Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at java.lang.reflect.Method.invoke(Native Method)
[task 2020-11-19T11:04:06.925Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2020-11-19T11:04:06.925Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2020-11-19T11:04:06.925Z] 11:04:06     INFO -  org.mozilla.geckoview.test |
[task 2020-11-19T11:04:06.925Z] 11:04:06     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2020-11-19T11:04:06.925Z] 11:04:06     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=fullscreenVideoElementMetadata
[task 2020-11-19T11:04:06.925Z] 11:04:06     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.MediaSessionTest
[task 2020-11-19T11:04:06.926Z] 11:04:06     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2020-11-19T11:04:06.926Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:52)
[task 2020-11-19T11:04:06.926Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2020-11-19T11:04:06.926Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2020-11-19T11:04:06.927Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:155)
[task 2020-11-19T11:04:06.927Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForResult(UiThreadUtils.java:80)
[task 2020-11-19T11:04:06.927Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForResult(GeckoSessionTestRule.java:2312)
[task 2020-11-19T11:04:06.927Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.MediaSessionTest.fullscreenVideoElementMetadata(MediaSessionTest.kt:800)
[task 2020-11-19T11:04:06.927Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at java.lang.reflect.Method.invoke(Native Method)
[task 2020-11-19T11:04:06.928Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2020-11-19T11:04:06.928Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2020-11-19T11:04:06.928Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2020-11-19T11:04:06.928Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2020-11-19T11:04:06.929Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at androidx.test.internal.runner.junit4.statement.RunBefores.evaluate(RunBefores.java:80)
[task 2020-11-19T11:04:06.929Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at androidx.test.internal.runner.junit4.statement.RunAfters.evaluate(RunAfters.java:61)
[task 2020-11-19T11:04:06.929Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1313)
[task 2020-11-19T11:04:06.929Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$sIbRNaZJgAu-QrUVWSGD8JbPSWM.run(lambda)
[task 2020-11-19T11:04:06.929Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2020-11-19T11:04:06.930Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2020-11-19T11:04:06.930Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2020-11-19T11:04:06.930Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at android.os.Looper.loop(Looper.java:154)
[task 2020-11-19T11:04:06.930Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2020-11-19T11:04:06.930Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at java.lang.reflect.Method.invoke(Native Method)
[task 2020-11-19T11:04:06.931Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2020-11-19T11:04:06.931Z] 11:04:06     INFO -  org.mozilla.geckoview.test | 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2020-11-19T11:04:06.931Z] 11:04:06     INFO -  org.mozilla.geckoview.test |
[task 2020-11-19T11:04:06.931Z] 11:04:06     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=321
[task 2020-11-19T11:04:06.931Z] 11:04:06     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: -2
[task 2020-11-19T11:04:06.932Z] 11:04:06  WARNING -  TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.MediaSessionTest.fullscreenVideoElementMetadata | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2020-11-19T11:04:06.932Z] 11:04:06     INFO -  TEST-INFO took 30561ms
[task 2020-11-19T11:04:06.932Z] 11:04:06     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=767
[task 2020-11-19T11:04:06.932Z] 11:04:06     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2020-11-19T11:04:06.932Z] 11:04:06     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2020-11-19T11:04:06.932Z] 11:04:06     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=domMultiSessions
[task 2020-11-19T11:04:06.932Z] 11:04:06     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.MediaSessionTest
[task 2020-11-19T11:04:06.932Z] 11:04:06     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=322
[task 2020-11-19T11:04:06.932Z] 11:04:06     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: 1
[task 2020-11-19T11:04:06.933Z] 11:04:06     INFO -  TEST-START | org.mozilla.geckoview.test.MediaSessionTest.domMultiSessions"```

Comment 1

[Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)]

Regression from bug 1673509?

Comment 2

[Alastor Wu [:alwu]]

Esawin, could you help me check this failure? Thank you.

Comment 3

[Intermittent Failures Robot]

8 failures in 4810 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 8

Platform breakdown:

• android-em-7-0-x86_64-qr: 5
• android-em-7-0-x86_64: 2
• android-em-7-0-x86_64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1678275&startday=2020-11-16&endday=2020-11-22&tree=all

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 3
• mozilla-central: 2
• try: 1

Platform breakdown:

• android-em-7-0-x86_64: 2
• android-em-7-0-x86_64-shippable: 2
• android-em-7-0-x86_64-qr: 1
• android-em-7-0-x86_64-shippable-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1678275&startday=2020-11-23&endday=2020-11-29&tree=all

Comment 5

[Eugen Sawin [:esawin]]

I can reproduce it locally in approx. 1 in 100 test runs. Not obvious what is triggering it yet, but will take a look.

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 12

Platform breakdown:

• android-em-7-0-x86_64-shippable-qr: 1
• android-em-7-0-x86_64-qr: 3
• android-em-7-0-x86_64-shippable: 1
• android-em-7-0-x86_64: 7

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1678275&startday=2020-11-30&endday=2020-12-06&tree=all

Comment 7

[Eugen Sawin [:esawin]]

Resolved in bug 1678357.

Comment 8

[Intermittent Failures Robot]

5 failures in 4060 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1
• autoland: 4

Platform breakdown:

• android-em-7-0-x86_64-shippable-qr: 1
• android-em-7-0-x86_64-qr: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1678275&startday=2020-12-07&endday=2020-12-13&tree=all

Comment 9

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

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

Comment 10

[Chris Peterson [:cpeterson]]

Moving some media bugs to the new GeckoView::Media component.