Closed Bug 1425411 Opened 8 years ago Closed 6 years ago

Intermittent dom/media/test/test_mediarecorder_bitrate.html | seek-short.webm encoded@1000000=6380 > encoded@100000=5904

Categories

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

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Status: NEW → RESOLVED
Closed: 8 years ago
Resolution: --- → INCOMPLETE
New failure log file: https://treeherder.mozilla.org/logviewer.html#?job_id=185767822&repo=autoland&lineNumber=4844 Snippet with the failure: [task 2018-06-30T16:09:18.231Z] INFO - TEST-START | dom/media/test/test_mediarecorder_bitrate.html [task 2018-06-30T16:09:18.510Z] INFO - GECKO(1687) | ++DOMWINDOW == 12 (0x7efbfbb4a800) [pid = 1744] [serial = 462] [outer = 0x7efc101c1e00] [task 2018-06-30T16:09:18.669Z] INFO - GECKO(1687) | ++DOCSHELL 0x7efbfcc7b800 == 4 [pid = 1744] [id = {37f27e7e-3aa5-4c28-9a35-47c5448985f0}] [task 2018-06-30T16:09:18.669Z] INFO - GECKO(1687) | ++DOMWINDOW == 13 (0x7efbff8d2600) [pid = 1744] [serial = 463] [outer = (nil)] [task 2018-06-30T16:09:18.693Z] INFO - GECKO(1687) | ++DOMWINDOW == 14 (0x7efbfbbbb000) [pid = 1744] [serial = 464] [outer = 0x7efbff8d2600] [task 2018-06-30T16:09:18.757Z] INFO - GECKO(1687) | --DOCSHELL 0x7f5569d9b800 == 5 [pid = 1687] [id = {b2b93bc2-9563-4e6e-9616-844ab8b5d885}] [task 2018-06-30T16:09:19.673Z] INFO - TEST-INFO | started process screentopng [task 2018-06-30T16:09:20.176Z] INFO - TEST-INFO | screentopng: exit 0 [task 2018-06-30T16:09:20.177Z] INFO - Buffered messages logged at 16:09:18 [task 2018-06-30T16:09:20.179Z] INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | A valid string reason is expected [task 2018-06-30T16:09:20.180Z] INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | Reason cannot be empty [task 2018-06-30T16:09:20.182Z] INFO - Started Sat Jun 30 2018 16:09:18 GMT+0000 (UTC) (1530374958.723s) [task 2018-06-30T16:09:20.183Z] INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | [started seek-short.webm-0 t=0.076] Length of array should match number of running tests [task 2018-06-30T16:09:20.184Z] INFO - Buffered messages logged at 16:09:19 [task 2018-06-30T16:09:20.185Z] INFO - loadedmetadata [task 2018-06-30T16:09:20.186Z] INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | Media recorder should be recording [task 2018-06-30T16:09:20.188Z] INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | Media recorder stream = element stream at the start of recording [task 2018-06-30T16:09:20.189Z] INFO - loadedmetadata [task 2018-06-30T16:09:20.190Z] INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | Media recorder should be recording [task 2018-06-30T16:09:20.191Z] INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | Media recorder stream = element stream at the start of recording [task 2018-06-30T16:09:20.192Z] INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | Events fired from ondataavailable should be BlobEvent [task 2018-06-30T16:09:20.193Z] INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | Event type should dataavailable [task 2018-06-30T16:09:20.194Z] INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | Blob data received should be greater than zero [task 2018-06-30T16:09:20.195Z] INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | onstop fired after ondataavailable [task 2018-06-30T16:09:20.196Z] INFO - test seek-short.webm encoded@1000000=6380 [task 2018-06-30T16:09:20.197Z] INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | Events fired from ondataavailable should be BlobEvent [task 2018-06-30T16:09:20.199Z] INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | Event type should dataavailable [task 2018-06-30T16:09:20.202Z] INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | Blob data received should be greater than zero [task 2018-06-30T16:09:20.203Z] INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | onstop fired after ondataavailable [task 2018-06-30T16:09:20.204Z] INFO - test seek-short.webm encoded@100000=5904 [task 2018-06-30T16:09:20.205Z] INFO - Buffered messages finished [task 2018-06-30T16:09:20.205Z] INFO - TEST-UNEXPECTED-FAIL | dom/media/test/test_mediarecorder_bitrate.html | seek-short.webm encoded@1000000=6380 > encoded@100000=5904 [task 2018-06-30T16:09:20.205Z] INFO - runTest/element.onloadedmetadata/mediaRecorder.onstop@dom/media/test/test_mediarecorder_bitrate.html:82:13 [task 2018-06-30T16:09:20.206Z] INFO - EventHandlerNonNull*runTest/element.onloadedmetadata@dom/media/test/test_mediarecorder_bitrate.html:58:5 [task 2018-06-30T16:09:20.206Z] INFO - EventHandlerNonNull*runTest@dom/media/test/test_mediarecorder_bitrate.html:33:3 [task 2018-06-30T16:09:20.207Z] INFO - startTest@dom/media/test/test_mediarecorder_bitrate.html:22:3 [task 2018-06-30T16:09:20.207Z] INFO - MediaTestManager/this.nextTest@dom/media/test/manifest.js:1773:7 [task 2018-06-30T16:09:20.208Z] INFO - MediaTestManager/this.runTests/<@dom/media/test/manifest.js:1693:7 [task 2018-06-30T16:09:20.208Z] INFO - [finished seek-short.webm-0] remaining= [task 2018-06-30T16:09:20.209Z] INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | [finished seek-short.webm-0 t=0.949] Length of array should match number of running tests
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 8 years ago7 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 7 years ago6 years ago
Resolution: --- → INCOMPLETE

Recent failure https://treeherder.mozilla.org/#/jobs?repo=mozilla-central&resultStatus=testfailed%2Cbusted%2Cexception&revision=8acc968600ffaf40c5b8f51a7701c39e07f17ff7&searchStr=linux%2Cx64%2Cccov%2Cdebug%2Cmochitests%2Ctest-linux64-ccov%2Fdebug-mochitest-media-e10s-1%2Cm%28mda1%29

Failure log https://treeherder.mozilla.org/logviewer.html#?job_id=250187068&repo=mozilla-central

Part of that log
[task 2019-06-05T17:22:41.068Z] 17:22:41 INFO - TEST-START | dom/media/test/test_mediarecorder_bitrate.html
[task 2019-06-05T17:22:41.090Z] 17:22:41 INFO - [1929, Main Thread] WARNING: No active window: file /builds/worker/workspace/build/src/js/xpconnect/src/XPCJSContext.cpp, line 662
[task 2019-06-05T17:22:41.110Z] 17:22:41 INFO - GECKO(1958) | --DOCSHELL 0x7f988e4da000 == 2 [pid = 2042] [id = {3783648b-bdae-41bf-bbcf-f3df3a3ee08e}] [url = about:blank]
[task 2019-06-05T17:22:41.166Z] 17:22:41 INFO - GECKO(1958) | ++DOMWINDOW == 9 (0x7f988e3da800) [pid = 2042] [serial = 466] [outer = 0x7f989ebe0c40]
[task 2019-06-05T17:22:41.268Z] 17:22:41 INFO - GECKO(1958) | ++DOCSHELL 0x7f988e4e1000 == 3 [pid = 2042] [id = {4f7563b5-0532-48a0-8f0f-f5db37f758b1}]
[task 2019-06-05T17:22:41.268Z] 17:22:41 INFO - GECKO(1958) | ++DOMWINDOW == 10 (0x7f9892731a60) [pid = 2042] [serial = 467] [outer = (nil)]
[task 2019-06-05T17:22:41.317Z] 17:22:41 INFO - GECKO(1958) | ++DOMWINDOW == 11 (0x7f988e3dc000) [pid = 2042] [serial = 468] [outer = 0x7f9892731a60]
[task 2019-06-05T17:22:41.823Z] 17:22:41 INFO - [1929, Main Thread] WARNING: No active window: file /builds/worker/workspace/build/src/js/xpconnect/src/XPCJSContext.cpp, line 662
[task 2019-06-05T17:22:42.700Z] 17:22:42 INFO - TEST-INFO | started process screentopng
[task 2019-06-05T17:22:43.008Z] 17:22:43 INFO - TEST-INFO | screentopng: exit 0
[task 2019-06-05T17:22:43.009Z] 17:22:43 INFO - Buffered messages logged at 17:22:41
[task 2019-06-05T17:22:43.009Z] 17:22:43 INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | A valid string reason is expected
[task 2019-06-05T17:22:43.010Z] 17:22:43 INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | Reason cannot be empty
[task 2019-06-05T17:22:43.011Z] 17:22:43 INFO - Started Wed Jun 05 2019 17:22:41 GMT+0000 (Coordinated Universal Time) (1559755361.354s)
[task 2019-06-05T17:22:43.011Z] 17:22:43 INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | [started seek-short.webm-0 t=0.446] Length of array should match number of running tests
[task 2019-06-05T17:22:43.012Z] 17:22:43 INFO - Buffered messages logged at 17:22:42
[task 2019-06-05T17:22:43.012Z] 17:22:43 INFO - loadedmetadata
[task 2019-06-05T17:22:43.013Z] 17:22:43 INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | Media recorder should be recording
[task 2019-06-05T17:22:43.015Z] 17:22:43 INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | Media recorder stream = element stream at the start of recording
[task 2019-06-05T17:22:43.016Z] 17:22:43 INFO - loadedmetadata
[task 2019-06-05T17:22:43.020Z] 17:22:43 INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | Media recorder should be recording
[task 2019-06-05T17:22:43.020Z] 17:22:43 INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | Media recorder stream = element stream at the start of recording
[task 2019-06-05T17:22:43.020Z] 17:22:43 INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | Events fired from ondataavailable should be BlobEvent
[task 2019-06-05T17:22:43.020Z] 17:22:43 INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | Event type should dataavailable
[task 2019-06-05T17:22:43.021Z] 17:22:43 INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | Blob data received should be greater than zero
[task 2019-06-05T17:22:43.021Z] 17:22:43 INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | onstop fired after ondataavailable
[task 2019-06-05T17:22:43.022Z] 17:22:43 INFO - test seek-short.webm encoded@100000=6441
[task 2019-06-05T17:22:43.022Z] 17:22:43 INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | Events fired from ondataavailable should be BlobEvent
[task 2019-06-05T17:22:43.022Z] 17:22:43 INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | Event type should dataavailable
[task 2019-06-05T17:22:43.022Z] 17:22:43 INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | Blob data received should be greater than zero
[task 2019-06-05T17:22:43.022Z] 17:22:43 INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | onstop fired after ondataavailable
[task 2019-06-05T17:22:43.022Z] 17:22:43 INFO - test seek-short.webm encoded@1000000=6380
[task 2019-06-05T17:22:43.022Z] 17:22:43 INFO - Buffered messages finished
[task 2019-06-05T17:22:43.022Z] 17:22:43 INFO - TEST-UNEXPECTED-FAIL | dom/media/test/test_mediarecorder_bitrate.html | seek-short.webm encoded@1000000=6380 > encoded@100000=6441
[task 2019-06-05T17:22:43.022Z] 17:22:43 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
[task 2019-06-05T17:22:43.022Z] 17:22:43 INFO - runTest/element.onloadedmetadata/mediaRecorder.onstop@dom/media/test/test_mediarecorder_bitrate.html:82:13
[task 2019-06-05T17:22:43.022Z] 17:22:43 INFO - EventHandlerNonNullrunTest/element.onloadedmetadata@dom/media/test/test_mediarecorder_bitrate.html:58:5
[task 2019-06-05T17:22:43.022Z] 17:22:43 INFO - EventHandlerNonNull
runTest@dom/media/test/test_mediarecorder_bitrate.html:33:3
[task 2019-06-05T17:22:43.022Z] 17:22:43 INFO - startTest@dom/media/test/test_mediarecorder_bitrate.html:21:3
[task 2019-06-05T17:22:43.022Z] 17:22:43 INFO - MediaTestManager/this.nextTest@dom/media/test/manifest.js:1834:12
[task 2019-06-05T17:22:43.022Z] 17:22:43 INFO - MediaTestManager/this.runTests/<@dom/media/test/manifest.js:1754:12
[task 2019-06-05T17:22:43.022Z] 17:22:43 INFO - [finished seek-short.webm-0] remaining=
[task 2019-06-05T17:22:43.023Z] 17:22:43 INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | [finished seek-short.webm-0 t=1.341] Length of array should match number of running tests
[task 2019-06-05T17:22:43.442Z] 17:22:43 INFO - GECKO(1958) | --DOMWINDOW == 10 (0x7f988e3dac00) [pid = 2042] [serial = 464] [outer = (nil)] [url = about:blank]
[task 2019-06-05T17:22:43.443Z] 17:22:43 INFO - GECKO(1958) | --DOMWINDOW == 9 (0x7f988e3d5c00) [pid = 2042] [serial = 465] [outer = (nil)] [url = http://mochi.test:8888/tests/SimpleTest/iframe-between-tests.html]
[task 2019-06-05T17:22:43.443Z] 17:22:43 INFO - GECKO(1958) | --DOMWINDOW == 8 (0x7f98927312e0) [pid = 2042] [serial = 463] [outer = (nil)] [url = about:blank]
[task 2019-06-05T17:22:43.444Z] 17:22:43 INFO - GECKO(1958) | --DOMWINDOW == 7 (0x7f988e3d9c00) [pid = 2042] [serial = 462] [outer = (nil)] [url = http://mochi.test:8888/tests/dom/media/test/test_media_sniffer.html]
[task 2019-06-05T17:22:43.480Z] 17:22:43 INFO - Finished at Wed Jun 05 2019 17:22:43 GMT+0000 (Coordinated Universal Time) (1559755363.47s)
[task 2019-06-05T17:22:43.480Z] 17:22:43 INFO - Running time: 2.117s
[task 2019-06-05T17:22:43.496Z] 17:22:43 INFO - GECKO(1958) | MEMORY STAT | vsize 3002MB | residentFast 411MB | heapAllocated 23MB
[task 2019-06-05T17:22:43.520Z] 17:22:43 INFO - TEST-OK | dom/media/test/test_mediarecorder_bitrate.html | took 2449ms
[task 2019-06-05T17:22:43.582Z] 17:22:43 INFO - GECKO(1958) | ++DOMWINDOW == 8 (0x7f988e3d6800) [pid = 2042] [serial = 469] [outer = 0x7f989ebe0c40]
[task 2019-06-05T17:22:43.647Z] 17:22:43 INFO - TEST-START | dom/media/test/test_mediarecorder_creation.html

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 6 years ago6 years ago
Resolution: --- → INCOMPLETE

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

[task 2019-07-22T11:11:24.160Z] 11:11:24 INFO - TEST-PASS | dom/media/test/test_mediarecorder_bitrate.html | onstop fired after ondataavailable
[task 2019-07-22T11:11:24.161Z] 11:11:24 INFO - test seek-short.webm encoded@1000000=6380
[task 2019-07-22T11:11:24.161Z] 11:11:24 INFO - Buffered messages finished
[task 2019-07-22T11:11:24.162Z] 11:11:24 INFO - TEST-UNEXPECTED-FAIL | dom/media/test/test_mediarecorder_bitrate.html | seek-short.webm encoded@1000000=6380 > encoded@100000=6036
[task 2019-07-22T11:11:24.163Z] 11:11:24 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
[task 2019-07-22T11:11:24.163Z] 11:11:24 INFO - runTest/element.onloadedmetadata/mediaRecorder.onstop@dom/media/test/test_mediarecorder_bitrate.html:82:13
[task 2019-07-22T11:11:24.164Z] 11:11:24 INFO - EventHandlerNonNullrunTest/element.onloadedmetadata@dom/media/test/test_mediarecorder_bitrate.html:58:5
[task 2019-07-22T11:11:24.164Z] 11:11:24 INFO - EventHandlerNonNull
runTest@dom/media/test/test_mediarecorder_bitrate.html:33:3
[task 2019-07-22T11:11:24.165Z] 11:11:24 INFO - startTest@dom/media/test/test_mediarecorder_bitrate.html:21:3
[task 2019-07-22T11:11:24.166Z] 11:11:24 INFO - MediaTestManager/this.nextTest@dom/media/test/manifest.js:1815:12
[task 2019-07-22T11:11:24.167Z] 11:11:24 INFO - MediaTestManager/this.runTests/<@dom/media/test/manifest.js:1735:12
[task 2019-07-22T11:11:24.168Z] 11:11:24 INFO - Async*MediaTestManager/this.runTests@dom/media/test/manifest.js:1734:19
[task 2019-07-22T11:11:24.169Z] 11:11:24 INFO - @dom/media/test/test_mediarecorder_bitrate.html:124:9
[task 2019-07-22T11:11:24.169Z] 11:11:24 INFO - [finished seek-short.webm-0] remaining=

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