1674085 - Intermittent dom/media/test/test_hls_player_independency.html | Test timed out.

Status: RESOLVED INCOMPLETE

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

Description

[Treeherder Bug Filer]

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

---

[task 2020-10-29T08:53:47.180Z] 08:53:47     INFO -  310 INFO TEST-START | dom/media/test/test_hls_player_independency.html
[task 2020-10-29T08:59:01.474Z] 08:59:01     INFO -  Buffered messages logged at 08:53:46
[task 2020-10-29T08:59:01.475Z] 08:59:01     INFO -  311 INFO TEST-PASS | dom/media/test/test_hls_player_independency.html | A valid string reason is expected
[task 2020-10-29T08:59:01.475Z] 08:59:01     INFO -  312 INFO TEST-PASS | dom/media/test/test_hls_player_independency.html | Reason cannot be empty
[task 2020-10-29T08:59:01.475Z] 08:59:01     INFO -  Buffered messages logged at 08:53:51
[task 2020-10-29T08:59:01.475Z] 08:59:01     INFO -  313 INFO TEST-PASS | dom/media/test/test_hls_player_independency.html | 4x3 content, the width should be 400.
[task 2020-10-29T08:59:01.475Z] 08:59:01     INFO -  314 INFO TEST-PASS | dom/media/test/test_hls_player_independency.html | 4x3 content, the height should be 300.
[task 2020-10-29T08:59:01.475Z] 08:59:01     INFO -  Buffered messages finished
[task 2020-10-29T08:59:01.476Z] 08:59:01  WARNING -  315 INFO TEST-UNEXPECTED-FAIL | dom/media/test/test_hls_player_independency.html | Test timed out.
[task 2020-10-29T08:59:01.476Z] 08:59:01     INFO -      SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2020-10-29T08:59:01.476Z] 08:59:01     INFO -      reportError@SimpleTest/TestRunner.js:143:22
[task 2020-10-29T08:59:01.476Z] 08:59:01     INFO -      TestRunner._checkForHangs@SimpleTest/TestRunner.js:165:18
[task 2020-10-29T08:59:01.479Z] 08:59:01     INFO -  316 INFO {"EMEInfo":{"keySystem":"","sessionsInfo":""},"compositorDroppedFrames":0,"decoder":{"PlayState":"ENDED","channels":2,"containerType":"application/vnd.apple.mpegurl","hasAudio":true,"hasVideo":true,"instance":"7572db241800","rate":22050,"reader":{"audioChannels":2,"audioDecoderName":"android decoder (remote)","audioFramesDecoded":105,"audioRate":22.049999237060547,"audioState":{"demuxEOS":1,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":1,"needInput":false,"numSamplesInput":105,"numSamplesOutput":105,"pending":0,"queueSize":0,"timeTreshold":-1,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"audioType":"audio/mp4a-latm","frameStats":{"droppedCompositorFrames":0,"droppedDecodedFrames":0,"droppedSinkFrames":0},"videoDecoderName":"android decoder (remote)","videoHardwareAccelerated":false,"videoHeight":300,"videoNumSamplesOutputTotal":146,"videoNumSamplesSkippedTotal":0,"videoRate":29.766679763793945,"videoState":{"demuxEOS":1,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":3,"needInput":false,"numSamplesInput":146,"numSamplesOutput":146,"pending":0,"queueSize":0,"timeTreshold":-1,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"videoType":"video/avc","videoWidth":400},"resource":{"cacheStream":{"cacheSuspended":false,"channelEnded":false,"channelOffset":0,"loadID":0,"streamLength":0}},"stateMachine":{"audioCompleted":true,"audioRequestStatus":"idle","clock":-1,"decodedAudioEndTime":4919724,"decodedVideoEndTime":4938334,"duration":4938334,"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":true,"hasVideo":true,"isPlaying":false,"isStarted":false,"size":0,"videoFrameEndTime":0,"videoSinkEndRequestExists":false}},"mediaTime":4938334,"playState":4,"sentFirstFrameLoadedEvent":true,"state":"","stateObj":{"isPrerolling":false},"videoCompleted":true,"videoRequestStatus":"idle"}}}
[task 2020-10-29T08:59:01.481Z] 08:59:01     INFO -  317 INFO {"EMEInfo":{"keySystem":"","sessionsInfo":""},"compositorDroppedFrames":0,"decoder":{"PlayState":"PLAYING","channels":2,"containerType":"application/vnd.apple.mpegurl","hasAudio":true,"hasVideo":true,"instance":"7572db23d000","rate":22050,"reader":{"audioChannels":2,"audioDecoderName":"android decoder (remote)","audioFramesDecoded":24,"audioRate":22.049999237060547,"audioState":{"demuxEOS":0,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":2,"needInput":false,"numSamplesInput":5,"numSamplesOutput":1,"pending":0,"queueSize":4,"timeTreshold":-1,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"audioType":"audio/mp4a-latm","frameStats":{"droppedCompositorFrames":0,"droppedDecodedFrames":0,"droppedSinkFrames":0},"videoDecoderName":"android decoder (remote)","videoHardwareAccelerated":false,"videoHeight":234,"videoNumSamplesOutputTotal":36,"videoNumSamplesSkippedTotal":0,"videoRate":29.96944236755371,"videoState":{"demuxEOS":0,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":4,"needInput":false,"numSamplesInput":63,"numSamplesOutput":36,"pending":0,"queueSize":27,"timeTreshold":-1,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"videoType":"video/avc","videoWidth":416},"resource":{"cacheStream":{"cacheSuspended":false,"channelEnded":false,"channelOffset":0,"loadID":0,"streamLength":0}},"stateMachine":{"audioCompleted":false,"audioRequestStatus":"idle","clock":1113125,"decodedAudioEndTime":1114550,"decodedVideoEndTime":1234577,"duration":4040000,"isPlaying":true,"mediaSink":{"audioSinkWrapper":{"audioEnded":false,"audioSink":{"audioEnded":false,"hasErrored":false,"isPlaying":true,"isStarted":false,"lastGoodPosition":1113125,"outputRate":48000,"playbackComplete":false,"startTime":0,"written":53430},"isPlaying":true,"isStarted":true},"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":true,"isPlaying":true,"isStarted":true,"size":4,"videoFrameEndTime":1134466,"videoSinkEndRequestExists":false}},"mediaTime":1113125,"playState":3,"sentFirstFrameLoadedEvent":true,"state":"","stateObj":{"isPrerolling":false},"videoCompleted":false,"videoRequestStatus":"idle"}}}
[task 2020-10-29T08:59:01.481Z] 08:59:01     INFO -  318 INFO TEST-OK | dom/media/test/test_hls_player_independency.html | took 308624ms```

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• android-em-7-0-x86_64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1674085&startday=2020-10-26&endday=2020-11-01&tree=all

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• android-hw-p2-8-0-android-aarch64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1674085&startday=2020-11-09&endday=2020-11-15&tree=all

Comment 3

[BugBot [:suhaib / :marco]]

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