Intermittent dom/media/test/test_seek-8.html | single tracking bug
Categories
(Core :: Audio/Video: Playback, defect, P5)
Tracking
()
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure, intermittent-testcase)
Filed by: nfay [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=408621924&repo=try
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/PAtW5777QKiHWG9nr-Koxw/runs/0/artifacts/public/logs/live_backing.log
[task 2023-03-11T13:03:05.989Z] 13:03:05 INFO - TEST-PASS | dom/media/test/test_seek-8.html | [finished sine.webm-5-seek8.js t=0.852] Length of array should match number of running tests
[task 2023-03-11T13:03:05.990Z] 13:03:05 INFO - TEST-PASS | dom/media/test/test_seek-8.html | [started detodos.opus-8-seek8.js t=0.853] Length of array should match number of running tests
[task 2023-03-11T13:03:05.990Z] 13:03:05 INFO - TEST-PASS | dom/media/test/test_seek-8.html | detodos.opus seek test 8: currentTime 2.9135 close to 2.9135
[task 2023-03-11T13:03:05.990Z] 13:03:05 INFO - [finished detodos.opus-8-seek8.js] remaining= split.webm-7-seek8.js
[task 2023-03-11T13:03:05.991Z] 13:03:05 INFO - TEST-PASS | dom/media/test/test_seek-8.html | [finished detodos.opus-8-seek8.js t=0.96] Length of array should match number of running tests
[task 2023-03-11T13:03:05.992Z] 13:03:05 INFO - TEST-PASS | dom/media/test/test_seek-8.html | [started gizmo.mp4-9-seek8.js t=0.962] Length of array should match number of running tests
[task 2023-03-11T13:03:05.993Z] 13:03:05 INFO - Buffered messages finished
[task 2023-03-11T13:03:05.993Z] 13:03:05 INFO - TEST-UNEXPECTED-FAIL | dom/media/test/test_seek-8.html | Test timed out!
[task 2023-03-11T13:03:05.993Z] 13:03:05 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:421:16
[task 2023-03-11T13:03:05.993Z] 13:03:05 INFO - onTimeout@dom/media/test/manifest.js:2367:9
[task 2023-03-11T13:03:05.994Z] 13:03:05 INFO - split.webm-7-seek8.js timed out!
[task 2023-03-11T13:03:05.999Z] 13:03:05 INFO - {"EMEInfo":{"keySystem":"","sessionsInfo":""},"compositorDroppedFrames":0,"decoder":{"PlayState":"LOADING","channels":0,"containerType":"video/webm","hasAudio":false,"hasVideo":false,"instance":"11ff295e0000","rate":0,"reader":{"audioChannels":0,"audioDecoderName":"unavailable","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":"none","frameStats":{"droppedCompositorFrames":0,"droppedDecodedFrames":0,"droppedSinkFrames":0},"videoDecoderName":"unavailable","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":"none","videoWidth":0},"resource":{"cacheStream":{"cacheSuspended":false,"channelEnded":false,"channelOffset":0,"loadID":0,"streamLength":0}},"stateMachine":{"audioCompleted":false,"audioRequestStatus":"idle","clock":-1,"decodedAudioEndTime":0,"decodedVideoEndTime":0,"duration":-1,"isPlaying":false,"mediaSink":{"audioSinkWrapper":{"audioEnded":true,"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":true,"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":"idle"}}}
[task 2023-03-11T13:03:06.000Z] 13:03:06 INFO - [finished split.webm-7-seek8.js] remaining= gizmo.mp4-9-seek8.js
[task 2023-03-11T13:03:06.001Z] 13:03:06 INFO - TEST-PASS | dom/media/test/test_seek-8.html | [finished split.webm-7-seek8.js t=180.852] Length of array should match number of running tests
[task 2023-03-11T13:03:06.001Z] 13:03:06 INFO - TEST-PASS | dom/media/test/test_seek-8.html | [started owl.mp3-10-seek8.js t=180.854] Length of array should match number of running tests
[task 2023-03-11T13:03:06.002Z] 13:03:06 INFO - GECKO(1748) | SEEK-TEST: Started owl.mp3 seek test 8
[task 2023-03-11T13:03:06.002Z] 13:03:06 INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-03-11T13:03:06.003Z] 13:03:06 INFO - TEST-UNEXPECTED-FAIL | dom/media/test/test_seek-8.html | Test timed out!
[task 2023-03-11T13:03:06.003Z] 13:03:06 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:421:16
[task 2023-03-11T13:03:06.003Z] 13:03:06 INFO - onTimeout@dom/media/test/manifest.js:2367:9
[task 2023-03-11T13:03:06.003Z] 13:03:06 INFO - gizmo.mp4-9-seek8.js timed out!
[task 2023-03-11T13:03:06.007Z] 13:03:06 INFO - {"EMEInfo":{"keySystem":"","sessionsInfo":""},"compositorDroppedFrames":0,"decoder":{"PlayState":"LOADING","channels":0,"containerType":"video/mp4","hasAudio":false,"hasVideo":false,"instance":"11ff295b0f80","rate":0,"reader":{"audioChannels":0,"audioDecoderName":"unavailable","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":"none","frameStats":{"droppedCompositorFrames":0,"droppedDecodedFrames":0,"droppedSinkFrames":0},"videoDecoderName":"unavailable","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":"none","videoWidth":0},"resource":{"cacheStream":{"cacheSuspended":false,"channelEnded":false,"channelOffset":0,"loadID":0,"streamLength":0}},"stateMachine":{"audioCompleted":false,"audioRequestStatus":"idle","clock":-1,"decodedAudioEndTime":0,"decodedVideoEndTime":0,"duration":-1,"isPlaying":false,"mediaSink":{"audioSinkWrapper":{"audioEnded":true,"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":true,"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":"idle"}}}
[task 2023-03-11T13:03:06.007Z] 13:03:06 INFO - [finished gizmo.mp4-9-seek8.js] remaining= owl.mp3-10-seek8.js
[task 2023-03-11T13:03:06.011Z] 13:03:06 INFO - TEST-PASS | dom/media/test/test_seek-8.html | [finished gizmo.mp4-9-seek8.js t=181.005] Length of array should match number of running tests
[task 2023-03-11T13:03:06.012Z] 13:03:06 INFO - TEST-PASS | dom/media/test/test_seek-8.html | [started bug482461-theora.ogv-11-seek8.js t=181.008] Length of array should match number of running tests
[task 2023-03-11T13:03:06.012Z] 13:03:06 INFO - GECKO(1748) | SEEK-TEST: Started bug482461-theora.ogv seek test 8
[task 2023-03-11T13:03:08.628Z] 13:03:08 INFO - GECKO(1748) | 1678539788638 addons.xpi ERROR System addon update list error Error: got node name: html, expected: updates
[task 2023-03-11T13:04:36.209Z] 13:04:36 INFO - console.error: (new TypeError("connection not specified or invalid.", "resource://gre/modules/Sqlite.sys.mjs", 1363))
[task 2023-03-11T13:06:05.795Z] 13:06:05 INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-03-11T13:06:05.801Z] 13:06:05 INFO - TEST-UNEXPECTED-FAIL | dom/media/test/test_seek-8.html | Test timed out!
[task 2023-03-11T13:06:05.801Z] 13:06:05 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:421:16
[task 2023-03-11T13:06:05.801Z] 13:06:05 INFO - onTimeout@dom/media/test/manifest.js:2367:9
[task 2023-03-11T13:06:05.802Z] 13:06:05 INFO - owl.mp3-10-seek8.js timed out!
[task 2023-03-11T13:06:05.842Z] 13:06:05 INFO - {"EMEInfo":{"keySystem":"","sessionsInfo":""},"compositorDroppedFrames":0,"decoder":{"PlayState":"LOADING","channels":0,"containerType":"audio/mpeg","hasAudio":false,"hasVideo":false,"instance":"11ff293fb780","rate":0,"reader":{"audioChannels":0,"audioDecoderName":"unavailable","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":"none","frameStats":{"droppedCompositorFrames":0,"droppedDecodedFrames":0,"droppedSinkFrames":0},"videoDecoderName":"unavailable","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":"none","videoWidth":0},"resource":{"cacheStream":{"cacheSuspended":false,"channelEnded":false,"channelOffset":0,"loadID":0,"streamLength":0}},"stateMachine":{"audioCompleted":false,"audioRequestStatus":"idle","clock":-1,"decodedAudioEndTime":0,"decodedVideoEndTime":0,"duration":-1,"isPlaying":false,"mediaSink":{"audioSinkWrapper":{"audioEnded":true,"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":true,"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":"idle"}}}
[task 2023-03-11T13:06:05.854Z] 13:06:05 INFO - [finished owl.mp3-10-seek8.js] remaining= bug482461-theora.ogv-11-seek8.js
[task 2023-03-11T13:06:05.855Z] 13:06:05 INFO - TEST-PASS | dom/media/test/test_seek-8.html | [finished owl.mp3-10-seek8.js t=360.877] Length of array should match number of running tests
[task 2023-03-11T13:06:05.952Z] 13:06:05 INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-03-11T13:06:05.956Z] 13:06:05 INFO - TEST-UNEXPECTED-FAIL | dom/media/test/test_seek-8.html | Test timed out!
[task 2023-03-11T13:06:05.956Z] 13:06:05 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:421:16
[task 2023-03-11T13:06:05.956Z] 13:06:05 INFO - onTimeout@dom/media/test/manifest.js:2367:9
[task 2023-03-11T13:06:05.957Z] 13:06:05 INFO - bug482461-theora.ogv-11-seek8.js timed out!
[task 2023-03-11T13:06:05.999Z] 13:06:05 INFO - {"EMEInfo":{"keySystem":"","sessionsInfo":""},"compositorDroppedFrames":0,"decoder":{"PlayState":"LOADING","channels":0,"containerType":"application/ogg","hasAudio":false,"hasVideo":false,"instance":"11ff29836100","rate":0,"reader":{"audioChannels":0,"audioDecoderName":"unavailable","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":"none","frameStats":{"droppedCompositorFrames":0,"droppedDecodedFrames":0,"droppedSinkFrames":0},"videoDecoderName":"unavailable","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":"none","videoWidth":0},"resource":{"cacheStream":{"cacheSuspended":false,"channelEnded":false,"channelOffset":0,"loadID":0,"streamLength":0}},"stateMachine":{"audioCompleted":false,"audioRequestStatus":"idle","clock":-1,"decodedAudioEndTime":0,"decodedVideoEndTime":0,"duration":-1,"isPlaying":false,"mediaSink":{"audioSinkWrapper":{"audioEnded":true,"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":true,"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":"idle"}}}
[task 2023-03-11T13:06:06.005Z] 13:06:06 INFO - [finished bug482461-theora.ogv-11-seek8.js] remaining=
[task 2023-03-11T13:06:06.006Z] 13:06:06 INFO - TEST-PASS | dom/media/test/test_seek-8.html | [finished bug482461-theora.ogv-11-seek8.js t=361.027] Length of array should match number of running tests
[task 2023-03-11T13:06:06.006Z] 13:06:06 INFO - GECKO(1748) | [Child 2768, MediaDecoderStateMachine #1] WARNING: Decoder=11ff295e0000 state=DECODING_METADATA Decode metadata failed, shutting down decoder: file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp:372
[task 2023-03-11T13:06:06.007Z] 13:06:06 INFO - GECKO(1748) | [Child 2768, MediaDecoderStateMachine #1] WARNING: Decoder=11ff295e0000 Decode error: NS_ERROR_DOM_MEDIA_METADATA_ERR (0x806e0006): file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachineBase.cpp:164
[task 2023-03-11T13:06:06.008Z] 13:06:06 INFO - GECKO(1748) | [Child 2768, MediaDecoderStateMachine #1] WARNING: Decoder=11ff293fb780 state=DECODING_METADATA Decode metadata failed, shutting down decoder: file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp:372
[task 2023-03-11T13:06:06.008Z] 13:06:06 INFO - GECKO(1748) | [Child 2768, MediaDecoderStateMachine #1] WARNING: Decoder=11ff293fb780 Decode error: NS_ERROR_DOM_MEDIA_METADATA_ERR (0x806e0006): file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachineBase.cpp:164
[task 2023-03-11T13:06:06.077Z] 13:06:06 INFO - Finished at Sat Mar 11 2023 13:06:06 GMT+0000 (Greenwich Mean Time) (1678539966.08s)
[task 2023-03-11T13:06:06.084Z] 13:06:06 INFO - Running time: 361.113s
[task 2023-03-11T13:06:06.125Z] 13:06:06 INFO - GECKO(1748) | MEMORY STAT | vsize 19413853MB | vsizeMaxContiguous 74680868MB | residentFast 844MB
[task 2023-03-11T13:06:06.140Z] 13:06:06 INFO - TEST-OK | dom/media/test/test_seek-8.html | took 361482ms
[task 2023-03-11T13:06:06.234Z] 13:06:06 INFO - TEST-START | dom/media/test/test_seek-9.html
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
Comment 3•3 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Comment 4•2 years ago
|
||
Tier 1 failure here.
| Reporter | ||
Comment 5•2 years ago
|
||
| treeherder | ||
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=417425750&repo=autoland
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
Comment 9•2 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
| Reporter | ||
Comment 10•2 years ago
|
||
| treeherder | ||
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=430974176&repo=mozilla-beta
| Comment hidden (Intermittent Failures Robot) |
Comment 12•2 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
| Reporter | ||
Comment 13•1 year ago
|
||
| treeherder | ||
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=459121574&repo=autoland
| Comment hidden (Intermittent Failures Robot) |
Comment 15•1 year ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
Description
•