Closed Bug 1288343 Opened 8 years ago Closed 7 years ago

Intermittent /media-source/SourceBuffer-abort-updating.html | InvalidStateError: An attempt was made to use an object that is not, or is no longer, usable

Categories

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

Product:
Core
Component:
Audio/Video: Playback
Type:
defect

Tracking

()

Status:
RESOLVED FIXED
Milestone:
mozilla54
Tracking Flags:
Tracking Status
firefox50 --- wontfix
firefox51 --- wontfix
firefox52 --- fixed
firefox-esr52 --- fixed
firefox53 --- fixed
firefox54 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: jya)

Details

(Keywords: intermittent-failure, Whiteboard: [stockwell disabled])

Attachments

(1 file)

disable the test until we can get time to look into it
901 bytes, patch
gbrown
: review+
Details | Diff | Splinter Review
Component: Audio/Video → Audio/Video: Playback
"                sourceBuffer.addEventListener('updatestart', t.step_func(function(e) {
                    events.push('updatestart');
                    //abort when sourceBuffer#updating is true
                    sourceBuffer.abort();

"

the test is invalid; there's no guarantee that by the time the updateend event is actually fired, the appendBuffer has actually completed anyway (as events are fired asynchronously)
the reson the test failed is that the first appendBuffer actually did complete. So it fires updateend which will then call EndOfStream()

but then abort() is called, which will also fire updateend and so EndOfStream() is called once again. As the mediasource is now already in ended state, it will throw an exception.
the last 2 weeks this bug has been occurring >30 times/week, this puts it in our radar as a priority bug that we want to fix to reduce the impact on developers and sheriffs.

this failure is on all platforms and configurations, although osx 10.10 is the least frequent.

on windows 7 debug I see this in the log [0]:
06:56:01     INFO - TEST-START | /media-source/SourceBuffer-abort-updating.html
06:56:01     INFO - PROCESS | 1704 | ++DOCSHELL 0BB29000 == 6 [pid = 2032] [id = {16c10574-6c5c-438e-8461-6f5c5e3dd2f4}]
06:56:01     INFO - PROCESS | 1704 | ++DOMWINDOW == 67 (0BBAA800) [pid = 2032] [serial = 1270] [outer = 00000000]
06:56:01     INFO - PROCESS | 1704 | ++DOMWINDOW == 68 (0BD90C00) [pid = 2032] [serial = 1271] [outer = 0BBAA800]
06:56:01     INFO - PROCESS | 1704 | ++DOMWINDOW == 69 (0C44A800) [pid = 2032] [serial = 1272] [outer = 0BBAA800]
06:56:01     INFO - PROCESS | 1704 | JavaScript error: http://web-platform.test:8000/media-source/SourceBuffer-abort-updating.html, line 67: InvalidStateError: An attempt was made to use an object that is not, or is no longer, usable
06:56:01     INFO - TEST-UNEXPECTED-ERROR | /media-source/SourceBuffer-abort-updating.html | InvalidStateError: An attempt was made to use an object that is not, or is no longer, usable
06:56:01     INFO - TEST-INFO took 570ms
06:56:01  WARNING - u'runner_teardown' ()
06:56:02     INFO - Setting up ssl
06:56:02     INFO - PROCESS | certutil | 
06:56:02     INFO - PROCESS | certutil | 
06:56:02     INFO - PROCESS | certutil | 
06:56:02     INFO - Certificate Nickname                                         Trust Attributes
06:56:02     INFO -                                                              SSL,S/MIME,JAR/XPI
06:56:02     INFO - 
06:56:02     INFO - web-platform-tests                                           CT,, 
06:56:02     INFO - 
06:56:03     INFO - Application command: c:\slave\test\build\application\firefox\firefox.exe -marionette about:blank -profile c:\users\cltbld\appdata\local\temp\tmppawykx.mozrunner
06:56:03     INFO - Starting runner
06:56:03     INFO - PROCESS | 5860 | [5860] WARNING: Failed to load startupcache file correctly, removing!: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/startupcache/StartupCache.cpp, line 219
06:56:03     INFO - PROCESS | 5860 | [5860] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/xpcom/base/nsSystemInfo.cpp, line 116
06:56:04     INFO - PROCESS | 5860 | [5860] WARNING: This method is lossy. Use GetCanonicalPath !: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/xpcom/io/nsLocalFileWin.cpp, line 3576
06:56:06     INFO - PROCESS | 5860 | ++DOCSHELL 0F30CC00 == 1 [pid = 5860] [id = {cc0d020b-d72c-4b1d-8d9d-40a79be04352}]
06:56:06     INFO - PROCESS | 5860 | ++DOMWINDOW == 1 (0F30D000) [pid = 5860] [serial = 1] [outer = 00000000]
06:56:06     INFO - PROCESS | 5860 | ++DOMWINDOW == 2 (0F30D800) [pid = 5860] [serial = 2] [outer = 0F30D000]
06:56:06     INFO - PROCESS | 5860 | [5860] WARNING: DWM not enabled, falling back to software vsync: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/gfx/thebes/gfxWindowsPlatform.cpp, line 1951
06:56:06     INFO - PROCESS | 5860 | ++DOCSHELL 1074DC00 == 2 [pid = 5860] [id = {40353f31-99e4-42da-9859-a47554ffb08b}]
06:56:06     INFO - PROCESS | 5860 | ++DOMWINDOW == 3 (1074E000) [pid = 5860] [serial = 3] [outer = 00000000]
06:56:06     INFO - PROCESS | 5860 | ++DOMWINDOW == 4 (1074E800) [pid = 5860] [serial = 4] [outer = 1074E000]
06:56:06     INFO - PROCESS | 5860 | 1485096966778	Marionette	INFO	Listening on port 2829
06:56:08     INFO - PROCESS | 5860 | [5860] WARNING: getting z level of unregistered window: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/xpfe/appshell/nsWindowMediator.cpp, line 623
06:56:08     INFO - PROCESS | 5860 | [5860] WARNING: getting z level of unregistered window: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/xpfe/appshell/nsWindowMediator.cpp, line 623
06:56:08     INFO - PROCESS | 5860 | [5860] WARNING: getting z level of unregistered window: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/xpfe/appshell/nsWindowMediator.cpp, line 623
06:56:08     INFO - PROCESS | 5860 | [5860] WARNING: getting z level of unregistered window: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/xpfe/appshell/nsWindowMediator.cpp, line 623
06:56:08     INFO - PROCESS | 5860 | ++DOCSHELL 118B8C00 == 3 [pid = 5860] [id = {fe87c315-4608-4de8-91f0-a0e4c9acb36a}]
06:56:08     INFO - PROCESS | 5860 | ++DOMWINDOW == 5 (118B9000) [pid = 5860] [serial = 5] [outer = 00000000]
06:56:08     INFO - PROCESS | 5860 | ++DOMWINDOW == 6 (118B9800) [pid = 5860] [serial = 6] [outer = 118B9000]
06:56:08     INFO - PROCESS | 5860 | ++DOMWINDOW == 7 (1284D400) [pid = 5860] [serial = 7] [outer = 1074E000]
06:56:08     INFO - PROCESS | 5860 | [Child 5188] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/toolkit/xre/nsXREDirProvider.cpp, line 1703
06:56:08     INFO - PROCESS | 5860 | [Child 5188] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/xpcom/base/nsSystemInfo.cpp, line 116
06:56:09     INFO - PROCESS | 5860 | [Parent 5860] WARNING: attempt to modify an immutable nsStandardURL: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/netwerk/base/nsStandardURL.cpp, line 1630
06:56:09     INFO - PROCESS | 5860 | [Parent 5860] WARNING: attempt to modify an immutable nsStandardURL: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/netwerk/base/nsStandardURL.cpp, line 1630
06:56:09     INFO - PROCESS | 5860 | [Parent 5860] WARNING: Failed to retarget HTML data delivery to the parser thread.: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/parser/html/nsHtml5StreamParser.cpp, line 970
06:56:09     INFO - PROCESS | 5860 | ++DOCSHELL 08E98800 == 1 [pid = 5188] [id = {27ab7dbe-87be-49c8-9368-73a5ee121137}]
06:56:09     INFO - PROCESS | 5860 | ++DOMWINDOW == 1 (08E98C00) [pid = 5188] [serial = 1] [outer = 00000000]
06:56:09     INFO - PROCESS | 5860 | ++DOMWINDOW == 2 (0AB1AC00) [pid = 5188] [serial = 2] [outer = 08E98C00]
06:56:09     INFO - PROCESS | 5860 | ++DOMWINDOW == 3 (0ACACC00) [pid = 5188] [serial = 3] [outer = 08E98C00]
06:56:09     INFO - PROCESS | 5860 | ++DOCSHELL 14D96800 == 4 [pid = 5860] [id = {83d5bdca-d37b-4a2a-b1d1-9db489854828}]
06:56:09     INFO - PROCESS | 5860 | ++DOMWINDOW == 8 (14D96C00) [pid = 5860] [serial = 8] [outer = 00000000]
06:56:09     INFO - PROCESS | 5860 | ++DOCSHELL 14D97400 == 5 [pid = 5860] [id = {88e2d027-7c05-4dab-b069-1716044869ca}]
06:56:09     INFO - PROCESS | 5860 | ++DOMWINDOW == 9 (14D97800) [pid = 5860] [serial = 9] [outer = 00000000]
06:56:09     INFO - PROCESS | 5860 | [Parent 5860] WARNING: getting z level of unregistered window: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/xpfe/appshell/nsWindowMediator.cpp, line 623
06:56:09     INFO - PROCESS | 5860 | [Parent 5860] WARNING: getting z level of unregistered window: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/xpfe/appshell/nsWindowMediator.cpp, line 623
06:56:09     INFO - PROCESS | 5860 | [Child 5188] WARNING: pipe error: 109: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
06:56:09     INFO - PROCESS | 5860 | [Child 5188] WARNING: pipe error: 109: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
06:56:09     INFO - PROCESS | 5860 | [Parent 5860] WARNING: pipe error: 109: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
06:56:09     INFO - PROCESS | 5860 | [Parent 5860] WARNING: pipe error: 109: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
06:56:09     INFO - PROCESS | 5860 | [Parent 5860] WARNING: pipe error: 109: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
06:56:09     INFO - PROCESS | 5860 | [Child 5188] WARNING: NS_ENSURE_TRUE(maybeContext) failed: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/xpcom/threads/nsThread.cpp, line 1004
06:56:09     INFO - PROCESS | 5860 | [Parent 5860] WARNING: pipe error: 109: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
06:56:09     INFO - PROCESS | 5860 | --DOCSHELL 08E98800 == 0 [pid = 5188] [id = {27ab7dbe-87be-49c8-9368-73a5ee121137}]
06:56:09     INFO - PROCESS | 5860 | --DOMWINDOW == 2 (08E98C00) [pid = 5188] [serial = 1] [outer = 00000000] [url = chrome://gfxsanity/content/sanitytest.html]
06:56:09     INFO - PROCESS | 5860 | --DOMWINDOW == 1 (0AB1AC00) [pid = 5188] [serial = 2] [outer = 00000000] [url = about:blank]
06:56:09     INFO - PROCESS | 5860 | --DOMWINDOW == 0 (0ACACC00) [pid = 5188] [serial = 3] [outer = 00000000] [url = chrome://gfxsanity/content/sanitytest.html]
06:56:09     INFO - PROCESS | 5860 | [Child 5188] WARNING: '!compMgr', file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/xpcom/glue/nsComponentManagerUtils.cpp, line 63
06:56:09     INFO - PROCESS | 5860 | nsStringStats
06:56:09     INFO - PROCESS | 5860 |  => mAllocCount:          14145
06:56:09     INFO - PROCESS | 5860 |  => mReallocCount:          216
06:56:09     INFO - PROCESS | 5860 |  => mFreeCount:           14145
06:56:09     INFO - PROCESS | 5860 |  => mShareCount:          10257
06:56:09     INFO - PROCESS | 5860 |  => mAdoptCount:           1148
06:56:09     INFO - PROCESS | 5860 |  => mAdoptFreeCount:       1148
06:56:09     INFO - PROCESS | 5860 |  => Process ID: 5188, Thread ID: 4184
06:56:10     INFO - PROCESS | 5860 | ++DOCSHELL 163A5000 == 6 [pid = 5860] [id = {6df19675-dcda-44d8-b0b7-c81e7fb22acc}]
06:56:10     INFO - PROCESS | 5860 | ++DOMWINDOW == 10 (163A5400) [pid = 5860] [serial = 10] [outer = 00000000]
06:56:10     INFO - PROCESS | 5860 | ++DOMWINDOW == 11 (167A9400) [pid = 5860] [serial = 11] [outer = 163A5400]
06:56:10     INFO - PROCESS | 5860 | ++DOMWINDOW == 12 (118BA400) [pid = 5860] [serial = 12] [outer = 14D96C00]
06:56:10     INFO - PROCESS | 5860 | ++DOMWINDOW == 13 (163CFC00) [pid = 5860] [serial = 13] [outer = 14D97800]
06:56:10     INFO - PROCESS | 5860 | [Child 4420] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/toolkit/xre/nsXREDirProvider.cpp, line 1703
06:56:10     INFO - PROCESS | 5860 | [Child 4420] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/xpcom/base/nsSystemInfo.cpp, line 116
06:56:11     INFO - PROCESS | 5860 | ++DOCSHELL 095A9800 == 1 [pid = 4420] [id = {8734ada1-cda5-43a3-bb5e-6f5345b63b22}]
06:56:11     INFO - PROCESS | 5860 | ++DOMWINDOW == 1 (095A9C00) [pid = 4420] [serial = 1] [outer = 00000000]
06:56:11     INFO - PROCESS | 5860 | ++DOMWINDOW == 2 (0A26E400) [pid = 4420] [serial = 2] [outer = 095A9C00]
06:56:12     INFO - PROCESS | 5860 | ++DOMWINDOW == 3 (0C0EB000) [pid = 4420] [serial = 3] [outer = 095A9C00]
06:56:12     INFO - PROCESS | 5860 | [Child 4420] WARNING: site security information will not be persisted: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/security/manager/ssl/nsSiteSecurityService.cpp, line 268
06:56:12     INFO - PROCESS | 5860 | [Parent 5860] WARNING: Could not get disk status from nsIDiskSpaceWatcher: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/uriloader/prefetch/nsOfflineCacheUpdateService.cpp, line 283
06:56:12     INFO - PROCESS | 5860 | ++DOMWINDOW == 4 (0C18B400) [pid = 4420] [serial = 4] [outer = 095A9C00]


The error is occurring here:
https://dxr.mozilla.org/mozilla-central/source/testing/web-platform/tests/media-source/SourceBuffer-abort-updating.html#67


would it be acceptable to catch the exception and move on as indicated in comment 11?

:jya, can you pick up this bug and get it resolved?

[0] https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-inbound&job_id=71020119&lineNumber=6894
Flags: needinfo?(jyavenard)
The test is wrong, as part of the WPT. It should be modified upstream or disable alltogether.

Now that I have finished my primary task, I'll have a closer look and see if I can submit a fix upstream
Flags: needinfo?(jyavenard)
Assignee: nobody → jyavenard
:jya, checking in here as a week has passed, any luck figuring out a root cause?  If you want me to disable this test, I don't mind handling that.
I will be verifying this on try, I think my change looks right.
Attachment #8841013 - Flags: review?(gbrown)
fine by me to disable it; it's invalid anyway. But 99% of the time it works and is rather useful...
Comment on attachment 8841013 [details] [diff] [review]
disable the test until we can get time to look into it

Review of attachment 8841013 [details] [diff] [review]:
-----------------------------------------------------------------

Check your try push...looks like the tests didn't run.
Attachment #8841013 - Flags: review?(gbrown) → review+
Pushed by jmaher@mozilla.com:
https://hg.mozilla.org/integration/mozilla-inbound/rev/d19abdca97d4
Intermittent /media-source/SourceBuffer-abort-updating.html. disabled for now. r=gbrown
Whiteboard: [stockwell disabled]
https://hg.mozilla.org/mozilla-central/rev/d19abdca97d4
Status: NEW → RESOLVED
Closed: 7 years ago
status-firefox54: --- → fixed
Resolution: --- → FIXED
Target Milestone: --- → mozilla54
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: