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
Description
•