Closed Bug 1551884 Opened 6 years ago Closed 5 years ago

Intermittent dom/media/test/test_background_video_resume_looping_video_without_audio.html | Test timed out.

Categories

(Core :: Audio/Video: Playback, defect, P5)

defect

Tracking

()

RESOLVED FIXED
85 Branch
Tracking Status
firefox-esr78 --- unaffected
firefox83 --- wontfix
firefox84 --- wontfix
firefox85 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: alwu)

References

Details

(Keywords: intermittent-failure, regression)

Attachments

(1 file)

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


[task 2019-05-15T12:39:08.383Z] 12:39:08 INFO - TEST-START | dom/media/test/test_background_video_resume_looping_video_without_audio.html
[task 2019-05-15T12:44:09.174Z] 12:44:09 INFO - TEST-INFO | started process screentopng
[task 2019-05-15T12:44:09.494Z] 12:44:09 INFO - TEST-INFO | screentopng: exit 0
[task 2019-05-15T12:44:09.496Z] 12:44:09 INFO - Buffered messages logged at 12:39:08
[task 2019-05-15T12:44:09.497Z] 12:44:09 INFO - TEST-PASS | dom/media/test/test_background_video_resume_looping_video_without_audio.html | A valid string reason is expected
[task 2019-05-15T12:44:09.498Z] 12:44:09 INFO - TEST-PASS | dom/media/test/test_background_video_resume_looping_video_without_audio.html | Reason cannot be empty
[task 2019-05-15T12:44:09.499Z] 12:44:09 INFO - ensure video becomes visible
[task 2019-05-15T12:44:09.503Z] 12:44:09 INFO - TEST-PASS | dom/media/test/test_background_video_resume_looping_video_without_audio.html | undefined is visible.
[task 2019-05-15T12:44:09.503Z] 12:44:09 INFO - start playing video
[task 2019-05-15T12:44:09.503Z] 12:44:09 INFO - TEST-PASS | dom/media/test/test_background_video_resume_looping_video_without_audio.html | video has started playing
[task 2019-05-15T12:44:09.503Z] 12:44:09 INFO - suspend video decoding
[task 2019-05-15T12:44:09.504Z] 12:44:09 INFO - Buffered messages finished
[task 2019-05-15T12:44:09.504Z] 12:44:09 INFO - TEST-UNEXPECTED-FAIL | dom/media/test/test_background_video_resume_looping_video_without_audio.html | Test timed out.
[task 2019-05-15T12:44:09.504Z] 12:44:09 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
[task 2019-05-15T12:44:09.504Z] 12:44:09 INFO - reportError@SimpleTest/TestRunner.js:121:22
[task 2019-05-15T12:44:09.504Z] 12:44:09 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
[task 2019-05-15T12:44:09.505Z] 12:44:09 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-05-15T12:44:09.505Z] 12:44:09 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-05-15T12:44:09.505Z] 12:44:09 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-05-15T12:44:09.505Z] 12:44:09 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-05-15T12:44:09.505Z] 12:44:09 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-05-15T12:44:09.505Z] 12:44:09 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-05-15T12:44:09.505Z] 12:44:09 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-05-15T12:44:09.506Z] 12:44:09 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-05-15T12:44:09.506Z] 12:44:09 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-05-15T12:44:09.507Z] 12:44:09 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-05-15T12:44:09.508Z] 12:44:09 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-05-15T12:44:09.508Z] 12:44:09 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-05-15T12:44:09.509Z] 12:44:09 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-05-15T12:44:09.509Z] 12:44:09 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-05-15T12:44:09.510Z] 12:44:09 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-05-15T12:44:09.511Z] 12:44:09 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-05-15T12:44:09.513Z] 12:44:09 INFO - TestRunner.runTests/<@SimpleTest/TestRunner.js:381:20
[task 2019-05-15T12:44:09.515Z] 12:44:09 INFO - promise callbackTestRunner.runTests@SimpleTest/TestRunner.js:368:50
[task 2019-05-15T12:44:09.516Z] 12:44:09 INFO - RunSet.runtests@SimpleTest/setup.js:201:14
[task 2019-05-15T12:44:09.517Z] 12:44:09 INFO - RunSet.runall@SimpleTest/setup.js:180:12
[task 2019-05-15T12:44:09.518Z] 12:44:09 INFO - hookupTests@SimpleTest/setup.js:273:12
[task 2019-05-15T12:44:09.518Z] 12:44:09 INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:36:5
[task 2019-05-15T12:44:09.520Z] 12:44:09 INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:49:11
[task 2019-05-15T12:44:09.521Z] 12:44:09 INFO - EventHandlerNonNull
getTestManifest@http://mochi.test:8888/manifestLibrary.js:45:3
[task 2019-05-15T12:44:09.522Z] 12:44:09 INFO - hookup@SimpleTest/setup.js:253:5
[task 2019-05-15T12:44:09.522Z] 12:44:09 INFO - EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=%2Ftmp&cleanupCrashes=true:11:1
[task 2019-05-15T12:44:10.173Z] 12:44:10 INFO - GECKO(1854) | MEMORY STAT | vsize 2642MB | residentFast 146MB | heapAllocated 15MB
[task 2019-05-15T12:44:10.173Z] 12:44:10 INFO - GECKO(1854) | MediaDecoder=7f793129e800: channels=0 rate=0 hasAudio=0 hasVideo=1 mPlayState=PLAYING
[task 2019-05-15T12:44:10.174Z] 12:44:10 INFO - GECKO(1854) | ChannelMediaResource: mStreamLength=112663 mChannelOffset=112663 mCacheSuspended=1 mChannelEnded=1 mLoadID=0
[task 2019-05-15T12:44:10.174Z] 12:44:10 INFO - GECKO(1854) | reader data:
[task 2019-05-15T12:44:10.175Z] 12:44:10 INFO - GECKO(1854) | Container Type: video/webm
[task 2019-05-15T12:44:10.177Z] 12:44:10 INFO - GECKO(1854) | Audio Decoder(none, 0 channels @ 0.0kHz): unavailable

Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → INCOMPLETE
Status: REOPENED → RESOLVED
Closed: 6 years ago6 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 6 years ago5 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

This started to fail frequently on Geckoview with Android 7.0 and Webrender last weekend. Anybody aware what could have started this?

Flags: needinfo?(snorp)
Flags: needinfo?(bvandyk)

(In reply to Sebastian Hengst [:aryx] (needinfo on intermittent or backout) from comment #30)

This started to fail frequently on Geckoview with Android 7.0 and Webrender last weekend. Anybody aware what could have started this?

Nope, not aware of anything. Alastor may have some ideas.

Flags: needinfo?(snorp) → needinfo?(alwu)

Clearing NI, :alwu seems like the best persons to understand this.

Flags: needinfo?(bvandyk)
Assignee: nobody → alwu
Flags: needinfo?(alwu)

As mozentervideosuspend would only be dispatched when we're in buffering or decoding state, we would miss the event if MDSM already entered other state. eg. completed state.

Pushed by alwu@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/3e61ec43982a suspend video decoding before start video in order to ensure we can always receive `mozentervideosuspend` event. r=bryce
Status: REOPENED → RESOLVED
Closed: 5 years ago5 years ago
Resolution: --- → FIXED
Target Milestone: --- → 85 Branch
See Also: → 1677880
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: