Open Bug 1304480 Opened 3 years ago Updated 7 days ago

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

Categories

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

x86_64
Windows 10
defect

Tracking

()

REOPENED

People

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

References

(Depends on 1 open bug)

Details

(Keywords: bulk-close-intermittents, intermittent-failure, Whiteboard: [stockwell unknown])

Component: Audio/Video → Audio/Video: Playback
Depends on: 1339351
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
(In reply to OrangeFactor Robot from comment #6)
> 6 failures in 947 pushes (0.006 failures/push) were associated with this bug
> in the last 7 days.    
> 
> Repository breakdown:
> * autoland: 5
> * mozilla-beta: 1
> 
> Platform breakdown:
> * windows10-64-stylo-disabled: 3
> * windows10-64: 3
> 
> For more details, see:
> https://brasstacks.mozilla.com/orangefactor/
> ?display=Bug&bugid=1304480&startday=2017-10-09&endday=2017-10-15&tree=all

Windows 10 timeout.
Depends on: 1407553
OS: Unspecified → Windows 10
Hardware: Unspecified → x86_64
Duplicate of this bug: 1399852

This bug failed 32 times in the last 7 days. Occurs on windows10-64 and windows10-64-pgo-qr on opt, pgo, asan and debug build types.

Recent log:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=231357882&repo=mozilla-central&lineNumber=1753

Can you please take a look at this bug?

Flags: needinfo?(drno)

There are 23 total failures in the last 7 days: https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2019-04-29&endday=2019-05-06&tree=trunk&bug=1304480

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=244624146&repo=mozilla-inbound&lineNumber=1705

00:24:58 INFO - TEST-START | dom/media/test/test_background_video_suspend.html
00:28:01 INFO - TEST-UNEXPECTED-FAIL | dom/media/test/test_background_video_suspend.html | Test timed out!
00:28:01 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
00:28:01 INFO - onTimeout@dom/media/test/manifest.js:1778:7
00:28:01 INFO - setTimeout handlerMediaTestManager/this.started@dom/media/test/manifest.js:1785:57
00:28:01 INFO - runTest@dom/media/test/test_background_video_suspend.html:23:13
00:28:01 INFO - MediaTestManager/this.nextTest@dom/media/test/manifest.js:1834:12
00:28:01 INFO - MediaTestManager/this.finished@dom/media/test/manifest.js:1813:12
00:28:01 INFO - runTest@dom/media/test/test_background_video_suspend.html:65:13
00:28:01 INFO - async
MediaTestManager/this.nextTest@dom/media/test/manifest.js:1834:12
00:28:01 INFO - MediaTestManager/this.runTests/<@dom/media/test/manifest.js:1754:12
00:28:01 INFO - gizmo.webm-2 timed out!
00:28:01 INFO - GECKO(9164) | MediaDecoder=1a76463b800: channels=1 rate=48000 hasAudio=1 hasVideo=1 mPlayState=PLAYING
00:28:01 INFO - GECKO(9164) | ChannelMediaResource: mStreamLength=159035 mChannelOffset=159035 mCacheSuspended=0 mChannelEnded=1 mLoadID=1
00:28:01 INFO - GECKO(9164) | reader data:
00:28:01 INFO - GECKO(9164) | Container Type: video/webm
00:28:01 INFO - GECKO(9164) | Audio Decoder(audio/opus, 1 channels @ 48.0kHz): opus audio decoder
00:28:01 INFO - GECKO(9164) | Audio Frames Decoded: 116
00:28:01 INFO - GECKO(9164) | Audio State: ni=0 no=0 wp=0 demuxr=0 demuxq=0 decoder=0 tt=-1.0 tths=-1 in=116 out=116 qs=0 pending=0 wfd=0 eos=0 ds=0 wfk=0 sid=4294967295
00:28:01 INFO - GECKO(9164) | Video Decoder(video/vp9, 560x320 @ 30.01): shutdown
00:28:01 INFO - GECKO(9164) | Hardware Video Decoding: disabled
00:28:01 INFO - GECKO(9164) | Video Frames Decoded: 13 (skipped=0)
00:28:01 INFO - GECKO(9164) | Video State: ni=0 no=0 wp=0 demuxr=0 demuxq=0 decoder=0 tt=-1.0 tths=-1 in=0 out=0 qs=0 pending:0 wfd=0 eos=0 ds=0 wfk=0 sid=4294967295
00:28:01 INFO - GECKO(9164) | Dropped Frames: reader=0 sink=0 compositor=0
00:28:01 INFO - GECKO(9164) | MDSM: duration=5590000 GetMediaTime=990000 GetClock=990000 mMediaSink=1a76838cd60 state=DECODING mPlayState=3 mSentFirstFrameLoadedEvent=1 IsPlaying=1 mAudioStatus=idle mVideoStatus=idle mDecodedAudioEndTime=2328500 mDecodedVideoEndTime=0 mAudioCompleted=0 mVideoCompleted=1 mIsPrerolling=0
00:28:01 INFO - GECKO(9164) | VideoSink: IsStarted=1 IsPlaying=1 VideoQueue(finished=1 size=0) mVideoFrameEndTime=100000 mHasVideo=1 mVideoSinkEndRequest.Exists()=0 mEndPromiseHolder.IsEmpty()=1
00:28:01 INFO - GECKO(9164) | AudioSinkWrapper: IsStarted=1 IsPlaying=1 AudioEnded=0
00:28:01 INFO - GECKO(9164) | AudioSink: StartTime=0 LastGoodPosition=990000 Playing=1 OutputRate=48000 Written=52320 Errored=0 PlaybackComplete=0
00:28:01 INFO - [finished gizmo.webm-2] remaining=
00:28:01 INFO - TEST-PASS | dom/media/test/test_background_video_suspend.html | [finished gizmo.webm-2 t=182.156] Length of array should match number of running tests
00:28:01 INFO - Finished at Sat May 04 2019 00:28:01 GMT+0000 (Greenwich Mean Time) (1556929681.016s)
00:28:01 INFO - Running time: 182.205s
00:28:01 INFO - GECKO(9164) | MEMORY STAT | vsize 2103877MB | vsizeMaxContiguous 71811790MB | residentFast 130MB | heapAllocated 9MB

Flags: needinfo?(drno)
Whiteboard: [stockwell needswork:owner]

Nils, could you please assign this to someone?

Flags: needinfo?(drno)

It might be as easy as just increasing the timeout values of the test it looks like.
Alastor, could you please have a look at this?

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

It looks like that the gizmo.mp4-0 didn't play to end after we resume it, the issue can't be solved by simply adding timeout value.
I guess that maybe it relates with bug1532495, will start to investigaste that bug later.

Flags: needinfo?(alwu)
Priority: P5 → P3
See Also: → 1532495
You need to log in before you can comment on or make changes to this bug.