Closed Bug 1413889 Opened 7 years ago Closed 5 years ago

Intermittent dom/media/test/test_streams_tracks.html | Test timed out!

Categories

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

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: archaeopteryx [at] coole-files.de https://treeherder.mozilla.org/logviewer.html#?job_id=141645302&repo=mozilla-inbound https://queue.taskcluster.net/v1/task/Fj98CqmAQgeJt9uQ19rNTw/runs/0/artifacts/public/logs/live_backing.log 11:49:44 INFO - 860 INFO TEST-START | dom/media/test/test_streams_tracks.html 11:52:44 INFO - TEST-INFO | started process screenshot 11:52:44 INFO - TEST-INFO | screenshot: exit 0 11:52:44 INFO - Buffered messages logged at 11:49:44 11:52:44 INFO - 861 INFO TEST-PASS | dom/media/test/test_streams_tracks.html | A valid string reason is expected 11:52:44 INFO - 862 INFO TEST-PASS | dom/media/test/test_streams_tracks.html | Reason cannot be empty 11:52:44 INFO - 863 INFO Started Thu Nov 02 2017 11:49:44 GMT+0000 (Coordinated Universal Time) (1509623384.766s) 11:52:44 INFO - 864 INFO TEST-PASS | dom/media/test/test_streams_tracks.html | [started big-short.wav-0 t=0.014] Length of array should match number of running tests 11:52:44 INFO - 865 INFO TEST-PASS | dom/media/test/test_streams_tracks.html | [started 320x240.ogv-1 t=0.015] Length of array should match number of running tests 11:52:44 INFO - Buffered messages finished 11:52:44 ERROR - 866 INFO TEST-UNEXPECTED-FAIL | dom/media/test/test_streams_tracks.html | Test timed out! 11:52:44 INFO - onTimeout@dom/media/test/manifest.js:1688:7 11:52:44 INFO - setTimeout handler*MediaTestManager/this.started@dom/media/test/manifest.js:1694:26 11:52:44 INFO - startTest@dom/media/test/test_streams_tracks.html:40:3 11:52:44 INFO - MediaTestManager/this.nextTest@dom/media/test/manifest.js:1743:7 11:52:44 INFO - MediaTestManager/this.runTests/<@dom/media/test/manifest.js:1663:7
Component: Audio/Video → Audio/Video: Playback
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
Status: REOPENED → RESOLVED
Closed: 7 years ago7 years ago
Resolution: --- → INCOMPLETE
New log file and snippet with the failure: https://treeherder.mozilla.org/logviewer.html#?job_id=194349140&repo=mozilla-central&lineNumber=7747 17:00:12 INFO - TEST-START | dom/media/test/test_streams_tracks.html 17:00:12 INFO - GECKO(7692) | ++DOMWINDOW == 18 (00000223C4151800) [pid = 6980] [serial = 989] [outer = 00000223C1126E00] 17:00:13 INFO - GECKO(7692) | ++DOCSHELL 00000223BF6E6000 == 6 [pid = 6980] [id = {131ecf82-bb2b-46e8-903f-c14a83e8d80b}] 17:00:13 INFO - GECKO(7692) | ++DOMWINDOW == 19 (00000223C1120200) [pid = 6980] [serial = 990] [outer = 0000000000000000] 17:00:13 INFO - GECKO(7692) | ++DOMWINDOW == 20 (00000223C4155400) [pid = 6980] [serial = 991] [outer = 00000223C1120200] 17:00:14 INFO - GECKO(7692) | [Child 6980, Main Thread] WARNING: Cannot query channel count on a AudioSegment with no chunks.: '!mChunks.IsEmpty()', file z:/build/build/src/dom/media/AudioSegment.h, line 424 17:00:14 INFO - GECKO(7692) | [Child 6980, Main Thread] WARNING: A new track was detected on the input stream; creating a corresponding MediaStreamTrack. Initial tracks should be added manually to immediately and synchronously be available to JS.: '!mStream->mTracks.IsEmpty()', file z:/build/build/src/dom/media/DOMMediaStream.cpp, line 178 17:00:14 INFO - GECKO(7692) | [Child 6980, Main Thread] WARNING: A new track was detected on the input stream; creating a corresponding MediaStreamTrack. Initial tracks should be added manually to immediately and synchronously be available to JS.: '!mStream->mTracks.IsEmpty()', file z:/build/build/src/dom/media/DOMMediaStream.cpp, line 178 17:00:14 INFO - GECKO(7692) | [Child 6980, Main Thread] WARNING: Cannot query channel count on a AudioSegment with no chunks.: '!mChunks.IsEmpty()', file z:/build/build/src/dom/media/AudioSegment.h, line 424 17:00:14 INFO - GECKO(7692) | [Child 6980, Main Thread] WARNING: A new track was detected on the input stream; creating a corresponding MediaStreamTrack. Initial tracks should be added manually to immediately and synchronously be available to JS.: '!mStream->mTracks.IsEmpty()', file z:/build/build/src/dom/media/DOMMediaStream.cpp, line 178 17:00:14 INFO - GECKO(7692) | [Child 6980, Main Thread] WARNING: A new track was detected on the input stream; creating a corresponding MediaStreamTrack. Initial tracks should be added manually to immediately and synchronously be available to JS.: '!mStream->mTracks.IsEmpty()', file z:/build/build/src/dom/media/DOMMediaStream.cpp, line 178 17:00:15 INFO - GECKO(7692) | [Child 6980, Main Thread] WARNING: A new track was detected on the input stream; creating a corresponding MediaStreamTrack. Initial tracks should be added manually to immediately and synchronously be available to JS.: '!mStream->mTracks.IsEmpty()', file z:/build/build/src/dom/media/DOMMediaStream.cpp, line 178 17:00:20 INFO - GECKO(7692) | --DOMWINDOW == 19 (00000223C1126800) [pid = 6980] [serial = 978] [outer = 0000000000000000] [url = about:blank] 17:00:20 INFO - GECKO(7692) | --DOMWINDOW == 18 (00000223C1120600) [pid = 6980] [serial = 982] [outer = 0000000000000000] [url = about:blank] 17:00:20 INFO - GECKO(7692) | --DOMWINDOW == 17 (00000223C1B45600) [pid = 6980] [serial = 986] [outer = 0000000000000000] [url = about:blank] 17:00:20 INFO - GECKO(7692) | [Child 6980, Main Thread] WARNING: Cannot query channel count on a AudioSegment with no chunks.: '!mChunks.IsEmpty()', file z:/build/build/src/dom/media/AudioSegment.h, line 424 17:00:20 INFO - GECKO(7692) | [Child 6980, Main Thread] WARNING: A new track was detected on the input stream; creating a corresponding MediaStreamTrack. Initial tracks should be added manually to immediately and synchronously be available to JS.: '!mStream->mTracks.IsEmpty()', file z:/build/build/src/dom/media/DOMMediaStream.cpp, line 178 17:00:23 INFO - GECKO(7692) | --DOMWINDOW == 16 (00000223C544EC00) [pid = 6980] [serial = 979] [outer = 0000000000000000] [url = about:blank] 17:00:23 INFO - GECKO(7692) | --DOMWINDOW == 15 (00000223C11DEC00) [pid = 6980] [serial = 983] [outer = 0000000000000000] [url = about:blank] 17:00:23 INFO - GECKO(7692) | --DOMWINDOW == 14 (00000223C1BD8000) [pid = 6980] [serial = 980] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/SimpleTest/iframe-between-tests.html] 17:00:23 INFO - GECKO(7692) | --DOMWINDOW == 13 (00000223C1DB1C00) [pid = 6980] [serial = 981] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/dom/media/test/test_streams_individual_pause.html] 17:00:23 INFO - GECKO(7692) | --DOMWINDOW == 12 (00000223C11D2000) [pid = 6980] [serial = 985] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/dom/media/test/test_streams_srcObject.html] 17:00:23 INFO - GECKO(7692) | --DOMWINDOW == 11 (00000223C11DE000) [pid = 6980] [serial = 987] [outer = 0000000000000000] [url = about:blank] 17:00:23 INFO - GECKO(7692) | --DOMWINDOW == 10 (00000223BF6D1800) [pid = 6980] [serial = 984] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/SimpleTest/iframe-between-tests.html] 17:00:23 INFO - GECKO(7692) | --DOMWINDOW == 9 (00000223C4153800) [pid = 6980] [serial = 988] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/SimpleTest/iframe-between-tests.html] 17:00:41 INFO - GECKO(7692) | [Parent 7692, Lazy Idle] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file z:/build/build/src/widget/windows/WinUtils.cpp, line 1433 17:01:11 INFO - GECKO(7692) | --DOCSHELL 00000223C1B70800 == 5 [pid = 6980] [id = {845c7455-94bb-4b2b-844a-49b5190d6cef}] 17:01:11 INFO - GECKO(7692) | --DOCSHELL 00000223C1B78000 == 4 [pid = 6980] [id = {20303955-5afd-48ad-b341-b897c65d1ed0}] 17:01:15 INFO - GECKO(7692) | --DOMWINDOW == 8 (00000223C76EC000) [pid = 6980] [serial = 977] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/dom/media/test/test_streams_gc.html] 17:01:39 INFO - GECKO(7692) | [Parent 7692, StreamTrans #42] WARNING: 'NS_FAILED(rv)', file z:/build/build/src/modules/libjar/nsJARChannel.cpp, line 417 17:02:41 INFO - GECKO(7692) | [Parent 7692, Lazy Idle] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file z:/build/build/src/widget/windows/WinUtils.cpp, line 1433 17:03:15 INFO - TEST-INFO | started process screenshot 17:03:15 INFO - TEST-INFO | screenshot: exit 0 17:03:15 INFO - Buffered messages logged at 17:00:13 17:03:15 INFO - TEST-PASS | dom/media/test/test_streams_tracks.html | A valid string reason is expected 17:03:15 INFO - TEST-PASS | dom/media/test/test_streams_tracks.html | Reason cannot be empty 17:03:15 INFO - Started Thu Aug 16 2018 17:00:13 GMT+0000 (Coordinated Universal Time) (1534438813.26s) 17:03:15 INFO - TEST-PASS | dom/media/test/test_streams_tracks.html | [started big-short.wav-0 t=0.047] Length of array should match number of running tests 17:03:15 INFO - TEST-PASS | dom/media/test/test_streams_tracks.html | [started 320x240.ogv-1 t=0.051] Length of array should match number of running tests 17:03:15 INFO - Buffered messages logged at 17:00:14 17:03:15 INFO - TEST-PASS | dom/media/test/test_streams_tracks.html | Correct track count for http://mochi.test:8888/tests/dom/media/test/320x240.ogv 17:03:15 INFO - TEST-PASS | dom/media/test/test_streams_tracks.html | Correct track count for http://mochi.test:8888/tests/dom/media/test/320x240.ogv 17:03:15 INFO - TEST-PASS | dom/media/test/test_streams_tracks.html | Correct track kind for track 0 of http://mochi.test:8888/tests/dom/media/test/320x240.ogv 17:03:15 INFO - TEST-PASS | dom/media/test/test_streams_tracks.html | id {878c8284-955f-47a7-bc91-7504cc3c618d} for track 0 of http://mochi.test:8888/tests/dom/media/test/320x240.ogv has correct form 17:03:15 INFO - [finished 320x240.ogv-1] remaining= big-short.wav-0 17:03:15 INFO - TEST-PASS | dom/media/test/test_streams_tracks.html | [finished 320x240.ogv-1 t=1.249] Length of array should match number of running tests 17:03:15 INFO - TEST-PASS | dom/media/test/test_streams_tracks.html | [started short-video.ogv-2 t=1.251] Length of array should match number of running tests 17:03:15 INFO - Buffered messages logged at 17:00:15 17:03:15 INFO - TEST-PASS | dom/media/test/test_streams_tracks.html | Correct track count for http://mochi.test:8888/tests/dom/media/test/big-short.wav 17:03:15 INFO - TEST-PASS | dom/media/test/test_streams_tracks.html | Correct track kind for track 0 of http://mochi.test:8888/tests/dom/media/test/big-short.wav 17:03:15 INFO - TEST-PASS | dom/media/test/test_streams_tracks.html | id {972ed806-470c-4e59-8af7-6708fe491405} for track 0 of http://mochi.test:8888/tests/dom/media/test/big-short.wav has correct form 17:03:15 INFO - TEST-PASS | dom/media/test/test_streams_tracks.html | Correct track count for http://mochi.test:8888/tests/dom/media/test/big-short.wav 17:03:15 INFO - [finished big-short.wav-0] remaining= short-video.ogv-2 17:03:15 INFO - TEST-PASS | dom/media/test/test_streams_tracks.html | [finished big-short.wav-0 t=2.079] Length of array should match number of running tests 17:03:15 INFO - TEST-PASS | dom/media/test/test_streams_tracks.html | [started seek-short.webm-3 t=2.08] Length of array should match number of running tests 17:03:15 INFO - TEST-PASS | dom/media/test/test_streams_tracks.html | Correct track count for http://mochi.test:8888/tests/dom/media/test/short-video.ogv 17:03:15 INFO - TEST-PASS | dom/media/test/test_streams_tracks.html | Correct track kind for track 0 of http://mochi.test:8888/tests/dom/media/test/short-video.ogv 17:03:15 INFO - TEST-PASS | dom/media/test/test_streams_tracks.html | id {ae925cd8-9b8b-46cd-9bb5-b3f3ba23bac7} for track 0 of http://mochi.test:8888/tests/dom/media/test/short-video.ogv has correct form 17:03:15 INFO - TEST-PASS | dom/media/test/test_streams_tracks.html | Correct track count for http://mochi.test:8888/tests/dom/media/test/short-video.ogv 17:03:15 INFO - TEST-PASS | dom/media/test/test_streams_tracks.html | Correct track kind for track 0 of http://mochi.test:8888/tests/dom/media/test/short-video.ogv 17:03:15 INFO - TEST-PASS | dom/media/test/test_streams_tracks.html | id {91440dd5-a5eb-4c20-ba10-da7f74cb0843} for track 0 of http://mochi.test:8888/tests/dom/media/test/short-video.ogv has correct form 17:03:15 INFO - [finished short-video.ogv-2] remaining= seek-short.webm-3 17:03:15 INFO - TEST-PASS | dom/media/test/test_streams_tracks.html | [finished short-video.ogv-2 t=2.538] Length of array should match number of running tests 17:03:15 INFO - TEST-PASS | dom/media/test/test_streams_tracks.html | [started flac-s24.flac-4 t=2.541] Length of array should match number of running tests 17:03:15 INFO - TEST-PASS | dom/media/test/test_streams_tracks.html | Correct track count for http://mochi.test:8888/tests/dom/media/test/seek-short.webm 17:03:15 INFO - TEST-PASS | dom/media/test/test_streams_tracks.html | Correct track count for http://mochi.test:8888/tests/dom/media/test/seek-short.webm 17:03:15 INFO - TEST-PASS | dom/media/test/test_streams_tracks.html | Correct track kind for track 0 of http://mochi.test:8888/tests/dom/media/test/seek-short.webm 17:03:15 INFO - TEST-PASS | dom/media/test/test_streams_tracks.html | id {2b5057e4-51d8-4709-b864-3658f77d65f2} for track 0 of http://mochi.test:8888/tests/dom/media/test/seek-short.webm has correct form 17:03:15 INFO - [finished seek-short.webm-3] remaining= flac-s24.flac-4 17:03:15 INFO - TEST-PASS | dom/media/test/test_streams_tracks.html | [finished seek-short.webm-3 t=2.549] Length of array should match number of running tests 17:03:15 INFO - Buffered messages finished 17:03:15 INFO - TEST-UNEXPECTED-FAIL | dom/media/test/test_streams_tracks.html | Test timed out! 17:03:15 INFO - onTimeout@dom/media/test/manifest.js:1723:7 17:03:15 INFO - setTimeout handler*MediaTestManager/this.started@dom/media/test/manifest.js:1730:26 17:03:15 INFO - startTest@dom/media/test/test_streams_tracks.html:40:3 17:03:15 INFO - MediaTestManager/this.nextTest@dom/media/test/manifest.js:1779:7 17:03:15 INFO - MediaTestManager/this.finished@dom/media/test/manifest.js:1758:7 17:03:15 INFO - onended@dom/media/test/test_streams_tracks.html:33:3 17:03:15 INFO - EventListener.handleEvent*startTest@dom/media/test/test_streams_tracks.html:45:3 17:03:15 INFO - MediaTestManager/this.nextTest@dom/media/test/manifest.js:1779:7 17:03:15 INFO - MediaTestManager/this.finished@dom/media/test/manifest.js:1758:7 17:03:15 INFO - onended@dom/media/test/test_streams_tracks.html:33:3 17:03:15 INFO - EventListener.handleEvent*startTest@dom/media/test/test_streams_tracks.html:45:3 17:03:15 INFO - MediaTestManager/this.nextTest@dom/media/test/manifest.js:1779:7 17:03:15 INFO - MediaTestManager/this.runTests/<@dom/media/test/manifest.js:1699:7 17:03:15 INFO - flac-s24.flac-4 timed out! 17:03:15 INFO - [finished flac-s24.flac-4] remaining= 17:03:15 INFO - TEST-PASS | dom/media/test/test_streams_tracks.html | [finished flac-s24.flac-4 t=182.563] Length of array should match number of running tests 17:03:16 INFO - Finished at Thu Aug 16 2018 17:03:16 GMT+0000 (Coordinated Universal Time) (1534438996.829s) 17:03:16 INFO - Running time: 183.57s
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 7 years ago6 years ago
Resolution: --- → INCOMPLETE

Recent failure log:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=236867700&repo=mozilla-central&lineNumber=8782

11:50:01 INFO - TEST-PASS | dom/media/test/test_streams_tracks.html | [finished short-video.ogv-2 t=1.198] Length of array should match number of running tests
11:50:01 INFO - Buffered messages finished
11:50:01 INFO - TEST-UNEXPECTED-FAIL | dom/media/test/test_streams_tracks.html | Test timed out!
11:50:01 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
11:50:01 INFO - onTimeout@dom/media/test/manifest.js:1778:7
11:50:01 INFO - setTimeout handlerMediaTestManager/this.started@dom/media/test/manifest.js:1785:57
11:50:01 INFO - startTest@dom/media/test/test_streams_tracks.html:40:11
11:50:01 INFO - MediaTestManager/this.nextTest@dom/media/test/manifest.js:1834:12
11:50:01 INFO - MediaTestManager/this.finished@dom/media/test/manifest.js:1813:12
11:50:01 INFO - onended@dom/media/test/test_streams_tracks.html:33:11
11:50:01 INFO - EventListener.handleEvent
startTest@dom/media/test/test_streams_tracks.html:45:11
11:50:01 INFO - MediaTestManager/this.nextTest@dom/media/test/manifest.js:1834:12
11:50:01 INFO - MediaTestManager/this.finished@dom/media/test/manifest.js:1813:12
11:50:01 INFO - onended@dom/media/test/test_streams_tracks.html:33:11
11:50:01 INFO - EventListener.handleEvent*startTest@dom/media/test/test_streams_tracks.html:45:11
11:50:01 INFO - MediaTestManager/this.nextTest@dom/media/test/manifest.js:1834:12
11:50:01 INFO - MediaTestManager/this.runTests/<@dom/media/test/manifest.js:1754:12
11:50:01 INFO - flac-s24.flac-4 timed out!
11:50:01 INFO - [finished flac-s24.flac-4] remaining=

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