1820343 - Intermittent dom/media/test/test_seek-4.html | single tracking bug

Status: RESOLVED INCOMPLETE

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

Description

[Treeherder Bug Filer]

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

---

task 2023-03-04T13:05:45.040Z] 13:05:45     INFO - TEST-PASS | dom/media/test/test_seek-4.html | [started gizmo.mp4-9-seek4.js t=1.071] Length of array should match number of running tests 
[task 2023-03-04T13:05:45.041Z] 13:05:45     INFO - Buffered messages finished
[task 2023-03-04T13:05:45.041Z] 13:05:45     INFO - TEST-UNEXPECTED-FAIL | dom/media/test/test_seek-4.html | Test timed out! 
[task 2023-03-04T13:05:45.041Z] 13:05:45     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:421:16
[task 2023-03-04T13:05:45.042Z] 13:05:45     INFO -     onTimeout@dom/media/test/manifest.js:2367:9
[task 2023-03-04T13:05:45.042Z] 13:05:45     INFO - split.webm-7-seek4.js timed out!
[task 2023-03-04T13:05:45.043Z] 13:05:45     INFO - {"EMEInfo":{"keySystem":"","sessionsInfo":""},"compositorDroppedFrames":0,"decoder":{"PlayState":"LOADING","channels":0,"containerType":"video/webm","hasAudio":false,"hasVideo":false,"instance":"12833b1d8180","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-04T13:05:45.047Z] 13:05:45     INFO - [finished split.webm-7-seek4.js] remaining= gizmo.mp4-9-seek4.js
[task 2023-03-04T13:05:45.048Z] 13:05:45     INFO - TEST-PASS | dom/media/test/test_seek-4.html | [finished split.webm-7-seek4.js t=180.898] Length of array should match number of running tests 
[task 2023-03-04T13:05:45.048Z] 13:05:45     INFO - TEST-PASS | dom/media/test/test_seek-4.html | [started owl.mp3-10-seek4.js t=180.901] Length of array should match number of running tests 
[task 2023-03-04T13:05:45.049Z] 13:05:45     INFO - GECKO(9244) | SEEK-TEST: Started owl.mp3 seek test 4
[task 2023-03-04T13:05:45.049Z] 13:05:45     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-03-04T13:05:45.053Z] 13:05:45     INFO - TEST-UNEXPECTED-FAIL | dom/media/test/test_seek-4.html | Test timed out! 
[task 2023-03-04T13:05:45.053Z] 13:05:45     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:421:16
[task 2023-03-04T13:05:45.053Z] 13:05:45     INFO -     onTimeout@dom/media/test/manifest.js:2367:9
[task 2023-03-04T13:05:45.053Z] 13:05:45     INFO - gizmo.mp4-9-seek4.js timed out!
[task 2023-03-04T13:05:45.096Z] 13:05:45     INFO - {"EMEInfo":{"keySystem":"","sessionsInfo":""},"compositorDroppedFrames":0,"decoder":{"PlayState":"LOADING","channels":0,"containerType":"video/mp4","hasAudio":false,"hasVideo":false,"instance":"12833b1dc080","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-04T13:05:45.101Z] 13:05:45     INFO - [finished gizmo.mp4-9-seek4.js] remaining= owl.mp3-10-seek4.js
[task 2023-03-04T13:05:45.102Z] 13:05:45     INFO - TEST-PASS | dom/media/test/test_seek-4.html | [finished gizmo.mp4-9-seek4.js t=181.124] Length of array should match number of running tests 
[task 2023-03-04T13:05:45.102Z] 13:05:45     INFO - TEST-PASS | dom/media/test/test_seek-4.html | [started bug482461-theora.ogv-11-seek4.js t=181.126] Length of array should match number of running tests 
[task 2023-03-04T13:05:45.103Z] 13:05:45     INFO - GECKO(9244) | SEEK-TEST: Started bug482461-theora.ogv seek test 4
[task 2023-03-04T13:06:11.334Z] 13:06:11     INFO - GECKO(9244) | console.error: (new Error("Polling for changes failed: Unexpected content-type \"text/plain;charset=US-ASCII\".", "resource://services-settings/remote-settings.sys.mjs", 325))
[task 2023-03-04T13:07:39.234Z] 13:07:39     INFO -  console.error: (new TypeError("connection not specified or invalid.", "resource://gre/modules/Sqlite.sys.mjs", 1363))
[task 2023-03-04T13:08:11.671Z] 13:08:11     INFO - GECKO(9244) | 1677935291672	addons.xpi	ERROR	System addon update list error Error: got node name: html, expected: updates
[task 2023-03-04T13:08:44.872Z] 13:08:44     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-03-04T13:08:44.876Z] 13:08:44     INFO - TEST-UNEXPECTED-FAIL | dom/media/test/test_seek-4.html | Test timed out! 
[task 2023-03-04T13:08:44.876Z] 13:08:44     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:421:16
[task 2023-03-04T13:08:44.876Z] 13:08:44     INFO -     onTimeout@dom/media/test/manifest.js:2367:9
[task 2023-03-04T13:08:44.877Z] 13:08:44     INFO - owl.mp3-10-seek4.js timed out!
[task 2023-03-04T13:08:44.919Z] 13:08:44     INFO - {"EMEInfo":{"keySystem":"","sessionsInfo":""},"compositorDroppedFrames":0,"decoder":{"PlayState":"LOADING","channels":0,"containerType":"audio/mpeg","hasAudio":false,"hasVideo":false,"instance":"12833ad4f680","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-04T13:08:44.922Z] 13:08:44     INFO - [finished owl.mp3-10-seek4.js] remaining= bug482461-theora.ogv-11-seek4.js
[task 2023-03-04T13:08:44.922Z] 13:08:44     INFO - TEST-PASS | dom/media/test/test_seek-4.html | [finished owl.mp3-10-seek4.js t=360.945] Length of array should match number of running tests 
[task 2023-03-04T13:08:45.107Z] 13:08:45     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-03-04T13:08:45.111Z] 13:08:45     INFO - TEST-UNEXPECTED-FAIL | dom/media/test/test_seek-4.html | Test timed out! 
[task 2023-03-04T13:08:45.111Z] 13:08:45     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:421:16
[task 2023-03-04T13:08:45.111Z] 13:08:45     INFO -     onTimeout@dom/media/test/manifest.js:2367:9
[task 2023-03-04T13:08:45.111Z] 13:08:45     INFO - bug482461-theora.ogv-11-seek4.js timed out!
[task 2023-03-04T13:08:45.140Z] 13:08:45     INFO - {"EMEInfo":{"keySystem":"","sessionsInfo":""},"compositorDroppedFrames":0,"decoder":{"PlayState":"LOADING","channels":0,"containerType":"application/ogg","hasAudio":false,"hasVideo":false,"instance":"12833ada1380","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-04T13:08:45.153Z] 13:08:45     INFO - [finished bug482461-theora.ogv-11-seek4.js] remaining= 

Comment 1

[Intermittent Failures Robot]

1 failures in 3752 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• windows11-64-2009-asan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1820343&startday=2023-02-27&endday=2023-03-05&tree=all

Comment 2

[Intermittent Failures Robot]

1 failures in 4161 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• windows11-64-2009-asan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1820343&startday=2023-03-20&endday=2023-03-26&tree=all

Comment 3

[Intermittent Failures Robot]

1 failures in 3386 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 1

Platform and build breakdown:

• windows11-64-2009-asan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1820343&startday=2023-04-03&endday=2023-04-09&tree=all

Comment 4

[Intermittent Failures Robot]

2 failures in 3177 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 2

Platform and build breakdown:

• windows11-32-2009-qr: 1
  • debug: 1
• windows11-64-2009-asan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1820343&startday=2023-04-24&endday=2023-04-30&tree=all

Comment 5

[Intermittent Failures Robot]

1 failures in 3309 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• windows11-64-2009-asan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1820343&startday=2023-07-10&endday=2023-07-16&tree=all

Comment 6

[Intermittent Failures Robot]

2 failures in 3403 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1
• mozilla-central: 1

Platform and build breakdown:

• windows11-64-2009-asan-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1820343&startday=2023-07-24&endday=2023-07-30&tree=all

Comment 7

[Intermittent Failures Robot]

2 failures in 3341 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 2

Platform and build breakdown:

• windows11-64-2009-asan-qr: 1
  • opt: 1
• windows11-32-2009-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1820343&startday=2023-08-07&endday=2023-08-13&tree=all

Comment 8

[Intermittent Failures Robot]

2 failures in 3076 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1
• mozilla-central: 1

Platform and build breakdown:

• windows11-64-2009-asan-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1820343&startday=2023-08-14&endday=2023-08-20&tree=all

Comment 9

[BugBot [:suhaib / :marco]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.