1564348 - Intermittent org.mozilla.geckoview.test.MediaElementTest#oggSeekMedia | status -2

Status: RESOLVED WORKSFORME

(GeckoView :: Media, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=255429760&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/LO0gbyiNSIO9HKRSqhyFDA/runs/1/artifacts/public/logs/live_backing.log

---

[task 2019-07-09T06:34:35.341Z] 06:34:35 INFO - TEST-START | org.mozilla.geckoview.test.MediaElementTest.oggSeekMedia
[task 2019-07-09T06:37:43.443Z] 06:37:43 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2019-07-09T06:37:43.445Z] 06:37:43 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=35
[task 2019-07-09T06:37:43.445Z] 06:37:43 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.MediaElementTest
[task 2019-07-09T06:37:43.445Z] 06:37:43 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2019-07-09T06:37:43.446Z] 06:37:43 INFO - org.mozilla.geckoview.test | Error in oggSeekMedia(org.mozilla.geckoview.test.MediaElementTest):
[task 2019-07-09T06:37:43.446Z] 06:37:43 INFO - org.mozilla.geckoview.test | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 180000ms
[task 2019-07-09T06:37:43.446Z] 06:37:43 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:58)
[task 2019-07-09T06:37:43.446Z] 06:37:43 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:730)
[task 2019-07-09T06:37:43.446Z] 06:37:43 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:92)
[task 2019-07-09T06:37:43.447Z] 06:37:43 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:161)
[task 2019-07-09T06:37:43.448Z] 06:37:43 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1528)
[task 2019-07-09T06:37:43.449Z] 06:37:43 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1470)
[task 2019-07-09T06:37:43.451Z] 06:37:43 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1423)
[task 2019-07-09T06:37:43.452Z] 06:37:43 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.MediaElementTest.seekMedia(MediaElementTest.kt:185)
[task 2019-07-09T06:37:43.453Z] 06:37:43 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.MediaElementTest.oggSeekMedia(MediaElementTest.kt:255)
[task 2019-07-09T06:37:43.453Z] 06:37:43 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invokeNative(Native Method)
[task 2019-07-09T06:37:43.454Z] 06:37:43 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Method.java:525)
[task 2019-07-09T06:37:43.454Z] 06:37:43 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2019-07-09T06:37:43.455Z] 06:37:43 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2019-07-09T06:37:43.456Z] 06:37:43 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2019-07-09T06:37:43.456Z] 06:37:43 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2019-07-09T06:37:43.457Z] 06:37:43 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1261)
[task 2019-07-09T06:37:43.457Z] 06:37:43 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2$$Lambda$0.run(Unknown Source)
[task 2019-07-09T06:37:43.458Z] 06:37:43 INFO - org.mozilla.geckoview.test | at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1719)
[task 2019-07-09T06:37:43.458Z] 06:37:43 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:730)
[task 2019-07-09T06:37:43.459Z] 06:37:43 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:92)
[task 2019-07-09T06:37:43.460Z] 06:37:43 INFO - org.mozilla.geckoview.test | at android.os.Looper.loop(Looper.java:137)
[task 2019-07-09T06:37:43.461Z] 06:37:43 INFO - org.mozilla.geckoview.test | at android.app.ActivityThread.main(ActivityThread.java:5103)
[task 2019-07-09T06:37:43.461Z] 06:37:43 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invokeNative(Native Method)
[task 2019-07-09T06:37:43.462Z] 06:37:43 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Method.java:525)
[task 2019-07-09T06:37:43.462Z] 06:37:43 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:737)
[task 2019-07-09T06:37:43.463Z] 06:37:43 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:553)
[task 2019-07-09T06:37:43.463Z] 06:37:43 INFO - org.mozilla.geckoview.test | at dalvik.system.NativeStart.main(Native Method)
[task 2019-07-09T06:37:43.464Z] 06:37:43 INFO - org.mozilla.geckoview.test |
[task 2019-07-09T06:37:43.464Z] 06:37:43 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=65
[task 2019-07-09T06:37:43.465Z] 06:37:43 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 180000ms
[task 2019-07-09T06:37:43.465Z] 06:37:43 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:58)
[task 2019-07-09T06:37:43.466Z] 06:37:43 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:730)
[task 2019-07-09T06:37:43.466Z] 06:37:43 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:92)
[task 2019-07-09T06:37:43.466Z] 06:37:43 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:161)
[task 2019-07-09T06:37:43.467Z] 06:37:43 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1528)
[task 2019-07-09T06:37:43.467Z] 06:37:43 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1470)
[task 2019-07-09T06:37:43.467Z] 06:37:43 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1423)
[task 2019-07-09T06:37:43.468Z] 06:37:43 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.MediaElementTest.seekMedia(MediaElementTest.kt:185)
[task 2019-07-09T06:37:43.468Z] 06:37:43 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.MediaElementTest.oggSeekMedia(MediaElementTest.kt:255)
[task 2019-07-09T06:37:43.468Z] 06:37:43 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invokeNative(Native Method)
[task 2019-07-09T06:37:43.468Z] 06:37:43 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Method.java:525)
[task 2019-07-09T06:37:43.469Z] 06:37:43 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2019-07-09T06:37:43.469Z] 06:37:43 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2019-07-09T06:37:43.469Z] 06:37:43 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2019-07-09T06:37:43.470Z] 06:37:43 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2019-07-09T06:37:43.470Z] 06:37:43 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1261)
[task 2019-07-09T06:37:43.470Z] 06:37:43 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2$$Lambda$0.run(Unknown Source)
[task 2019-07-09T06:37:43.470Z] 06:37:43 INFO - org.mozilla.geckoview.test | at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1719)
[task 2019-07-09T06:37:43.471Z] 06:37:43 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:730)
[task 2019-07-09T06:37:43.471Z] 06:37:43 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:92)
[task 2019-07-09T06:37:43.471Z] 06:37:43 INFO - org.mozilla.geckoview.test | at android.os.Looper.loop(Looper.java:137)
[task 2019-07-09T06:37:43.471Z] 06:37:43 INFO - org.mozilla.geckoview.test | at android.app.ActivityThread.main(ActivityThread.java:5103)
[task 2019-07-09T06:37:43.472Z] 06:37:43 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invokeNative(Native Method)
[task 2019-07-09T06:37:43.472Z] 06:37:43 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Method.java:525)
[task 2019-07-09T06:37:43.472Z] 06:37:43 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:737)
[task 2019-07-09T06:37:43.473Z] 06:37:43 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:553)
[task 2019-07-09T06:37:43.473Z] 06:37:43 INFO - org.mozilla.geckoview.test | at dalvik.system.NativeStart.main(Native Method)
[task 2019-07-09T06:37:43.473Z] 06:37:43 INFO - org.mozilla.geckoview.test |
[task 2019-07-09T06:37:43.473Z] 06:37:43 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=oggSeekMedia
[task 2019-07-09T06:37:43.474Z] 06:37:43 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: -2
[task 2019-07-09T06:37:43.474Z] 06:37:43 WARNING - TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.MediaElementTest.oggSeekMedia | status -2
[task 2019-07-09T06:37:43.474Z] 06:37:43 INFO - TEST-INFO took 188115ms

Comment 1

[Intermittent Failures Robot]

21 failures in 1090 pushes (0.019 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 17
• mozilla-central: 1
• mozilla-inbound: 2
• try: 1

Platform breakdown:

• android-em-4-3-armv7-api16: 20
• android-em-7-0-x86_64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1564348&startday=2019-07-09&endday=2019-07-09&tree=all

Comment 2

[Andreea Pavel [:apavel]]

This seems to be a regression of bug 1553515, Agi can you take a look?

Joel, this bug and bug 1545931 seem to have the same cuplrit. Please let us know if we should backout.

Comment 3

[Joel Maher ( :jmaher ) (UTC -8)]

:apavel, as bug 1553515 is to cleanup the tests and it looks to be cleanup up many other issues I would like to leave this in. We have 2 regressions known so far, I assume :agi can get them fixed up or disabled today or tomorrow. Thanks for mentioning this- if this were a feature instead of tests as a root cause, I would be more inclined to backout.

Comment 4

[Andreea Pavel [:apavel]]

Okay Joel, thank you.

Comment 5

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

I just landed https://hg.mozilla.org/integration/autoland/rev/8c3c0710eb53fd9a6b3f6879e593676a5844a872 which should make this go away (since it only happens on arm opt).

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 18
• mozilla-inbound: 2
• mozilla-central: 1
• try: 1

Platform breakdown:

• android-em-4-3-armv7-api16: 21
• android-em-7-0-x86_64: 1

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

Comment 7

[Intermittent Failures Robot]

2 failures in 4608 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1
• mozilla-inbound: 1

Platform breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1564348&startday=2019-07-22&endday=2019-07-28&tree=all

Comment 8

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

rbarker pointed me to Bug 1510393 which might be causing this.

Comment 9

[Intermittent Failures Robot]

2 failures in 4839 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 2

Platform breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1564348&startday=2019-08-12&endday=2019-08-18&tree=all

Comment 10

[Intermittent Failures Robot]

2 failures in 5073 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 2

Platform breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1564348&startday=2019-08-19&endday=2019-08-25&tree=all

Comment 11

[Intermittent Failures Robot]

1 failures in 5047 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-inbound: 1

Platform breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1564348&startday=2019-08-26&endday=2019-09-01&tree=all

Comment 12

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2
• try: 1

Platform breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1564348&startday=2019-09-02&endday=2019-09-08&tree=all

Comment 13

[Intermittent Failures Robot]

1 failures in 4637 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-inbound: 1

Platform breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1564348&startday=2019-09-09&endday=2019-09-15&tree=all

Comment 14

[Intermittent Failures Robot]

1 failures in 4350 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1564348&startday=2019-09-16&endday=2019-09-22&tree=all

Comment 15

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 4

Platform breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1564348&startday=2019-09-23&endday=2019-09-29&tree=all

Comment 16

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1
• autoland: 3

Platform breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1564348&startday=2019-09-30&endday=2019-10-06&tree=all

Comment 17

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1
• autoland: 7
• mozilla-central: 1

Platform breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1564348&startday=2019-10-07&endday=2019-10-13&tree=all

Comment 18

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 4
• try: 1

Platform breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1564348&startday=2019-10-14&endday=2019-10-20&tree=all

Comment 19

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 3
• try: 2

Platform breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1564348&startday=2019-10-21&endday=2019-10-27&tree=all

Comment 20

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 14

Platform breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1564348&startday=2019-10-28&endday=2019-11-03&tree=all

Comment 21

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 12
• mozilla-central: 3

Platform breakdown:

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

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

Comment 22

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 20

Platform breakdown:

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

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

Comment 23

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 7
• try: 1

Platform breakdown:

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

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

Comment 24

[Intermittent Failures Robot]

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

Repository breakdown:

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

Platform breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1564348&startday=2019-11-25&endday=2019-12-01&tree=all

Comment 25

[Andreea Pavel [:apavel]]

There are 17 total failures in the last 7 days on android-em-7-0-x86_64 debug and 60 total failures in the last 7 days.

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

[task 2019-12-02T07:13:41.853Z] 07:13:41 INFO - TEST-START | org.mozilla.geckoview.test.MediaElementTest.oggSeekMedia
[task 2019-12-02T07:14:28.925Z] 07:14:28 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=587
[task 2019-12-02T07:14:28.925Z] 07:14:28 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2019-12-02T07:14:28.925Z] 07:14:28 INFO - org.mozilla.geckoview.test | Error in oggSeekMedia(org.mozilla.geckoview.test.MediaElementTest):
[task 2019-12-02T07:14:28.925Z] 07:14:28 INFO - org.mozilla.geckoview.test | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 45000ms
[task 2019-12-02T07:14:28.925Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:58)
[task 2019-12-02T07:14:28.926Z] 07:14:28 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2019-12-02T07:14:28.926Z] 07:14:28 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2019-12-02T07:14:28.926Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:161)
[task 2019-12-02T07:14:28.926Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1533)
[task 2019-12-02T07:14:28.926Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1475)
[task 2019-12-02T07:14:28.926Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1428)
[task 2019-12-02T07:14:28.926Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.MediaElementTest.waitUntilState(MediaElementTest.kt:73)
[task 2019-12-02T07:14:28.926Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.MediaElementTest.waitUntilVideoReady(MediaElementTest.kt:91)
[task 2019-12-02T07:14:28.927Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.MediaElementTest.waitUntilVideoReady$default(MediaElementTest.kt:89)
[task 2019-12-02T07:14:28.927Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.MediaElementTest.seekMedia(MediaElementTest.kt:168)
[task 2019-12-02T07:14:28.927Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.MediaElementTest.oggSeekMedia(MediaElementTest.kt:255)
[task 2019-12-02T07:14:28.927Z] 07:14:28 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2019-12-02T07:14:28.927Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2019-12-02T07:14:28.927Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2019-12-02T07:14:28.927Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2019-12-02T07:14:28.928Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2019-12-02T07:14:28.928Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1266)
[task 2019-12-02T07:14:28.928Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$mzZNnl5Bu5F2_4xGxj0DHU4J33I.run(lambda)
[task 2019-12-02T07:14:28.928Z] 07:14:28 INFO - org.mozilla.geckoview.test | at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2019-12-02T07:14:28.929Z] 07:14:28 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2019-12-02T07:14:28.929Z] 07:14:28 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2019-12-02T07:14:28.929Z] 07:14:28 INFO - org.mozilla.geckoview.test | at android.os.Looper.loop(Looper.java:154)
[task 2019-12-02T07:14:28.929Z] 07:14:28 INFO - org.mozilla.geckoview.test | at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2019-12-02T07:14:28.929Z] 07:14:28 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2019-12-02T07:14:28.929Z] 07:14:28 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2019-12-02T07:14:28.929Z] 07:14:28 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2019-12-02T07:14:28.929Z] 07:14:28 INFO - org.mozilla.geckoview.test |
[task 2019-12-02T07:14:28.929Z] 07:14:28 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2019-12-02T07:14:28.929Z] 07:14:28 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=oggSeekMedia
[task 2019-12-02T07:14:28.930Z] 07:14:28 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.MediaElementTest
[task 2019-12-02T07:14:28.930Z] 07:14:28 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 45000ms
[task 2019-12-02T07:14:28.930Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:58)
[task 2019-12-02T07:14:28.930Z] 07:14:28 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2019-12-02T07:14:28.930Z] 07:14:28 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2019-12-02T07:14:28.930Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:161)
[task 2019-12-02T07:14:28.930Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1533)
[task 2019-12-02T07:14:28.930Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1475)
[task 2019-12-02T07:14:28.930Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1428)
[task 2019-12-02T07:14:28.931Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.MediaElementTest.waitUntilState(MediaElementTest.kt:73)
[task 2019-12-02T07:14:28.931Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.MediaElementTest.waitUntilVideoReady(MediaElementTest.kt:91)
[task 2019-12-02T07:14:28.931Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.MediaElementTest.waitUntilVideoReady$default(MediaElementTest.kt:89)
[task 2019-12-02T07:14:28.931Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.MediaElementTest.seekMedia(MediaElementTest.kt:168)
[task 2019-12-02T07:14:28.931Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.MediaElementTest.oggSeekMedia(MediaElementTest.kt:255)
[task 2019-12-02T07:14:28.931Z] 07:14:28 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2019-12-02T07:14:28.931Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2019-12-02T07:14:28.932Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2019-12-02T07:14:28.932Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2019-12-02T07:14:28.932Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2019-12-02T07:14:28.932Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1266)
[task 2019-12-02T07:14:28.932Z] 07:14:28 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$mzZNnl5Bu5F2_4xGxj0DHU4J33I.run(lambda)
[task 2019-12-02T07:14:28.932Z] 07:14:28 INFO - org.mozilla.geckoview.test | at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2019-12-02T07:14:28.932Z] 07:14:28 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2019-12-02T07:14:28.932Z] 07:14:28 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2019-12-02T07:14:28.933Z] 07:14:28 INFO - org.mozilla.geckoview.test | at android.os.Looper.loop(Looper.java:154)
[task 2019-12-02T07:14:28.933Z] 07:14:28 INFO - org.mozilla.geckoview.test | at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2019-12-02T07:14:28.933Z] 07:14:28 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2019-12-02T07:14:28.933Z] 07:14:28 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2019-12-02T07:14:28.933Z] 07:14:28 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2019-12-02T07:14:28.933Z] 07:14:28 INFO - org.mozilla.geckoview.test |
[task 2019-12-02T07:14:28.933Z] 07:14:28 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=230
[task 2019-12-02T07:14:28.933Z] 07:14:28 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: -2
[task 2019-12-02T07:14:28.933Z] 07:14:28 WARNING - TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.MediaElementTest.oggSeekMedia | status -2
[task 2019-12-02T07:14:28.934Z] 07:14:28 INFO - TEST-INFO took 47077ms
[task 2019-12-02T07:14:28.934Z] 07:14:28 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=587
[task 2019-12-02T07:14:28.934Z] 07:14:28 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2019-12-02T07:14:28.934Z] 07:14:28 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2019-12-02T07:14:28.934Z] 07:14:28 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=webmPlayMediaFromScript
[task 2019-12-02T07:14:28.934Z] 07:14:28 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.MediaElementTest
[task 2019-12-02T07:14:28.934Z] 07:14:28 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=231
[task 2019-12-02T07:14:28.934Z] 07:14:28 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: 1

Emily can you assign someone to take a look?

Comment 26

[Emily Toop (:fluffyemily)]

snorp will take a look

Comment 27

[Andreea Pavel [:apavel]]

Thank you.

Comment 28

[Intermittent Failures Robot]

29 failures in 5144 pushes (0.006 failures/push) were associated with this bug in the last 7 days.

This is the #44 most frequent failure this week.

Repository breakdown:

• autoland: 27
• mozilla-beta: 1
• try: 1

Platform breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1564348&startday=2019-12-02&endday=2019-12-08&tree=all

Comment 29

[Andreea Pavel [:apavel]]

There are 31 total failures in the last 7 days on android-em-7-0-x86_64 debug and opt.

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

[task 2019-12-09T19:02:45.448Z] 19:02:45 INFO - TEST-START | org.mozilla.geckoview.test.MediaElementTest.oggSeekMedia
[task 2019-12-09T19:03:34.331Z] 19:03:34 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=596
[task 2019-12-09T19:03:34.331Z] 19:03:34 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2019-12-09T19:03:34.331Z] 19:03:34 INFO - org.mozilla.geckoview.test | Error in oggSeekMedia(org.mozilla.geckoview.test.MediaElementTest):
[task 2019-12-09T19:03:34.333Z] 19:03:34 INFO - org.mozilla.geckoview.test | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 45000ms
[task 2019-12-09T19:03:34.333Z] 19:03:34 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:58)
[task 2019-12-09T19:03:34.333Z] 19:03:34 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2019-12-09T19:03:34.333Z] 19:03:34 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2019-12-09T19:03:34.333Z] 19:03:34 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:161)
[task 2019-12-09T19:03:34.333Z] 19:03:34 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1533)
[task 2019-12-09T19:03:34.333Z] 19:03:34 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1475)
[task 2019-12-09T19:03:34.333Z] 19:03:34 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1428)
[task 2019-12-09T19:03:34.333Z] 19:03:34 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.MediaElementTest.seekMedia(MediaElementTest.kt:185)
[task 2019-12-09T19:03:34.333Z] 19:03:34 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.MediaElementTest.oggSeekMedia(MediaElementTest.kt:255)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1266)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$mzZNnl5Bu5F2_4xGxj0DHU4J33I.run(lambda)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at android.os.Looper.loop(Looper.java:154)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test |
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=oggSeekMedia
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.MediaElementTest
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 45000ms
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:58)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:161)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1533)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1475)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1428)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.MediaElementTest.seekMedia(MediaElementTest.kt:185)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.MediaElementTest.oggSeekMedia(MediaElementTest.kt:255)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1266)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$mzZNnl5Bu5F2_4xGxj0DHU4J33I.run(lambda)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at android.os.Looper.loop(Looper.java:154)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test |
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=234
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: -2
[task 2019-12-09T19:03:34.340Z] 19:03:34 WARNING - TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.MediaElementTest.oggSeekMedia | status -2
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - TEST-INFO took 48889ms
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=596
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=webmPlayMediaFromScript
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.MediaElementTest
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=235
[task 2019-12-09T19:03:34.340Z] 19:03:34 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: 1

James are there update here?

Comment 30

[James Willcox (:snorp) ([e-mail]) (he/him)]

I don't think anyone has looked at this one yet, but we have some folks going through these. Maybe Brendan has some cycles?

Comment 31

[Brendan Dahl [:bdahl]]

It looks like we get all the events in the right order in MediaElementTest.seekMedia:

12-14 00:06:44.640  3478  3493 I Gecko   : >>> event aEvent.type=seeking
12-14 00:06:44.642  3478  3493 I Gecko   : >>> event aEvent.type=timeupdate
12-14 00:06:44.643  3478  3493 I Gecko   : >>> event aEvent.type=seeked
12-14 00:06:44.643  3478  3493 I Gecko   : >>> event aEvent.type=canplay
12-14 00:06:44.644  3478  3493 I Gecko   : >>> event aEvent.type=canplaythrough

However, the timeupdate event which triggers the onTimeChange callback has time=1.599984 and seek=2.0. Since time < seek we never advance out of the while loop. On successful runs, time=2.0 seek=2.0.

Now to figure out why we get the strange timeupdate time event...

Comment 32

[Intermittent Failures Robot]

30 failures in 4867 pushes (0.006 failures/push) were associated with this bug in the last 7 days.

This is the #45 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: 23
• try: 5
• mozilla-central: 2

Platform breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1564348&startday=2019-12-09&endday=2019-12-15&tree=all

Comment 33

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 15

Platform breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1564348&startday=2019-12-16&endday=2019-12-22&tree=all

Comment 34

[Brendan Dahl [:bdahl]]

I just rebased and I can no longer reproduce this locally. It also looks like there haven't been any failures in the last two weeks. This may have been fixed somewhere else.

Comment 35

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

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

Comment 36

[Chris Peterson [:cpeterson]]

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