Closed Bug 1780870 Opened 4 years ago Closed 4 years ago

Intermittent dom/media/test/test_fragment_noplay.html | Test timed out!

Categories

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

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

Tracking

()

Status:
RESOLVED DUPLICATE of bug 1781552

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

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

[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -  TEST-PASS | dom/media/test/test_fragment_noplay.html | [finished big.wav#t=3-7-4 t=1.125] Length of array should match number of running tests
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -  TEST-PASS | dom/media/test/test_fragment_noplay.html | [started big.wav#t=3,7,9-6 t=1.127] Length of array should match number of running tests
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -  TEST-PASS | dom/media/test/test_fragment_noplay.html | big.wav#t=3:7 fragment test: loadedmetadata currentTime is -0.15 < 0 < 0.15
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -  TEST-PASS | dom/media/test/test_fragment_noplay.html | big.wav#t=3:7 fragment test: mozFragmentEnd (9.287982) == end Time (9.287982)
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -  [finished big.wav#t=3:7-5] remaining= big.wav#t=3,7,9-6
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -  TEST-PASS | dom/media/test/test_fragment_noplay.html | [finished big.wav#t=3:7-5 t=1.199] Length of array should match number of running tests
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -  TEST-PASS | dom/media/test/test_fragment_noplay.html | [started big.wav#t%3D3-7 t=1.201] Length of array should match number of running tests
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -  Buffered messages finished
[task 2022-07-22T20:07:18.008Z] 20:07:17  WARNING -  TEST-UNEXPECTED-FAIL | dom/media/test/test_fragment_noplay.html | Test timed out!
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -      SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -      onTimeout@dom/media/test/manifest.js:2364:9
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -  big.wav#t=3,7,9-6 timed out!
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -  {"EMEInfo":{"keySystem":"","sessionsInfo":""},"compositorDroppedFrames":0,"decoder":{"PlayState":"","channels":0,"containerType":"","hasAudio":false,"hasVideo":false,"instance":"","rate":0,"reader":{"audioChannels":0,"audioDecoderName":"","audioFramesDecoded":0,"audioRate":0,"audioState":{"demuxEOS":0,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":0,"needInput":false,"numSamplesInput":0,"numSamplesOutput":0,"pending":0,"queueSize":0,"timeTreshold":0,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"audioType":"","frameStats":{"droppedCompositorFrames":0,"droppedDecodedFrames":0,"droppedSinkFrames":0},"videoDecoderName":"","videoHardwareAccelerated":false,"videoHeight":0,"videoNumSamplesOutputTotal":0,"videoNumSamplesSkippedTotal":0,"videoRate":0,"videoState":{"demuxEOS":0,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":0,"needInput":false,"numSamplesInput":0,"numSamplesOutput":0,"pending":0,"queueSize":0,"timeTreshold":0,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"videoType":"","videoWidth":0},"resource":{"cacheStream":{"cacheSuspended":false,"channelEnded":false,"channelOffset":0,"loadID":0,"streamLength":0}},"stateMachine":{"audioCompleted":false,"audioRequestStatus":"","clock":0,"decodedAudioEndTime":0,"decodedVideoEndTime":0,"duration":0,"isPlaying":false,"mediaSink":{"audioSinkWrapper":{"audioEnded":false,"audioSink":{"audioEnded":false,"hasErrored":false,"isPlaying":false,"isStarted":false,"lastGoodPosition":0,"outputRate":0,"playbackComplete":false,"startTime":0,"written":0},"isPlaying":false,"isStarted":false},"decodedStream":{"audioQueueFinished":false,"audioQueueSize":0,"data":{"audioFramesWritten":0,"haveSentFinishAudio":false,"haveSentFinishVideo":false,"instance":"","lastVideoEndTime":0,"lastVideoStartTime":0,"nextAudioTime":0,"streamAudioWritten":0,"streamVideoWritten":0},"instance":"","lastAudio":0,"lastOutputTime":0,"playing":0,"startTime":0},"videoSink":{"endPromiseHolderIsEmpty":false,"finished":false,"hasVideo":false,"isPlaying":false,"isStarted":false,"size":0,"videoFrameEndTime":0,"videoSinkEndRequestExists":false}},"mediaTime":0,"playState":0,"sentFirstFrameLoadedEvent":false,"state":"","stateObj":{"isPrerolling":false},"videoCompleted":false,"videoRequestStatus":""}}}
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -  [finished big.wav#t=3,7,9-6] remaining= big.wav#t%3D3-7
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -  TEST-PASS | dom/media/test/test_fragment_noplay.html | [finished big.wav#t=3,7,9-6 t=181.388] Length of array should match number of running tests
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -  TEST-PASS | dom/media/test/test_fragment_noplay.html | [started big.wav#%74=3-8 t=181.392] Length of array should match number of running tests
[task 2022-07-22T20:07:18.008Z] 20:07:17  WARNING -  TEST-UNEXPECTED-FAIL | dom/media/test/test_fragment_noplay.html | Test timed out!
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -      SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -      onTimeout@dom/media/test/manifest.js:2364:9
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -  big.wav#t%3D3-7 timed out!
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -  {"EMEInfo":{"keySystem":"","sessionsInfo":""},"compositorDroppedFrames":0,"decoder":{"PlayState":"","channels":0,"containerType":"","hasAudio":false,"hasVideo":false,"instance":"","rate":0,"reader":{"audioChannels":0,"audioDecoderName":"","audioFramesDecoded":0,"audioRate":0,"audioState":{"demuxEOS":0,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":0,"needInput":false,"numSamplesInput":0,"numSamplesOutput":0,"pending":0,"queueSize":0,"timeTreshold":0,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"audioType":"","frameStats":{"droppedCompositorFrames":0,"droppedDecodedFrames":0,"droppedSinkFrames":0},"videoDecoderName":"","videoHardwareAccelerated":false,"videoHeight":0,"videoNumSamplesOutputTotal":0,"videoNumSamplesSkippedTotal":0,"videoRate":0,"videoState":{"demuxEOS":0,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":0,"needInput":false,"numSamplesInput":0,"numSamplesOutput":0,"pending":0,"queueSize":0,"timeTreshold":0,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"videoType":"","videoWidth":0},"resource":{"cacheStream":{"cacheSuspended":false,"channelEnded":false,"channelOffset":0,"loadID":0,"streamLength":0}},"stateMachine":{"audioCompleted":false,"audioRequestStatus":"","clock":0,"decodedAudioEndTime":0,"decodedVideoEndTime":0,"duration":0,"isPlaying":false,"mediaSink":{"audioSinkWrapper":{"audioEnded":false,"audioSink":{"audioEnded":false,"hasErrored":false,"isPlaying":false,"isStarted":false,"lastGoodPosition":0,"outputRate":0,"playbackComplete":false,"startTime":0,"written":0},"isPlaying":false,"isStarted":false},"decodedStream":{"audioQueueFinished":false,"audioQueueSize":0,"data":{"audioFramesWritten":0,"haveSentFinishAudio":false,"haveSentFinishVideo":false,"instance":"","lastVideoEndTime":0,"lastVideoStartTime":0,"nextAudioTime":0,"streamAudioWritten":0,"streamVideoWritten":0},"instance":"","lastAudio":0,"lastOutputTime":0,"playing":0,"startTime":0},"videoSink":{"endPromiseHolderIsEmpty":false,"finished":false,"hasVideo":false,"isPlaying":false,"isStarted":false,"size":0,"videoFrameEndTime":0,"videoSinkEndRequestExists":false}},"mediaTime":0,"playState":0,"sentFirstFrameLoadedEvent":false,"state":"","stateObj":{"isPrerolling":false},"videoCompleted":false,"videoRequestStatus":""}}}
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -  [finished big.wav#t%3D3-7] remaining= big.wav#%74=3-8
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -  TEST-PASS | dom/media/test/test_fragment_noplay.html | [finished big.wav#t%3D3-7 t=181.646] Length of array should match number of running tests
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -  TEST-PASS | dom/media/test/test_fragment_noplay.html | [started big.wav#t=%33-9 t=181.648] Length of array should match number of running tests
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -  TEST-PASS | dom/media/test/test_fragment_noplay.html | big.wav#%74=3 fragment test: loadedmetadata currentTime is 2.85 < 3 < 3.15
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -  TEST-PASS | dom/media/test/test_fragment_noplay.html | big.wav#%74=3 fragment test: mozFragmentEnd (9.287982) == end Time (9.287982)
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -  [finished big.wav#%74=3-8] remaining= big.wav#t=%33-9
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -  TEST-PASS | dom/media/test/test_fragment_noplay.html | [finished big.wav#%74=3-8 t=181.875] Length of array should match number of running tests
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -  TEST-PASS | dom/media/test/test_fragment_noplay.html | [started big.wav#t=3%2C7-10 t=181.878] Length of array should match number of running tests
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -  TEST-PASS | dom/media/test/test_fragment_noplay.html | big.wav#t=%33 fragment test: loadedmetadata currentTime is 2.85 < 3 < 3.15
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -  TEST-PASS | dom/media/test/test_fragment_noplay.html | big.wav#t=%33 fragment test: mozFragmentEnd (9.287982) == end Time (9.287982)
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -  [finished big.wav#t=%33-9] remaining= big.wav#t=3%2C7-10
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -  TEST-PASS | dom/media/test/test_fragment_noplay.html | [finished big.wav#t=%33-9 t=181.949] Length of array should match number of running tests
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -  TEST-PASS | dom/media/test/test_fragment_noplay.html | [started big.wav#t=%6Ept:3-11 t=181.951] Length of array should match number of running tests
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -  TEST-PASS | dom/media/test/test_fragment_noplay.html | big.wav#t=3%2C7 fragment test: loadedmetadata currentTime is 2.85 < 3 < 3.15
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -  TEST-PASS | dom/media/test/test_fragment_noplay.html | big.wav#t=3%2C7 fragment test: mozFragmentEnd (7) == end Time (7)
[task 2022-07-22T20:07:18.008Z] 20:07:17     INFO -  [finished big.wav#t=3%2C7-10] remaining= big.wav#t=%6Ept:3-11
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → DUPLICATE
You need to log in before you can comment on or make changes to this bug.