Open Bug 1661187 Opened 4 years ago Updated 3 months ago

Intermittent dom/media/mediacontrol/tests/browser/browser_resume_latest_paused_media.js | Test timed out -

Categories

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

defect

Tracking

()

REOPENED

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Depends on 1 open bug)

Details

(Keywords: intermittent-failure, leave-open, Whiteboard: [stockwell unknown])

Attachments

(1 file)

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


[task 2020-08-25T17:17:04.494Z] 17:17:04 INFO - TEST-START | dom/media/mediacontrol/tests/browser_resume_latest_paused_media.js
[task 2020-08-25T17:17:05.949Z] 17:17:05 INFO - GECKO(4863) | [Child 5066, MediaDecoderStateMachine #1] WARNING: Decoder=6170000e8580 Decode error: NS_ERROR_DOM_MEDIA_DECODE_ERR (0x806e0004) - virtual mozilla::MediaResult mozilla::FFmpegAudioDecoder<57>::DoDecode(mozilla::MediaRawData *, uint8_t *, int, bool *, mozilla::MediaDataDecoder::DecodedData &): FFmpeg audio error:-22: file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp, line 3470
[task 2020-08-25T17:17:06.775Z] 17:17:06 INFO - GECKO(4863) | [Child 5066, MediaDecoderStateMachine #1] WARNING: Decoder=6170000e8580 Decode error: NS_ERROR_DOM_MEDIA_DECODE_ERR (0x806e0004) - virtual mozilla::MediaResult mozilla::FFmpegAudioDecoder<57>::DoDecode(mozilla::MediaRawData *, uint8_t *, int, bool *, mozilla::MediaDataDecoder::DecodedData &): FFmpeg audio error:-22: file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp, line 3470
[task 2020-08-25T17:17:06.856Z] 17:17:06 INFO - GECKO(4863) | [Child 5066, MediaDecoderStateMachine #1] WARNING: Decoder=6170000e8580 Decode error: NS_ERROR_DOM_MEDIA_DECODE_ERR (0x806e0004) - virtual mozilla::MediaResult mozilla::FFmpegAudioDecoder<57>::DoDecode(mozilla::MediaRawData *, uint8_t *, int, bool *, mozilla::MediaDataDecoder::DecodedData &): FFmpeg audio error:-22: file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp, line 3470
[task 2020-08-25T17:17:06.920Z] 17:17:06 INFO - GECKO(4863) | [Child 5066, MediaDecoderStateMachine #1] WARNING: Decoder=6170000e8580 Decode error: NS_ERROR_DOM_MEDIA_DECODE_ERR (0x806e0004) - virtual mozilla::MediaResult mozilla::FFmpegAudioDecoder<57>::DoDecode(mozilla::MediaRawData *, uint8_t *, int, bool *, mozilla::MediaDataDecoder::DecodedData &): FFmpeg audio error:-22: file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp, line 3470
[task 2020-08-25T17:17:06.936Z] 17:17:06 INFO - GECKO(4863) | [Child 5066, MediaDecoderStateMachine #1] WARNING: Decoder=6170000e8580 Decode error: NS_ERROR_DOM_MEDIA_DECODE_ERR (0x806e0004) - virtual mozilla::MediaResult mozilla::FFmpegAudioDecoder<57>::DoDecode(mozilla::MediaRawData *, uint8_t *, int, bool *, mozilla::MediaDataDecoder::DecodedData &): FFmpeg audio error:-22: file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp, line 3470
[task 2020-08-25T17:17:07.006Z] 17:17:07 INFO - GECKO(4863) | [Child 5066, MediaDecoderStateMachine #1] WARNING: Decoder=6170000e8580 Decode error: NS_ERROR_DOM_MEDIA_DECODE_ERR (0x806e0004) - virtual mozilla::MediaResult mozilla::FFmpegAudioDecoder<57>::DoDecode(mozilla::MediaRawData *, uint8_t *, int, bool *, mozilla::MediaDataDecoder::DecodedData &): FFmpeg audio error:-22: file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp, line 3470
[task 2020-08-25T17:17:07.105Z] 17:17:07 INFO - GECKO(4863) | [Child 5066, MediaDecoderStateMachine #1] WARNING: Decoder=6170000e8580 Decode error: NS_ERROR_DOM_MEDIA_DECODE_ERR (0x806e0004) - virtual mozilla::MediaResult mozilla::FFmpegAudioDecoder<57>::DoDecode(mozilla::MediaRawData *, uint8_t *, int, bool *, mozilla::MediaDataDecoder::DecodedData &): FFmpeg audio error:-22: file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp, line 3470
[task 2020-08-25T17:17:07.203Z] 17:17:07 INFO - GECKO(4863) | [Child 5066, MediaDecoderStateMachine #1] WARNING: Decoder=6170000e8580 Decode error: NS_ERROR_DOM_MEDIA_DECODE_ERR (0x806e0004) - virtual mozilla::MediaResult mozilla::FFmpegAudioDecoder<57>::DoDecode(mozilla::MediaRawData *, uint8_t *, int, bool *, mozilla::MediaDataDecoder::DecodedData &): FFmpeg audio error:-22: file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp, line 3470
[task 2020-08-25T17:17:22.310Z] 17:17:22 INFO - GECKO(4863) | [Child 5066, MediaDecoderStateMachine #1] WARNING: Decoder=6170000e8580 Decode error: NS_ERROR_DOM_MEDIA_DECODE_ERR (0x806e0004) - virtual mozilla::MediaResult mozilla::FFmpegAudioDecoder<57>::DoDecode(mozilla::MediaRawData *, uint8_t *, int, bool *, mozilla::MediaDataDecoder::DecodedData &): FFmpeg audio error:-22: file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp, line 3470
[task 2020-08-25T17:18:34.508Z] 17:18:34 INFO - TEST-INFO | started process screentopng
[task 2020-08-25T17:18:35.183Z] 17:18:35 INFO - TEST-INFO | screentopng: exit 0
[task 2020-08-25T17:18:35.185Z] 17:18:35 INFO - Buffered messages logged at 17:17:04
[task 2020-08-25T17:18:35.186Z] 17:18:35 INFO - Entering test bound setupTestingPref
[task 2020-08-25T17:18:35.187Z] 17:18:35 INFO - Console message: [JavaScript Error: "NS_ERROR_FAILURE: Should pass a non-null uri" {file: "resource://gre/modules/URIFixup.jsm" line: 271}]
[task 2020-08-25T17:18:35.188Z] 17:18:35 INFO - getFixupURIInfo@resource://gre/modules/URIFixup.jsm:271:13
[task 2020-08-25T17:18:35.189Z] 17:18:35 INFO - _getUrlMetaData@resource:///modules/UrlbarValueFormatter.jsm:150:35
[task 2020-08-25T17:18:35.191Z] 17:18:35 INFO - _ensureFormattedHostVisible/<@resource:///modules/UrlbarValueFormatter.jsm:105:41
[task 2020-08-25T17:18:35.193Z] 17:18:35 INFO -
[task 2020-08-25T17:18:35.194Z] 17:18:35 INFO - Leaving test bound setupTestingPref
[task 2020-08-25T17:18:35.195Z] 17:18:35 INFO - Entering test bound testResumingLatestPausedMedias
[task 2020-08-25T17:18:35.196Z] 17:18:35 INFO - open media page and play all media
[task 2020-08-25T17:18:35.197Z] 17:18:35 INFO - Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "https://example.com/browser/dom/media/mediacontrol/tests/file_multiple_audible_media.html" line: 0}]
[task 2020-08-25T17:18:35.198Z] 17:18:35 INFO - Buffered messages logged at 17:17:05
[task 2020-08-25T17:18:35.199Z] 17:18:35 INFO - play video1 video
[task 2020-08-25T17:18:35.205Z] 17:18:35 INFO - play video2 video
[task 2020-08-25T17:18:35.207Z] 17:18:35 INFO - play video3 video
[task 2020-08-25T17:18:35.208Z] 17:18:35 INFO - Console message: [JavaScript Warning: "Media resource https://example.com/browser/dom/media/mediacontrol/tests/gizmo.mp4 could not be decoded." {file: "https://example.com/browser/dom/media/mediacontrol/tests/file_multiple_audible_media.html" line: 0}]
[task 2020-08-25T17:18:35.209Z] 17:18:35 INFO - Buffered messages logged at 17:17:06
[task 2020-08-25T17:18:35.210Z] 17:18:35 INFO - Console message: [JavaScript Warning: "Media resource https://example.com/browser/dom/media/mediacontrol/tests/gizmo.mp4 could not be decoded." {file: "https://example.com/browser/dom/media/mediacontrol/tests/file_multiple_audible_media.html" line: 0}]
[task 2020-08-25T17:18:35.211Z] 17:18:35 INFO - Console message: [JavaScript Warning: "Media resource https://example.com/browser/dom/media/mediacontrol/tests/gizmo.mp4 could not be decoded." {file: "https://example.com/browser/dom/media/mediacontrol/tests/file_multiple_audible_media.html" line: 0}]
[task 2020-08-25T17:18:35.212Z] 17:18:35 INFO - Console message: [JavaScript Warning: "Media resource https://example.com/browser/dom/media/mediacontrol/tests/gizmo.mp4 could not be decoded, error: Error Code: NS_ERROR_DOM_MEDIA_DECODE_ERR (0x806e0004)
[task 2020-08-25T17:18:35.213Z] 17:18:35 INFO - Details: virtual mozilla::MediaResult mozilla::FFmpegAudioDecoder<57>::DoDecode(mozilla::MediaRawData *, uint8_t *, int, bool *, mozilla::MediaDataDecoder::DecodedData &): FFmpeg audio error:-22" {file: "https://example.com/browser/dom/media/mediacontrol/tests/file_multiple_audible_media.html" line: 0}]
[task 2020-08-25T17:18:35.214Z] 17:18:35 INFO - Console message: [JavaScript Warning: "Media resource https://example.com/browser/dom/media/mediacontrol/tests/gizmo.mp4 could not be decoded." {file: "https://example.com/browser/dom/media/mediacontrol/tests/file_multiple_audible_media.html" line: 0}]
[task 2020-08-25T17:18:35.215Z] 17:18:35 INFO - Console message: [JavaScript Warning: "Media resource https://example.com/browser/dom/media/mediacontrol/tests/gizmo.mp4 could not be decoded." {file: "https://example.com/browser/dom/media/mediacontrol/tests/file_multiple_audible_media.html" line: 0}]
[task 2020-08-25T17:18:35.215Z] 17:18:35 INFO - Buffered messages logged at 17:17:07
[task 2020-08-25T17:18:35.221Z] 17:18:35 INFO - Console message: [JavaScript Warning: "Media resource https://example.com/browser/dom/media/mediacontrol/tests/gizmo.mp4 could not be decoded." {file: "https://example.com/browser/dom/media/mediacontrol/tests/file_multiple_audible_media.html" line: 0}]
[task 2020-08-25T17:18:35.222Z] 17:18:35 INFO - Console message: [JavaScript Warning: "Media resource https://example.com/browser/dom/media/mediacontrol/tests/gizmo.mp4 could not be decoded." {file: "https://example.com/browser/dom/media/mediacontrol/tests/file_multiple_audible_media.html" line: 0}]
[task 2020-08-25T17:18:35.223Z] 17:18:35 INFO - Console message: [JavaScript Warning: "Media resource https://example.com/browser/dom/media/mediacontrol/tests/gizmo.mp4 could not be decoded." {file: "https://example.com/browser/dom/media/mediacontrol/tests/file_multiple_audible_media.html" line: 0}]
[task 2020-08-25T17:18:35.224Z] 17:18:35 INFO - Console message: [JavaScript Warning: "Media resource https://example.com/browser/dom/media/mediacontrol/tests/gizmo.mp4 could not be decoded, error: Error Code: NS_ERROR_DOM_MEDIA_DECODE_ERR (0x806e0004)
[task 2020-08-25T17:18:35.225Z] 17:18:35 INFO - Details: virtual mozilla::MediaResult mozilla::FFmpegAudioDecoder<57>::DoDecode(mozilla::MediaRawData *, uint8_t *, int, bool *, mozilla::MediaDataDecoder::DecodedData &): FFmpeg audio error:-22" {file: "https://example.com/browser/dom/media/mediacontrol/tests/file_multiple_audible_media.html" line: 0}]
[task 2020-08-25T17:18:35.226Z] 17:18:35 INFO - Buffered messages logged at 17:17:22
[task 2020-08-25T17:18:35.227Z] 17:18:35 INFO - Console message: [JavaScript Warning: "Media resource https://example.com/browser/dom/media/mediacontrol/tests/gizmo.mp4 could not be decoded." {file: "https://example.com/browser/dom/media/mediacontrol/tests/file_multiple_audible_media.html" line: 0}]
[task 2020-08-25T17:18:35.228Z] 17:18:35 INFO - Buffered messages logged at 17:17:23
[task 2020-08-25T17:18:35.229Z] 17:18:35 INFO - Console message: [JavaScript Warning: "Media resource https://example.com/browser/dom/media/mediacontrol/tests/gizmo.mp4 could not be decoded, error: Error Code: NS_ERROR_DOM_MEDIA_DECODE_ERR (0x806e0004)
[task 2020-08-25T17:18:35.230Z] 17:18:35 INFO - Details: virtual mozilla::MediaResult mozilla::FFmpegAudioDecoder<57>::DoDecode(mozilla::MediaRawData *, uint8_t *, int, bool *, mozilla::MediaDataDecoder::DecodedData &): FFmpeg audio error:-22" {file: "https://example.com/browser/dom/media/mediacontrol/tests/file_multiple_audible_media.html" line: 0}]
[task 2020-08-25T17:18:35.231Z] 17:18:35 INFO - Buffered messages finished
[task 2020-08-25T17:18:35.232Z] 17:18:35 INFO - TEST-UNEXPECTED-FAIL | dom/media/mediacontrol/tests/browser_resume_latest_paused_media.js | Test timed out -
[task 2020-08-25T17:18:35.233Z] 17:18:35 INFO - GECKO(4863) | MEMORY STAT | vsize 20975519MB | residentFast 1214MB
[task 2020-08-25T17:18:35.234Z] 17:18:35 INFO - TEST-OK | dom/media/mediacontrol/tests/browser_resume_latest_paused_media.js | took 90061ms
[task 2020-08-25T17:18:35.235Z] 17:18:35 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-08-25T17:18:35.236Z] 17:18:35 INFO - TEST-UNEXPECTED-FAIL | dom/media/mediacontrol/tests/browser_resume_latest_paused_media.js | Found a tab after previous test timed out: https://example.com/browser/dom/media/mediacontrol/tests/file_multiple_audible_media.html -
[task 2020-08-25T17:18:35.237Z] 17:18:35 INFO - checking window state
[task 2020-08-25T17:18:35.238Z] 17:18:35 INFO - GECKO(4863) | JavaScript error: resource://gre/actors/AudioPlaybackParent.jsm, line 19: TypeError: browser is null
[task 2020-08-25T17:18:35.239Z] 17:18:35 INFO - TEST-START | dom/media/mediacontrol/tests/browser_stop_control_after_media_reaches_to_end.js
[task 2020-08-25T17:18:35.245Z] 17:18:35 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-08-25T17:18:35.246Z] 17:18:35 INFO - Buffered messages logged at 17:18:35
[task 2020-08-25T17:18:35.247Z] 17:18:35 INFO - Entering test bound setupTestingPref
[task 2020-08-25T17:18:35.248Z] 17:18:35 INFO - Console message: [JavaScript Error: "TypeError: browser is null" {file: "resource://gre/actors/AudioPlaybackParent.jsm" line: 19}]
[task 2020-08-25T17:18:35.249Z] 17:18:35 INFO - receiveMessage@resource://gre/actors/AudioPlaybackParent.jsm:19:9
[task 2020-08-25T17:18:35.250Z] 17:18:35 INFO -
[task 2020-08-25T17:18:35.251Z] 17:18:35 INFO - Buffered messages finished
[task 2020-08-25T17:18:35.252Z] 17:18:35 INFO - TEST-UNEXPECTED-FAIL | dom/media/mediacontrol/tests/browser_resume_latest_paused_media.js | Uncaught exception received from previously timed out test - AbortError: Actor 'SpecialPowers' destroyed before query 'Spawn' was resolved
[task 2020-08-25T17:18:35.253Z] 17:18:35 INFO - Leaving test bound setupTestingPref
[task 2020-08-25T17:18:35.254Z] 17:18:35 INFO - Entering test bound testControlShouldStopAfterMediaReachesToTheEnd
[task 2020-08-25T17:18:35.255Z] 17:18:35 INFO - open media page and play media until the end
[task 2020-08-25T17:18:35.407Z] 17:18:35 INFO - Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "https://example.com/browser/dom/media/mediacontrol/tests/file_non_looping_media.html" line: 0}]
[task 2020-08-25T17:18:35.599Z] 17:18:35 INFO - adjust the start position to faster reach to the end
[task 2020-08-25T17:18:35.600Z] 17:18:35 INFO - TEST-PASS | dom/media/mediacontrol/tests/browser_stop_control_after_media_reaches_to_end.js | video's duration is larger than 1.0s - true == true -
[task 2020-08-25T17:18:35.602Z] 17:18:35 INFO - play video video
[task 2020-08-25T17:18:35.643Z] 17:18:35 INFO - TEST-PASS | dom/media/mediacontrol/tests/browser_stop_control_after_media_reaches_to_end.js | Receive 1 times 'onactivechange' -
[task 2020-08-25T17:18:35.644Z] 17:18:35 INFO - TEST-PASS | dom/media/mediacontrol/tests/browser_stop_control_after_media_reaches_to_end.js | controller should be active -
[task 2020-08-25T17:18:35.644Z] 17:18:35 INFO - TEST-PASS | dom/media/mediacontrol/tests/browser_stop_control_after_media_reaches_to_end.js | controller should be audible -
[task 2020-08-25T17:18:35.644Z] 17:18:35 INFO - TEST-PASS | dom/media/mediacontrol/tests/browser_stop_control_after_media_reaches_to_end.js | controller should be playing -
[task 2020-08-25T17:18:38.842Z] 17:18:38 INFO - TEST-PASS | dom/media/mediacontrol/tests/browser_stop_control_after_media_reaches_to_end.js | Receive 2 times 'onactivechange' -
[task 2020-08-25T17:18:38.845Z] 17:18:38 INFO - TEST-PASS | dom/media/mediacontrol/tests/browser_stop_control_after_media_reaches_to_end.js | controller should be inactive -
[task 2020-08-25T17:18:38.846Z] 17:18:38 INFO - TEST-PASS | dom/media/mediacontrol/tests/browser_stop_control_after_media_reaches_to_end.js | controller should be inaudible -
[task 2020-08-25T17:18:38.848Z] 17:18:38 INFO - TEST-PASS | dom/media/mediacontrol/tests/browser_stop_control_after_media_reaches_to_end.js | controller should be paused -
[task 2020-08-25T17:18:38.856Z] 17:18:38 INFO - remove tab
[task 2020-08-25T17:18:38.897Z] 17:18:38 INFO - Leaving test bound testControlShouldStopAfterMediaReachesToTheEnd
[task 2020-08-25T17:18:38.953Z] 17:18:38 INFO - GECKO(4863) | MEMORY STAT | vsize 20975455MB | residentFast 1182MB
[task 2020-08-25T17:18:38.954Z] 17:18:38 INFO - TEST-OK | dom/media/mediacontrol/tests/browser_stop_control_after_media_reaches_to_end.js | took 3945ms

Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE

This bug failed 81 times in the last 7 days. Occurs on linux1804-64, linux1804-64-shippable, linux1804-64-tsan on opt and debug build types.

Recent log:
https://treeherder.mozilla.org/logviewer?job_id=329079712&repo=autoland&lineNumber=22905

Bryce: Can you please take a look at this bug? It had a spike in the last week.

Flags: needinfo?(bvandyk)
Whiteboard: [stockwell needswork:owner]
Flags: needinfo?(bvandyk) → needinfo?(alwu)

This looks exact same as bug1683016, where the failure happens in intializing the cubeb audio backend, not from the media control part. However, we still don't understand why that would happen. I will suggest to simply disable this test on Liunx 64, like this one.

Flags: needinfo?(alwu)
Pushed by archaeopteryx@coole-files.de:
https://hg.mozilla.org/integration/autoland/rev/8a4e442064f7
disable browser_resume_latest_paused_media.js on Linux x64 opt and debug. r=intermittent-reviewers,jmaher DONTBUILD
Depends on: 1693151
Severity: normal → S3
Summary: Intermittent dom/media/mediacontrol/tests/browser_resume_latest_paused_media.js | Test timed out - → Intermittent dom/media/mediacontrol/tests/browser/browser_resume_latest_paused_media.js | Test timed out -
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: