Closed Bug 1474162 Opened 7 years ago Closed 7 years ago

Intermittent dom/media/test/test_chaining.html | Test timed out! (mozilla-beta)

Categories

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

Product:
Core
Component:
Audio/Video: Playback
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: apavel [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=187019715&repo=mozilla-beta https://queue.taskcluster.net/v1/task/es_Kr0GZR0efUyXBt5Efzg/runs/0/artifacts/public/logs/live_backing.log 23:05:55 INFO - 316 INFO TEST-START | dom/media/test/test_chaining.html 23:08:55 INFO - TEST-INFO | started process screenshot 23:08:55 INFO - TEST-INFO | screenshot: exit 0 23:08:55 INFO - Buffered messages logged at 23:05:55 23:08:55 INFO - 317 INFO TEST-PASS | dom/media/test/test_chaining.html | A valid string reason is expected 23:08:55 INFO - 318 INFO TEST-PASS | dom/media/test/test_chaining.html | Reason cannot be empty 23:08:55 INFO - 319 INFO Started Sat Jul 07 2018 23:05:55 GMT+0000 (Coordinated Universal Time) (1531004755.882s) 23:08:55 INFO - 320 INFO TEST-PASS | dom/media/test/test_chaining.html | [started chain.ogg-0 t=0.01] Length of array should match number of running tests 23:08:55 INFO - 321 INFO TEST-PASS | dom/media/test/test_chaining.html | [started chain.opus-1 t=0.01] Length of array should match number of running tests 23:08:55 INFO - 322 INFO TEST-PASS | dom/media/test/test_chaining.html | The metadata index value should increase.http://mochi.test:8888/tests/dom/media/test/chain.ogg 23:08:55 INFO - 323 INFO TEST-PASS | dom/media/test/test_chaining.html | The currenttime should be increased correctly in new chained part. 23:08:55 INFO - 324 INFO TEST-PASS | dom/media/test/test_chaining.html | The metadata index value should increase.http://mochi.test:8888/tests/dom/media/test/chain.opus 23:08:55 INFO - 325 INFO TEST-PASS | dom/media/test/test_chaining.html | The currenttime should be increased correctly in new chained part. 23:08:55 INFO - Buffered messages finished 23:08:55 ERROR - 326 INFO TEST-UNEXPECTED-FAIL | dom/media/test/test_chaining.html | Test timed out! 23:08:55 INFO - onTimeout@dom/media/test/manifest.js:1717:7 23:08:55 INFO - 327 INFO chain.ogg-0 timed out! 23:08:55 INFO - Not taking screenshot here: see the one that was previously logged 23:08:55 ERROR - 328 INFO TEST-UNEXPECTED-FAIL | dom/media/test/test_chaining.html | Test timed out! 23:08:55 INFO - onTimeout@dom/media/test/manifest.js:1717:7 23:08:55 INFO - 329 INFO chain.opus-1 timed out! 23:08:55 INFO - GECKO(5956) | MediaDecoder=243136e0800: channels=2 rate=44100 hasAudio=1 hasVideo=0 mPlayState=PLAYING 23:08:55 INFO - GECKO(5956) | ChannelMediaResource: mStreamLength=63610 mChannelOffset=63610 mCacheSuspended=0 mChannelEnded=1 mLoadID=1 23:08:55 INFO - GECKO(5956) | reader data: 23:08:55 INFO - GECKO(5956) | Container Type: application/ogg 23:08:55 INFO - GECKO(5956) | Audio Decoder(audio/vorbis, 2 channels @ 44.1kHz): vorbis audio decoder 23:08:55 INFO - GECKO(5956) | Audio Frames Decoded: 103 23:08:55 INFO - GECKO(5956) | Audio State: ni=0 no=0 wp=0 demuxr=0 demuxq=0 decoder=0 tt=-1.0 tths=-1 in=16 out=15 qs=1 pending=0 wfd=0 eos=0 ds=0 wfk=0 sid=3 23:08:55 INFO - GECKO(5956) | Video Decoder(none, 0x0 @ 0.00): unavailable 23:08:55 INFO - GECKO(5956) | Hardware Video Decoding: disabled 23:08:55 INFO - GECKO(5956) | Video Frames Decoded: 0 (skipped=0) 23:08:55 INFO - GECKO(5956) | MDSM: duration=9223372036854775807 GetMediaTime=990000 GetClock=990000 mMediaSink=2431487b1f0 state=DECODING mPlayState=3 mSentFirstFrameLoadedEvent=1 IsPlaying=1 mAudioStatus=idle mVideoStatus=idle mDecodedAudioEndTime=2317823 mDecodedVideoEndTime=0 mAudioCompleted=0 mVideoCompleted=0 mIsPrerolling=0 23:08:55 INFO - GECKO(5956) | VideoSink: IsStarted=1 IsPlaying=1 VideoQueue(finished=0 size=0) mVideoFrameEndTime=0 mHasVideo=0 mVideoSinkEndRequest.Exists()=0 mEndPromiseHolder.IsEmpty()=1 23:08:55 INFO - GECKO(5956) | AudioSinkWrapper: IsStarted=1 IsPlaying=1 AudioEnded=0 23:08:55 INFO - GECKO(5956) | AudioSink: StartTime=0 LastGoodPosition=990000 Playing=1 OutputRate=44100 Written=48069 Errored=0 PlaybackComplete=0 23:08:55 INFO - GECKO(5956) | MediaDecoder=24314873800: channels=2 rate=48000 hasAudio=1 hasVideo=0 mPlayState=PLAYING 23:08:55 INFO - GECKO(5956) | ChannelMediaResource: mStreamLength=50101 mChannelOffset=50101 mCacheSuspended=0 mChannelEnded=1 mLoadID=1 23:08:55 INFO - GECKO(5956) | reader data: 23:08:55 INFO - GECKO(5956) | Container Type: application/ogg 23:08:55 INFO - GECKO(5956) | Audio Decoder(audio/opus, 2 channels @ 48.0kHz): opus audio decoder 23:08:55 INFO - GECKO(5956) | Audio Frames Decoded: 119 23:08:55 INFO - GECKO(5956) | Audio State: ni=0 no=0 wp=0 demuxr=0 demuxq=0 decoder=0 tt=-1.0 tths=-1 in=17 out=17 qs=0 pending=0 wfd=0 eos=0 ds=0 wfk=0 sid=4 23:08:55 INFO - GECKO(5956) | Video Decoder(none, 0x0 @ 0.00): unavailable 23:08:55 INFO - GECKO(5956) | Hardware Video Decoding: disabled 23:08:55 INFO - GECKO(5956) | Video Frames Decoded: 0 (skipped=0) 23:08:55 INFO - GECKO(5956) | MDSM: duration=9223372036854775807 GetMediaTime=990000 GetClock=990000 mMediaSink=2431487bef0 state=DECODING mPlayState=3 mSentFirstFrameLoadedEvent=1 IsPlaying=1 mAudioStatus=idle mVideoStatus=idle mDecodedAudioEndTime=2332584 mDecodedVideoEndTime=0 mAudioCompleted=0 mVideoCompleted=0 mIsPrerolling=0 23:08:55 INFO - GECKO(5956) | VideoSink: IsStarted=1 IsPlaying=1 VideoQueue(finished=0 size=0) mVideoFrameEndTime=0 mHasVideo=0 mVideoSinkEndRequest.Exists()=0 mEndPromiseHolder.IsEmpty()=1 23:08:55 INFO - GECKO(5956) | AudioSinkWrapper: IsStarted=1 IsPlaying=1 AudioEnded=0 23:08:55 INFO - GECKO(5956) | AudioSink: StartTime=0 LastGoodPosition=990000 Playing=1 OutputRate=48000 Written=52320 Errored=0 PlaybackComplete=0 23:08:55 INFO - 330 INFO [finished chain.ogg-0] remaining= chain.opus-1 23:08:55 INFO - 331 INFO TEST-PASS | dom/media/test/test_chaining.html | [finished chain.ogg-0 t=180.012] Length of array should match number of running tests 23:08:55 INFO - 332 INFO TEST-PASS | dom/media/test/test_chaining.html | [started variable-channel.ogg-2 t=180.013] Length of array should match number of running tests 23:08:55 INFO - 333 INFO [finished chain.opus-1] remaining= variable-channel.ogg-2 23:08:55 INFO - 334 INFO TEST-PASS | dom/media/test/test_chaining.html | [finished chain.opus-1 t=180.013] Length of array should match number of running tests 23:08:55 INFO - 335 INFO TEST-PASS | dom/media/test/test_chaining.html | [started variable-channel.opus-3 t=180.014] Length of array should match number of running tests 23:08:55 INFO - 336 INFO TEST-PASS | dom/media/test/test_chaining.html | The metadata index value should increase.http://mochi.test:8888/tests/dom/media/test/variable-channel.ogg 23:08:55 INFO - 337 INFO TEST-PASS | dom/media/test/test_chaining.html | The currenttime should be increased correctly in new chained part. 23:08:55 INFO - 338 INFO TEST-PASS | dom/media/test/test_chaining.html | The metadata index value should increase.http://mochi.test:8888/tests/dom/media/test/variable-channel.opus 23:08:55 INFO - 339 INFO TEST-PASS | dom/media/test/test_chaining.html | The currenttime should be increased correctly in new chained part. 23:11:55 INFO - Not taking screenshot here: see the one that was previously logged 23:11:55 ERROR - 340 INFO TEST-UNEXPECTED-FAIL | dom/media/test/test_chaining.html | Test timed out! 23:11:55 INFO - onTimeout@dom/media/test/manifest.js:1717:7 23:11:55 INFO - 341 INFO variable-channel.ogg-2 timed out! 23:11:55 INFO - Not taking screenshot here: see the one that was previously logged 23:11:55 ERROR - 342 INFO TEST-UNEXPECTED-FAIL | dom/media/test/test_chaining.html | Test timed out!
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.