Closed Bug 1601169 Opened 6 years ago Closed 6 years ago

Intermittent dom/ipc/tests/browser_crash_oopiframe.js | Uncaught exception - OperationError: The operation failed for an operation-specific reason | A promise chain failed to handle a rejection: [Exception... "Component is not available" nsresult: "0x8004

Categories

(Core :: DOM: Content Processes, defect, P5)

Product:
Core
Component:
DOM: Content Processes
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

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

[task 2019-12-04T04:16:29.010Z] 04:16:29 INFO - TEST-START | dom/ipc/tests/browser_crash_oopiframe.js
[task 2019-12-04T04:16:29.011Z] 04:16:29 INFO - GECKO(4798) | Chrome file doesn't exist: /builds/worker/workspace/build/tests/mochitest/browser/dom/ipc/tests/head.js
[task 2019-12-04T04:16:29.128Z] 04:16:29 INFO - GECKO(4798) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpi6ycz8.mozrunner/runtests_leaks_tab_pid5181.log
[task 2019-12-04T04:16:29.164Z] 04:16:29 INFO - GECKO(4798) | [Child 5181, Main Thread] WARNING: No CID found when attempting to map contract ID: file /builds/worker/workspace/build/src/xpcom/components/nsComponentManager.cpp, line 729
[task 2019-12-04T04:16:29.167Z] 04:16:29 INFO - GECKO(4798) | [Child 5156: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7f9d5becd800 == 0 [pid = 5156] [id = {bbb82baa-7822-4a0b-83e8-8abd39eefa78}] [url = http://mochi.test:8888/browser/dom/ipc/tests/file_cancel_content_js.html]
[task 2019-12-04T04:16:29.228Z] 04:16:29 INFO - GECKO(4798) | [Child 5156: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x7f9d5cb48f20) [pid = 5156] [serial = 1] [outer = (nil)] [url = http://mochi.test:8888/browser/dom/ipc/tests/file_cancel_content_js.html]
[task 2019-12-04T04:16:29.229Z] 04:16:29 INFO - GECKO(4798) | [Child 5156: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7f9d5be5c400) [pid = 5156] [serial = 3] [outer = (nil)] [url = http://mochi.test:8888/browser/dom/ipc/tests/file_cancel_content_js.html]
[task 2019-12-04T04:16:29.229Z] 04:16:29 INFO - GECKO(4798) | [Child 5156: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x7f9d5be51c00) [pid = 5156] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2019-12-04T04:16:29.266Z] 04:16:29 INFO - GECKO(4798) | nsStringStats
[task 2019-12-04T04:16:29.266Z] 04:16:29 INFO - GECKO(4798) | => mAllocCount: 8219
[task 2019-12-04T04:16:29.266Z] 04:16:29 INFO - GECKO(4798) | => mReallocCount: 0
[task 2019-12-04T04:16:29.266Z] 04:16:29 INFO - GECKO(4798) | => mFreeCount: 8219
[task 2019-12-04T04:16:29.266Z] 04:16:29 INFO - GECKO(4798) | => mShareCount: 12063452
[task 2019-12-04T04:16:29.266Z] 04:16:29 INFO - GECKO(4798) | => mAdoptCount: 324
[task 2019-12-04T04:16:29.266Z] 04:16:29 INFO - GECKO(4798) | => mAdoptFreeCount: 330
[task 2019-12-04T04:16:29.266Z] 04:16:29 INFO - GECKO(4798) | => Process ID: 5156, Thread ID: 140314288461632
[task 2019-12-04T04:16:29.310Z] 04:16:29 INFO - GECKO(4798) | [Parent 4798, Main Thread] WARNING: NS_ENSURE_TRUE(GetWrapper()) failed: file /builds/worker/workspace/build/src/dom/ipc/JSWindowActor.cpp, line 55
[task 2019-12-04T04:16:29.351Z] 04:16:29 INFO - GECKO(4798) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-12-04T04:16:29.352Z] 04:16:29 INFO - GECKO(4798) | [Child 5181, Main Thread] WARNING: Could not get the program name for a cubeb stream.: 'NS_SUCCEEDED(rv)', file /builds/worker/workspace/build/src/dom/media/CubebUtils.cpp, line 381
[task 2019-12-04T04:16:29.532Z] 04:16:29 INFO - GECKO(4798) | [Child 5181, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/workspace/build/src/dom/ipc/ContentChild.cpp, line 1798
[task 2019-12-04T04:16:29.547Z] 04:16:29 INFO - GECKO(4798) | [Child 5181: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f21d5dcd800 == 1 [pid = 5181] [id = {f3011de7-7a83-4c90-bdf3-0ab3e23b4f72}]
[task 2019-12-04T04:16:29.603Z] 04:16:29 INFO - GECKO(4798) | [Child 5181: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7f21d6b89f20) [pid = 5181] [serial = 1] [outer = (nil)]
[task 2019-12-04T04:16:29.604Z] 04:16:29 INFO - GECKO(4798) | [Child 5181, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/workspace/build/src/layout/base/nsPresContext.cpp, line 848
[task 2019-12-04T04:16:29.604Z] 04:16:29 INFO - GECKO(4798) | [Child 5181: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7f21d6b0c800) [pid = 5181] [serial = 2] [outer = 0x7f21d6b89f20]
[task 2019-12-04T04:16:29.727Z] 04:16:29 INFO - GECKO(4798) | [Child 5181: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7f21d6ba6000) [pid = 5181] [serial = 3] [outer = 0x7f21d6b89f20]
[task 2019-12-04T04:16:29.783Z] 04:16:29 INFO - GECKO(4798) | [Child 5181: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f21d6a94000 == 2 [pid = 5181] [id = {6ae22176-63e2-4e5e-97cf-a63d52855335}]
[task 2019-12-04T04:16:29.784Z] 04:16:29 INFO - GECKO(4798) | [Child 5181: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (0x7f21d6b89200) [pid = 5181] [serial = 4] [outer = (nil)]
[task 2019-12-04T04:16:29.914Z] 04:16:29 INFO - GECKO(4798) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpi6ycz8.mozrunner/runtests_leaks_tab_pid5205.log
[task 2019-12-04T04:16:29.915Z] 04:16:29 INFO - GECKO(4798) | [Child 5181, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannelChild, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannelChild.cpp, line 63
[task 2019-12-04T04:16:29.916Z] 04:16:29 INFO - GECKO(4798) | [Child 5181, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannelChild, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannelChild.cpp, line 63
[task 2019-12-04T04:16:29.917Z] 04:16:29 INFO - GECKO(4798) | [Child 5181, Main Thread] WARNING: NS_ENSURE_TRUE(request) failed: file /builds/worker/workspace/build/src/netwerk/base/nsLoadGroup.cpp, line 575
[task 2019-12-04T04:16:29.917Z] 04:16:29 INFO - GECKO(4798) | [Child 5181, Main Thread] WARNING: NS_ENSURE_TRUE(currentInner) failed: file /builds/worker/workspace/build/src/dom/base/WindowDestroyedEvent.cpp, line 106
[task 2019-12-04T04:16:29.942Z] 04:16:29 INFO - GECKO(4798) | [Child 5205, Main Thread] WARNING: No CID found when attempting to map contract ID: file /builds/worker/workspace/build/src/xpcom/components/nsComponentManager.cpp, line 729
[task 2019-12-04T04:16:29.942Z] 04:16:29 INFO - GECKO(4798) | [Parent 4798, Main Thread] WARNING: we only accept nsIURI interface type, patch welcome: file /builds/worker/workspace/build/src/dom/ipc/PropertyBagUtils.cpp, line 112
[task 2019-12-04T04:16:30.018Z] 04:16:30 INFO - GECKO(4798) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-12-04T04:16:30.114Z] 04:16:30 INFO - GECKO(4798) | [Child 5205, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/workspace/build/src/dom/ipc/ContentChild.cpp, line 1798
[task 2019-12-04T04:16:30.121Z] 04:16:30 INFO - GECKO(4798) | [Child 5205: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f9f6e3d3800 == 1 [pid = 5205] [id = {6ae22176-63e2-4e5e-97cf-a63d52855335}]
[task 2019-12-04T04:16:30.162Z] 04:16:30 INFO - GECKO(4798) | [Child 5205: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7f9f6f3dbf20) [pid = 5205] [serial = 1] [outer = (nil)]
[task 2019-12-04T04:16:30.163Z] 04:16:30 INFO - GECKO(4798) | [Child 5205, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/workspace/build/src/layout/base/nsPresContext.cpp, line 848
[task 2019-12-04T04:16:30.163Z] 04:16:30 INFO - GECKO(4798) | [Child 5205: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7f9f6e210c00) [pid = 5205] [serial = 2] [outer = 0x7f9f6f3dbf20]
[task 2019-12-04T04:16:30.211Z] 04:16:30 INFO - GECKO(4798) | [Child 5205: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7f9f6f1b8c00) [pid = 5205] [serial = 3] [outer = 0x7f9f6f3dbf20]
[task 2019-12-04T04:16:30.293Z] 04:16:30 INFO - GECKO(4798) | Et tu, Brute?
[task 2019-12-04T04:16:30.293Z] 04:16:30 INFO - GECKO(4798) | XPCOM_MEM_BLOAT_LOG: /tmp/tmpi6ycz8.mozrunner/runtests_leaks.log
[task 2019-12-04T04:16:30.293Z] 04:16:30 INFO - GECKO(4798) | Writing to log: /tmp/tmpi6ycz8.mozrunner/runtests_leaks_tab_pid5205.log
[task 2019-12-04T04:16:30.356Z] 04:16:30 INFO - GECKO(4798) | [Child 5181: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f21d6a94800 == 3 [pid = 5181] [id = {6ae22176-63e2-4e5e-97cf-a63d52855335}]
[task 2019-12-04T04:16:30.356Z] 04:16:30 INFO - GECKO(4798) | [Child 5181: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (0x7f21d6b8a100) [pid = 5181] [serial = 5] [outer = (nil)]
[task 2019-12-04T04:16:30.372Z] 04:16:30 INFO - GECKO(4798) | [Child 5181: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 6 (0x7f21d6ba9400) [pid = 5181] [serial = 6] [outer = 0x7f21d6b8a100]
[task 2019-12-04T04:16:30.388Z] 04:16:30 INFO - GECKO(4798) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpi6ycz8.mozrunner/runtests_leaks_tab_pid5230.log
[task 2019-12-04T04:16:30.404Z] 04:16:30 INFO - GECKO(4798) | [Child 5230, Main Thread] WARNING: No CID found when attempting to map contract ID: file /builds/worker/workspace/build/src/xpcom/components/nsComponentManager.cpp, line 729
[task 2019-12-04T04:16:30.427Z] 04:16:30 INFO - GECKO(4798) | [Parent 4798, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/workspace/build/src/obj-firefox/dist/include/mozilla/ipc/ProtocolUtils.h, line 296
[task 2019-12-04T04:16:30.427Z] 04:16:30 INFO - GECKO(4798) | [Parent 4798, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/workspace/build/src/ipc/glue/ProtocolUtils.cpp, line 481
[task 2019-12-04T04:16:30.435Z] 04:16:30 INFO - GECKO(4798) | [Child 5181: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (0x7f21d6b89200) [pid = 5181] [serial = 4] [outer = (nil)] [url = ]
[task 2019-12-04T04:16:30.520Z] 04:16:30 INFO - GECKO(4798) | [Child 5181: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 6 (0x7f21d6bacc00) [pid = 5181] [serial = 7] [outer = 0x7f21d6b8a100]
[task 2019-12-04T04:16:30.538Z] 04:16:30 INFO - TEST-INFO | started process screentopng
[task 2019-12-04T04:16:31.219Z] 04:16:31 INFO - TEST-INFO | screentopng: exit 0
[task 2019-12-04T04:16:31.219Z] 04:16:31 INFO - Buffered messages logged at 04:16:29
[task 2019-12-04T04:16:31.219Z] 04:16:31 INFO - Entering test bound
[task 2019-12-04T04:16:31.219Z] 04:16:31 INFO - TEST-PASS | dom/ipc/tests/browser_crash_oopiframe.js | This test only makes sense of we can use OOP iframes. -
[task 2019-12-04T04:16:31.219Z] 04:16:31 INFO - Buffered messages logged at 04:16:30
[task 2019-12-04T04:16:31.219Z] 04:16:31 INFO - TEST-PASS | dom/ipc/tests/browser_crash_oopiframe.js | oop frame has root as parent -
[task 2019-12-04T04:16:31.219Z] 04:16:31 INFO - Waiting for oop-browser-crashed event.
[task 2019-12-04T04:16:31.220Z] 04:16:31 INFO - Console message: [JavaScript Error: "remote browser crashed while on about:blank
[task 2019-12-04T04:16:31.220Z] 04:16:31 INFO - " {file: "chrome://mochikit/content/mochitest-e10s-utils.js" line: 10}]
[task 2019-12-04T04:16:31.220Z] 04:16:31 INFO - e10s_init/<@chrome://mochikit/content/mochitest-e10s-utils.js:10:10
[task 2019-12-04T04:16:31.220Z] 04:16:31 INFO - EventListener.handleEvente10s_init@chrome://mochikit/content/mochitest-e10s-utils.js:6:10
[task 2019-12-04T04:16:31.220Z] 04:16:31 INFO - testInit@chrome://mochikit/content/browser-test.js:130:5
[task 2019-12-04T04:16:31.220Z] 04:16:31 INFO - setTimeout handler@chrome://mochikit/content/browser-test.js:40:11
[task 2019-12-04T04:16:31.220Z] 04:16:31 INFO - loadChromeScripts@jar:file:///tmp/tmpi6ycz8.mozrunner/extensions/mochikit@mozilla.org.xpi!/api.js:21:25
[task 2019-12-04T04:16:31.221Z] 04:16:31 INFO - loadMochitest@jar:file:///tmp/tmpi6ycz8.mozrunner/extensions/mochikit@mozilla.org.xpi!/api.js:143:20
[task 2019-12-04T04:16:31.221Z] 04:16:31 INFO - @tests/mochitest/runtests.py:1792:5
[task 2019-12-04T04:16:31.221Z] 04:16:31 INFO - @tests/mochitest/runtests.py:1793:8
[task 2019-12-04T04:16:31.221Z] 04:16:31 INFO - evaluate.sandbox/promise<@chrome://marionette/content/evaluate.js:138:10
[task 2019-12-04T04:16:31.221Z] 04:16:31 INFO - evaluate.sandbox@chrome://marionette/content/evaluate.js:114:17
[task 2019-12-04T04:16:31.221Z] 04:16:31 INFO - GeckoDriver.prototype.execute_@chrome://marionette/content/driver.js:1068:28
[task 2019-12-04T04:16:31.221Z] 04:16:31 INFO - async*GeckoDriver.prototype.executeScript@chrome://marionette/content/driver.js:943:30
[task 2019-12-04T04:16:31.221Z] 04:16:31 INFO - despatch@chrome://marionette/content/server.js:305:40
[task 2019-12-04T04:16:31.221Z] 04:16:31 INFO - execute@chrome://marionette/content/server.js:275:16
[task 2019-12-04T04:16:31.221Z] 04:16:31 INFO - onPacket/<@chrome://marionette/content/server.js:248:20
[task 2019-12-04T04:16:31.221Z] 04:16:31 INFO - onPacket@chrome://marionette/content/server.js:249:9
[task 2019-12-04T04:16:31.222Z] 04:16:31 INFO - _onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20
[task 2019-12-04T04:16:31.222Z] 04:16:31 INFO -
[task 2019-12-04T04:16:31.222Z] 04:16:31 INFO - TEST-PASS | dom/ipc/tests/browser_crash_oopiframe.js | should not be reporting top-level frame crash -
[task 2019-12-04T04:16:31.223Z] 04:16:31 INFO - TEST-PASS | dom/ipc/tests/browser_crash_oopiframe.js | top frame browsing context id not expected. -
[task 2019-12-04T04:16:31.223Z] 04:16:31 INFO - TEST-PASS | dom/ipc/tests/browser_crash_oopiframe.js | oop frame browsing context id expected. -
[task 2019-12-04T04:16:31.223Z] 04:16:31 INFO - Buffered messages finished
[task 2019-12-04T04:16:31.224Z] 04:16:31 INFO - TEST-UNEXPECTED-FAIL | dom/ipc/tests/browser_crash_oopiframe.js | Uncaught exception - OperationError: The operation failed for an operation-specific reason
[task 2019-12-04T04:16:31.224Z] 04:16:31 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-12-04T04:16:31.227Z] 04:16:31 INFO - TEST-UNEXPECTED-FAIL | dom/ipc/tests/browser_crash_oopiframe.js | A promise chain failed to handle a rejection: [Exception... "Component is not available" nsresult: "0x80040111 (NS_ERROR_NOT_AVAILABLE)" location: "<unknown>" data: no] - stack: (No stack available.)
[task 2019-12-04T04:16:31.227Z] 04:16:31 INFO - Rejection date: Wed Dec 04 2019 04:16:30 GMT+0000 (Coordinated Universal Time) - false == true - JS frame :: resource://testing-common/PromiseTestUtils.jsm :: assertNoUncaughtRejections :: line 265
[task 2019-12-04T04:16:31.228Z] 04:16:31 INFO - Stack trace:
[task 2019-12-04T04:16:31.228Z] 04:16:31 INFO - resource://testing-common/PromiseTestUtils.jsm:assertNoUncaughtRejections:265
[task 2019-12-04T04:16:31.229Z] 04:16:31 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1100
[task 2019-12-04T04:16:31.231Z] 04:16:31 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1104
[task 2019-12-04T04:16:31.231Z] 04:16:31 INFO - chrome://mochikit/content/browser-test.js:nextTest/<:932
[task 2019-12-04T04:16:31.232Z] 04:16:31 INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:805
[task 2019-12-04T04:16:31.232Z] 04:16:31 INFO - Leaving test bound
[task 2019-12-04T04:16:31.233Z] 04:16:31 INFO - GECKO(4798) | JavaScript error: , line 0: NS_ERROR_NOT_AVAILABLE
[task 2019-12-04T04:16:31.233Z] 04:16:31 INFO - Console message: [JavaScript Error: "NS_ERROR_NOT_AVAILABLE"]
[task 2019-12-04T04:16:31.234Z] 04:16:31 INFO - GECKO(4798) | [Child 5181, Main Thread] WARNING: '!mCanSend || !mManager || !mManager->CanSend()', file /builds/worker/workspace/build/src/dom/ipc/JSWindowActorChild.cpp, line 74
[task 2019-12-04T04:16:31.235Z] 04:16:31 INFO - GECKO(4798) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-12-04T04:16:31.235Z] 04:16:31 INFO - GECKO(4798) | [Child 5230, Main Thread] WARNING: Could not get the program name for a cubeb stream.: 'NS_SUCCEEDED(rv)', file /builds/worker/workspace/build/src/dom/media/CubebUtils.cpp, line 381
[task 2019-12-04T04:16:31.237Z] 04:16:31 INFO - GECKO(4798) | [Child 5181, Main Thread] WARNING: '!aWin', file /builds/worker/workspace/build/src/dom/base/Document.cpp, line 1385
[task 2019-12-04T04:16:31.238Z] 04:16:31 INFO - GECKO(4798) | [Child 5181, Main Thread] WARNING: '!aWin', file /builds/worker/workspace/build/src/dom/base/Document.cpp, line 1385
[task 2019-12-04T04:16:31.239Z] 04:16:31 INFO - GECKO(4798) | MEMORY STAT | vsize 2966MB | residentFast 356MB | heapAllocated 113MB
[task 2019-12-04T04:16:31.240Z] 04:16:31 INFO - TEST-OK | dom/ipc/tests/browser_crash_oopiframe.js | took 1583ms
[task 2019-12-04T04:16:31.241Z] 04:16:31 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-12-04T04:16:31.241Z] 04:16:31 INFO - TEST-UNEXPECTED-FAIL | dom/ipc/tests/browser_crash_oopiframe.js | Found an unexpected tab at the end of test run: about:blank -
[task 2019-12-04T04:16:31.243Z] 04:16:31 INFO - GECKO(4798) | [Child 5181: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7f21d6a94000 == 2 [pid = 5181] [id = {6ae22176-63e2-4e5e-97cf-a63d52855335}] [url = ]
[task 2019-12-04T04:16:31.243Z] 04:16:31 INFO - checking window state

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

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