Closed Bug 1693431 Opened 5 years ago Closed 4 years ago

Intermittent dom/media/mediacontrol/tests/browser/browser_media_control_position_state.js | video started playing - false == true - got false, expected true (operator ==)

Categories

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

Product:
Core
Component:
Audio/Video
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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

[task 2021-02-18T00:43:10.814Z] 00:43:10     INFO - TEST-START | dom/media/mediacontrol/tests/browser/browser_media_control_position_state.js
[task 2021-02-18T00:43:12.807Z] 00:43:12     INFO - GECKO(1555) | JavaScript error: resource://gre/actors/AudioPlaybackParent.jsm, line 24: TypeError: can't access property "audioPlaybackStopped", browser is null
[task 2021-02-18T00:43:13.172Z] 00:43:13     INFO - GECKO(1555) | [Child 1778, MediaDecoderStateMachine #1] WARNING: Decoder=7b5800014d00 state=DECODING_METADATA Decode metadata failed, shutting down decoder: file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp:366
[task 2021-02-18T00:43:13.172Z] 00:43:13     INFO - GECKO(1555) | [Child 1778, MediaDecoderStateMachine #1] WARNING: Decoder=7b5800014d00 Decode error: NS_ERROR_DOM_MEDIA_METADATA_ERR (0x806e0006) - static MP4Metadata::ResultAndByteBuffer mozilla::MP4Metadata::Metadata(mozilla::ByteStream *): Cannot parse metadata: file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp:3476
[task 2021-02-18T00:43:13.420Z] 00:43:13     INFO - TEST-INFO | started process screentopng
[task 2021-02-18T00:43:13.758Z] 00:43:13     INFO - TEST-INFO | screentopng: exit 0
[task 2021-02-18T00:43:13.758Z] 00:43:13     INFO - Buffered messages logged at 00:43:10
[task 2021-02-18T00:43:13.758Z] 00:43:13     INFO - Entering test bound setupTestingPref
[task 2021-02-18T00:43:13.758Z] 00:43:13     INFO - Leaving test bound setupTestingPref
[task 2021-02-18T00:43:13.758Z] 00:43:13     INFO - Entering test bound testSetPositionState
[task 2021-02-18T00:43:13.759Z] 00:43:13     INFO - open media page
[task 2021-02-18T00:43:13.759Z] 00:43:13     INFO - Buffered messages logged at 00:43:11
[task 2021-02-18T00:43:13.759Z] 00:43:13     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/browser/file_non_autoplay.html" line: 0}]
[task 2021-02-18T00:43:13.759Z] 00:43:13     INFO - start media
[task 2021-02-18T00:43:13.759Z] 00:43:13     INFO - TEST-PASS | dom/media/mediacontrol/tests/browser/browser_media_control_position_state.js | video started playing - true == true - 
[task 2021-02-18T00:43:13.760Z] 00:43:13     INFO - Buffered messages logged at 00:43:12
[task 2021-02-18T00:43:13.760Z] 00:43:13     INFO - set duration only
[task 2021-02-18T00:43:13.760Z] 00:43:13     INFO - handle event=activated
[task 2021-02-18T00:43:13.760Z] 00:43:13     INFO - handle event=playbackstatechange
[task 2021-02-18T00:43:13.761Z] 00:43:13     INFO - handle event=metadatachange
[task 2021-02-18T00:43:13.761Z] 00:43:13     INFO - handle event=positionstatechange
[task 2021-02-18T00:43:13.761Z] 00:43:13     INFO - TEST-PASS | dom/media/mediacontrol/tests/browser/browser_media_control_position_state.js | expected duration 60 is equal to 60 - 
[task 2021-02-18T00:43:13.761Z] 00:43:13     INFO - TEST-PASS | dom/media/mediacontrol/tests/browser/browser_media_control_position_state.js | expected default playbackRate is 1.0 - 
[task 2021-02-18T00:43:13.761Z] 00:43:13     INFO - TEST-PASS | dom/media/mediacontrol/tests/browser/browser_media_control_position_state.js | expected default position is 0.0 - 
[task 2021-02-18T00:43:13.761Z] 00:43:13     INFO - set duration and playback rate
[task 2021-02-18T00:43:13.761Z] 00:43:13     INFO - handle event=positionstatechange
[task 2021-02-18T00:43:13.761Z] 00:43:13     INFO - TEST-PASS | dom/media/mediacontrol/tests/browser/browser_media_control_position_state.js | expected duration 50 is equal to 50 - 
[task 2021-02-18T00:43:13.762Z] 00:43:13     INFO - TEST-PASS | dom/media/mediacontrol/tests/browser/browser_media_control_position_state.js | expected playbackRate 2 is equal to 2 - 
[task 2021-02-18T00:43:13.762Z] 00:43:13     INFO - TEST-PASS | dom/media/mediacontrol/tests/browser/browser_media_control_position_state.js | expected default position is 0.0 - 
[task 2021-02-18T00:43:13.762Z] 00:43:13     INFO - set duration, playback rate and position
[task 2021-02-18T00:43:13.762Z] 00:43:13     INFO - handle event=positionstatechange
[task 2021-02-18T00:43:13.762Z] 00:43:13     INFO - TEST-PASS | dom/media/mediacontrol/tests/browser/browser_media_control_position_state.js | expected duration 40 is equal to 40 - 
[task 2021-02-18T00:43:13.762Z] 00:43:13     INFO - TEST-PASS | dom/media/mediacontrol/tests/browser/browser_media_control_position_state.js | expected playbackRate 3 is equal to 3 - 
[task 2021-02-18T00:43:13.762Z] 00:43:13     INFO - TEST-PASS | dom/media/mediacontrol/tests/browser/browser_media_control_position_state.js | expected position 10 is equal to 10 - 
[task 2021-02-18T00:43:13.762Z] 00:43:13     INFO - remove tab
[task 2021-02-18T00:43:13.762Z] 00:43:13     INFO - wait until finishing close tab wrapper
[task 2021-02-18T00:43:13.762Z] 00:43:13     INFO - handle event=deactivated
[task 2021-02-18T00:43:13.763Z] 00:43:13     INFO - TEST-PASS | dom/media/mediacontrol/tests/browser/browser_media_control_position_state.js | First event should be 'activated' - 
[task 2021-02-18T00:43:13.763Z] 00:43:13     INFO - TEST-PASS | dom/media/mediacontrol/tests/browser/browser_media_control_position_state.js | Last event should be 'deactivated' - 
[task 2021-02-18T00:43:13.763Z] 00:43:13     INFO - Leaving test bound testSetPositionState
[task 2021-02-18T00:43:13.763Z] 00:43:13     INFO - Entering test bound testSetPositionStateFromInactiveMediaSession
[task 2021-02-18T00:43:13.763Z] 00:43:13     INFO - open media page
[task 2021-02-18T00:43:13.763Z] 00:43:13     INFO - Console message: [JavaScript Error: "TypeError: can't access property "audioPlaybackStopped", browser is null" {file: "resource://gre/actors/AudioPlaybackParent.jsm" line: 24}]
[task 2021-02-18T00:43:13.763Z] 00:43:13     INFO - receiveMessage@resource://gre/actors/AudioPlaybackParent.jsm:24:9
[task 2021-02-18T00:43:13.763Z] 00:43:13     INFO - 
[task 2021-02-18T00:43:13.765Z] 00:43:13     INFO - Buffered messages logged at 00:43:13
[task 2021-02-18T00:43:13.765Z] 00:43:13     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/browser/file_non_autoplay.html" line: 0}]
[task 2021-02-18T00:43:13.765Z] 00:43:13     INFO - Console message: [JavaScript Warning: "Media resource https://example.com/browser/dom/media/mediacontrol/tests/browser/gizmo.mp4 could not be decoded." {file: "https://example.com/browser/dom/media/mediacontrol/tests/browser/file_non_autoplay.html" line: 0}]
[task 2021-02-18T00:43:13.765Z] 00:43:13     INFO - start media
[task 2021-02-18T00:43:13.765Z] 00:43:13     INFO - Buffered messages finished
[task 2021-02-18T00:43:13.766Z] 00:43:13     INFO - TEST-UNEXPECTED-FAIL | dom/media/mediacontrol/tests/browser/browser_media_control_position_state.js | video started playing - false == true - got false, expected true (operator ==)
[task 2021-02-18T00:43:13.766Z] 00:43:13     INFO - Stack trace:
[task 2021-02-18T00:43:13.766Z] 00:43:13     INFO - ok@resource://specialpowers/SpecialPowersSandbox.jsm:87:21
[task 2021-02-18T00:43:13.766Z] 00:43:13     INFO - @chrome://mochitests/content/browser/dom/media/mediacontrol/tests/browser/head.js:127:7
[task 2021-02-18T00:43:13.801Z] 00:43:13     INFO - GECKO(1555) | [Child 1778, MediaDecoderStateMachine #1] WARNING: Decoder=7b5800011a00 Decode error: NS_ERROR_DOM_MEDIA_DEMUXER_ERR (0x806e000c): file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp:3476
[task 2021-02-18T00:43:14.198Z] 00:43:14     INFO - Console message: [JavaScript Warning: "Media resource https://example.com/browser/dom/media/mediacontrol/tests/browser/gizmo.mp4 could not be decoded, error: Error Code: NS_ERROR_DOM_MEDIA_METADATA_ERR (0x806e0006)
[task 2021-02-18T00:43:14.199Z] 00:43:14     INFO - Details: static MP4Metadata::ResultAndByteBuffer mozilla::MP4Metadata::Metadata(mozilla::ByteStream *): Cannot parse metadata" {file: "https://example.com/browser/dom/media/mediacontrol/tests/browser/file_non_autoplay.html" line: 0}]
[task 2021-02-18T00:45:10.836Z] 00:45:10     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-02-18T00:45:10.836Z] 00:45:10     INFO - TEST-UNEXPECTED-FAIL | dom/media/mediacontrol/tests/browser/browser_media_control_position_state.js | Test timed out - 
[task 2021-02-18T00:45:10.853Z] 00:45:10     INFO - GECKO(1555) | MEMORY STAT | vsize 130551395MB | residentFast 2391MB
[task 2021-02-18T00:45:10.854Z] 00:45:10     INFO - TEST-OK | dom/media/mediacontrol/tests/browser/browser_media_control_position_state.js | took 120037ms
[task 2021-02-18T00:45:10.854Z] 00:45:10     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-02-18T00:45:10.854Z] 00:45:10     INFO - TEST-UNEXPECTED-FAIL | dom/media/mediacontrol/tests/browser/browser_media_control_position_state.js | Found a tab after previous test timed out: https://example.com/browser/dom/media/mediacontrol/tests/browser/file_non_autoplay.html - 
[task 2021-02-18T00:45:11.035Z] 00:45:11     INFO - checking window state
[task 2021-02-18T00:45:11.183Z] 00:45:11     INFO - TEST-START | dom/media/mediacontrol/tests/browser/browser_media_control_seekto.js
[task 2021-02-18T00:45:14.084Z] 00:45:14     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-02-18T00:45:14.085Z] 00:45:14     INFO - Buffered messages logged at 00:45:11
[task 2021-02-18T00:45:14.086Z] 00:45:14     INFO - Entering test bound setupTestingPref
[task 2021-02-18T00:45:14.087Z] 00:45:14     INFO - Leaving test bound setupTestingPref
[task 2021-02-18T00:45:14.088Z] 00:45:14     INFO - Entering test bound testSetPositionState
[task 2021-02-18T00:45:14.089Z] 00:45:14     INFO - open media page
[task 2021-02-18T00:45:14.090Z] 00:45:14     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/browser/file_non_autoplay.html" line: 0}]
[task 2021-02-18T00:45:14.091Z] 00:45:14     INFO - start media
[task 2021-02-18T00:45:14.092Z] 00:45:14     INFO - TEST-PASS | dom/media/mediacontrol/tests/browser/browser_media_control_seekto.js | video started playing - true == true - 
[task 2021-02-18T00:45:14.093Z] 00:45:14     INFO - Buffered messages logged at 00:45:14
[task 2021-02-18T00:45:14.094Z] 00:45:14     INFO - seek to 0 seconds.
[task 2021-02-18T00:45:14.095Z] 00:45:14     INFO - Buffered messages finished
[task 2021-02-18T00:45:14.095Z] 00:45:14     INFO - TEST-UNEXPECTED-FAIL | dom/media/mediacontrol/tests/browser/browser_media_control_position_state.js | Uncaught exception received from previously timed out test - at chrome://mochitests/content/browser/dom/media/mediacontrol/tests/browser/browser_media_control_position_state.js:57 - ReferenceError: info is not defined
[task 2021-02-18T00:45:14.096Z] 00:45:14     INFO - Stack trace:
[task 2021-02-18T00:45:14.097Z] 00:45:14     INFO - testSetPositionStateFromInactiveMediaSession@chrome://mochitests/content/browser/dom/media/mediacontrol/tests/browser/browser_media_control_position_state.js:57:3
[task 2021-02-18T00:45:14.107Z] 00:45:14     INFO - handle event=activated
[task 2021-02-18T00:45:14.122Z] 00:45:14     INFO - handle event=playbackstatechange
[task 2021-02-18T00:45:14.128Z] 00:45:14     INFO - handle event=metadatachange
[task 2021-02-18T00:45:14.141Z] 00:45:14     INFO - handle event=supportedkeyschange
[task 2021-02-18T00:45:14.142Z] 00:45:14     INFO - TEST-PASS | dom/media/mediacontrol/tests/browser/browser_media_control_seekto.js | Seektime must be presented - true == true - ```

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

Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

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

Status: REOPENED → RESOLVED
Closed: 5 years ago → 4 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.