Closed Bug 1593172 Opened 6 years ago Closed 5 years ago

Intermittent dom/ipc/tests/browser_crash_oopiframe.js | The iframe is now pointing at about:framecrashed -

Categories

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

defect

Tracking

()

RESOLVED FIXED
mozilla80
Tracking Status
firefox80 --- fixed

People

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

Details

(Keywords: intermittent-failure)

Attachments

(1 file)

Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=274013364&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/XPmdTsXnRtaovafTmZEoVg/runs/0/artifacts/public/logs/live_backing.log


[task 2019-11-01T04:08:37.895Z] 04:08:37 INFO - TEST-START | dom/ipc/tests/browser_crash_oopiframe.js
[task 2019-11-01T04:08:37.898Z] 04:08:37 INFO - GECKO(9445) | Chrome file doesn't exist: /builds/worker/workspace/build/tests/mochitest/browser/dom/ipc/tests/head.js
[task 2019-11-01T04:08:37.923Z] 04:08:37 INFO - GECKO(9445) | --DOMWINDOW == 2 (0x7f58113c2f20) [pid = 9744] [serial = 1] [outer = (nil)] [url = https://example.org/]
[task 2019-11-01T04:08:37.924Z] 04:08:37 INFO - GECKO(9445) | --DOMWINDOW == 1 (0x7f5810d7fc00) [pid = 9744] [serial = 3] [outer = (nil)] [url = https://example.org/]
[task 2019-11-01T04:08:37.924Z] 04:08:37 INFO - GECKO(9445) | --DOMWINDOW == 0 (0x7f5810d73000) [pid = 9744] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2019-11-01T04:08:37.964Z] 04:08:37 INFO - GECKO(9445) | nsStringStats
[task 2019-11-01T04:08:37.966Z] 04:08:37 INFO - GECKO(9445) | => mAllocCount: 8248
[task 2019-11-01T04:08:37.966Z] 04:08:37 INFO - GECKO(9445) | => mReallocCount: 0
[task 2019-11-01T04:08:37.966Z] 04:08:37 INFO - GECKO(9445) | => mFreeCount: 8248
[task 2019-11-01T04:08:37.966Z] 04:08:37 INFO - GECKO(9445) | => mShareCount: 7024
[task 2019-11-01T04:08:37.967Z] 04:08:37 INFO - GECKO(9445) | => mAdoptCount: 578
[task 2019-11-01T04:08:37.967Z] 04:08:37 INFO - GECKO(9445) | => mAdoptFreeCount: 582
[task 2019-11-01T04:08:37.967Z] 04:08:37 INFO - GECKO(9445) | => Process ID: 9744, Thread ID: 140016659240768
[task 2019-11-01T04:08:38.007Z] 04:08:38 INFO - GECKO(9445) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp1vovFU.mozrunner/runtests_leaks_tab_pid9769.log
[task 2019-11-01T04:08:38.023Z] 04:08:38 INFO - GECKO(9445) | [Child 9769, 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-11-01T04:08:38.184Z] 04:08:38 INFO - GECKO(9445) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-11-01T04:08:38.355Z] 04:08:38 INFO - GECKO(9445) | [Child 9769, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/workspace/build/src/dom/ipc/ContentChild.cpp, line 1787
[task 2019-11-01T04:08:38.355Z] 04:08:38 INFO - GECKO(9445) | ++DOCSHELL 0x7f33215b2000 == 1 [pid = 9769] [id = {6fdff8c6-9239-4e95-b3a7-c0fcd21c02bc}]
[task 2019-11-01T04:08:38.415Z] 04:08:38 INFO - GECKO(9445) | ++DOMWINDOW == 1 (0x7f3322587f20) [pid = 9769] [serial = 1] [outer = (nil)]
[task 2019-11-01T04:08:38.416Z] 04:08:38 INFO - GECKO(9445) | ++DOMWINDOW == 2 (0x7f33225e0800) [pid = 9769] [serial = 2] [outer = 0x7f3322587f20]
[task 2019-11-01T04:08:38.491Z] 04:08:38 INFO - GECKO(9445) | ++DOMWINDOW == 3 (0x7f3321cd6000) [pid = 9769] [serial = 3] [outer = 0x7f3322587f20]
[task 2019-11-01T04:08:38.549Z] 04:08:38 INFO - GECKO(9445) | ++DOCSHELL 0x7f332114c000 == 2 [pid = 9769] [id = {8632cf72-ae43-41ea-bb48-d06ee267d145}]
[task 2019-11-01T04:08:38.550Z] 04:08:38 INFO - GECKO(9445) | ++DOMWINDOW == 4 (0x7f3322588100) [pid = 9769] [serial = 4] [outer = (nil)]
[task 2019-11-01T04:08:38.628Z] 04:08:38 INFO - GECKO(9445) | [Parent 9445, Main Thread] WARNING: NS_ENSURE_TRUE(aCSP) failed: file /builds/worker/workspace/build/src/dom/security/FramingChecker.cpp, line 175
[task 2019-11-01T04:08:38.704Z] 04:08:38 INFO - GECKO(9445) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp1vovFU.mozrunner/runtests_leaks_tab_pid9793.log
[task 2019-11-01T04:08:38.721Z] 04:08:38 INFO - GECKO(9445) | [Child 9769, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannelChild, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannelChild.cpp, line 42
[task 2019-11-01T04:08:38.721Z] 04:08:38 INFO - GECKO(9445) | [Child 9769, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannelChild, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannelChild.cpp, line 42
[task 2019-11-01T04:08:38.721Z] 04:08:38 INFO - GECKO(9445) | [Child 9769, Main Thread] WARNING: NS_ENSURE_TRUE(request) failed: file /builds/worker/workspace/build/src/netwerk/base/nsLoadGroup.cpp, line 575
[task 2019-11-01T04:08:38.721Z] 04:08:38 INFO - GECKO(9445) | [Child 9769, Main Thread] WARNING: NS_ENSURE_TRUE(currentInner) failed: file /builds/worker/workspace/build/src/dom/base/WindowDestroyedEvent.cpp, line 106
[task 2019-11-01T04:08:38.737Z] 04:08:38 INFO - GECKO(9445) | [Child 9793, 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-11-01T04:08:38.841Z] 04:08:38 INFO - GECKO(9445) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-11-01T04:08:39.003Z] 04:08:39 INFO - GECKO(9445) | [Child 9793, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/workspace/build/src/dom/ipc/ContentChild.cpp, line 1787
[task 2019-11-01T04:08:39.019Z] 04:08:39 INFO - GECKO(9445) | ++DOCSHELL 0x7f90c46b4800 == 1 [pid = 9793] [id = {c00c9f19-d3c0-41e4-9d61-be35d741c595}]
[task 2019-11-01T04:08:39.055Z] 04:08:39 INFO - GECKO(9445) | ++DOMWINDOW == 1 (0x7f90c55c5f20) [pid = 9793] [serial = 1] [outer = (nil)]
[task 2019-11-01T04:08:39.055Z] 04:08:39 INFO - GECKO(9445) | ++DOMWINDOW == 2 (0x7f90c4666c00) [pid = 9793] [serial = 2] [outer = 0x7f90c55c5f20]
[task 2019-11-01T04:08:39.097Z] 04:08:39 INFO - GECKO(9445) | ++DOMWINDOW == 3 (0x7f90c5508c00) [pid = 9793] [serial = 3] [outer = 0x7f90c55c5f20]
[task 2019-11-01T04:08:39.134Z] 04:08:39 INFO - GECKO(9445) | --DOMWINDOW == 11 (0x7f210f37fc00) [pid = 9445] [serial = 5] [outer = (nil)] [url = about:blank]
[task 2019-11-01T04:08:39.134Z] 04:08:39 INFO - GECKO(9445) | --DOMWINDOW == 10 (0x7f210c46cc00) [pid = 9445] [serial = 9] [outer = (nil)] [url = about:blank]
[task 2019-11-01T04:08:39.233Z] 04:08:39 INFO - GECKO(9445) | Et tu, Brute?
[task 2019-11-01T04:08:39.233Z] 04:08:39 INFO - GECKO(9445) | XPCOM_MEM_BLOAT_LOG: /tmp/tmp1vovFU.mozrunner/runtests_leaks.log
[task 2019-11-01T04:08:39.233Z] 04:08:39 INFO - GECKO(9445) | Writing to log: /tmp/tmp1vovFU.mozrunner/runtests_leaks_tab_pid9793.log
[task 2019-11-01T04:08:39.301Z] 04:08:39 INFO - GECKO(9445) | [Parent 9445, Gecko_IOThread] WARNING: pipe error (70): Connection reset by peer: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 358
[task 2019-11-01T04:08:39.453Z] 04:08:39 INFO - GECKO(9445) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv
<...>
[task 2019-11-01T04:08:39.637Z] 04:08:39 INFO - GECKO(9445) | ###!!! [Parent][MessageChannel] Error: (msgtype=0x1E0075,name=PBrowser::Msg_ChildToParentMatrix) Channel error: cannot send/recv
[task 2019-11-01T04:08:39.638Z] 04:08:39 INFO - GECKO(9445) | ++DOCSHELL 0x7f332114d800 == 3 [pid = 9769] [id = {56b38d3c-d694-4ab9-9e24-274ed1aa3e21}]
[task 2019-11-01T04:08:39.639Z] 04:08:39 INFO - GECKO(9445) | ++DOMWINDOW == 5 (0x7f33225882e0) [pid = 9769] [serial = 5] [outer = (nil)]
[task 2019-11-01T04:08:39.639Z] 04:08:39 INFO - GECKO(9445) | [Parent 9445, 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-11-01T04:08:39.640Z] 04:08:39 INFO - GECKO(9445) | [Parent 9445, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/workspace/build/src/ipc/glue/ProtocolUtils.cpp, line 481
[task 2019-11-01T04:08:39.640Z] 04:08:39 INFO - GECKO(9445) | ++DOMWINDOW == 6 (0x7f33225de400) [pid = 9769] [serial = 6] [outer = 0x7f33225882e0]
[task 2019-11-01T04:08:39.640Z] 04:08:39 INFO - GECKO(9445) | ++DOMWINDOW == 7 (0x7f33225e7000) [pid = 9769] [serial = 7] [outer = 0x7f33225882e0]
[task 2019-11-01T04:08:39.651Z] 04:08:39 INFO - TEST-INFO | started process screentopng
[task 2019-11-01T04:08:40.331Z] 04:08:40 INFO - TEST-INFO | screentopng: exit 0
[task 2019-11-01T04:08:40.332Z] 04:08:40 INFO - Buffered messages logged at 04:08:37
[task 2019-11-01T04:08:40.333Z] 04:08:40 INFO - Entering test bound
[task 2019-11-01T04:08:40.333Z] 04:08:40 INFO - TEST-PASS | dom/ipc/tests/browser_crash_oopiframe.js | This test only makes sense of we can use OOP iframes. -
[task 2019-11-01T04:08:40.337Z] 04:08:40 INFO - Buffered messages logged at 04:08:39
[task 2019-11-01T04:08:40.338Z] 04:08:40 INFO - TEST-PASS | dom/ipc/tests/browser_crash_oopiframe.js | oop frame has root as parent -
[task 2019-11-01T04:08:40.339Z] 04:08:40 INFO - Waiting for oop-browser-crashed event.
[task 2019-11-01T04:08:40.339Z] 04:08:40 INFO - Console message: [JavaScript Error: "remote browser crashed while on about:blank
[task 2019-11-01T04:08:40.339Z] 04:08:40 INFO - " {file: "chrome://mochikit/content/mochitest-e10s-utils.js" line: 10}]
[task 2019-11-01T04:08:40.339Z] 04:08:40 INFO - e10s_init/<@chrome://mochikit/content/mochitest-e10s-utils.js:10:10
[task 2019-11-01T04:08:40.339Z] 04:08:40 INFO - EventListener.handleEvente10s_init@chrome://mochikit/content/mochitest-e10s-utils.js:6:10
[task 2019-11-01T04:08:40.339Z] 04:08:40 INFO - testInit@chrome://mochikit/content/browser-test.js:130:5
[task 2019-11-01T04:08:40.339Z] 04:08:40 INFO - setTimeout handler
@chrome://mochikit/content/browser-test.js:40:11
[task 2019-11-01T04:08:40.339Z] 04:08:40 INFO - loadChromeScripts@jar:file:///tmp/tmp1vovFU.mozrunner/extensions/mochikit@mozilla.org.xpi!/api.js:21:25
[task 2019-11-01T04:08:40.339Z] 04:08:40 INFO - loadMochitest@jar:file:///tmp/tmp1vovFU.mozrunner/extensions/mochikit@mozilla.org.xpi!/api.js:143:20
[task 2019-11-01T04:08:40.341Z] 04:08:40 INFO - @tests/mochitest/runtests.py:1794:5
[task 2019-11-01T04:08:40.341Z] 04:08:40 INFO - @tests/mochitest/runtests.py:1795:8
[task 2019-11-01T04:08:40.341Z] 04:08:40 INFO - evaluate.sandbox/promise<@chrome://marionette/content/evaluate.js:138:10
[task 2019-11-01T04:08:40.341Z] 04:08:40 INFO - evaluate.sandbox@chrome://marionette/content/evaluate.js:114:17
[task 2019-11-01T04:08:40.341Z] 04:08:40 INFO - GeckoDriver.prototype.execute_@chrome://marionette/content/driver.js:1068:28
[task 2019-11-01T04:08:40.341Z] 04:08:40 INFO - async*GeckoDriver.prototype.executeScript@chrome://marionette/content/driver.js:943:30
[task 2019-11-01T04:08:40.341Z] 04:08:40 INFO - despatch@chrome://marionette/content/server.js:305:40
[task 2019-11-01T04:08:40.341Z] 04:08:40 INFO - execute@chrome://marionette/content/server.js:275:16
[task 2019-11-01T04:08:40.341Z] 04:08:40 INFO - onPacket/<@chrome://marionette/content/server.js:248:20
[task 2019-11-01T04:08:40.341Z] 04:08:40 INFO - onPacket@chrome://marionette/content/server.js:249:9
[task 2019-11-01T04:08:40.341Z] 04:08:40 INFO - _onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20
[task 2019-11-01T04:08:40.341Z] 04:08:40 INFO -
[task 2019-11-01T04:08:40.341Z] 04:08:40 INFO - TEST-PASS | dom/ipc/tests/browser_crash_oopiframe.js | should not be reporting top-level frame crash -
[task 2019-11-01T04:08:40.341Z] 04:08:40 INFO - TEST-PASS | dom/ipc/tests/browser_crash_oopiframe.js | top frame browsing context id not expected. -
[task 2019-11-01T04:08:40.341Z] 04:08:40 INFO - TEST-PASS | dom/ipc/tests/browser_crash_oopiframe.js | oop frame browsing context id expected. -
[task 2019-11-01T04:08:40.341Z] 04:08:40 INFO - Buffered messages finished
[task 2019-11-01T04:08:40.341Z] 04:08:40 INFO - TEST-UNEXPECTED-FAIL | dom/ipc/tests/browser_crash_oopiframe.js | The iframe is now pointing at about:framecrashed -
[task 2019-11-01T04:08:40.341Z] 04:08:40 INFO - Stack trace:
[task 2019-11-01T04:08:40.341Z] 04:08:40 INFO - chrome://mochikit/content/browser-test.js:test_ok:1299
[task 2019-11-01T04:08:40.341Z] 04:08:40 INFO - chrome://mochitests/content/browser/dom/ipc/tests/browser_crash_oopiframe.js:null:79
[task 2019-11-01T04:08:40.341Z] 04:08:40 INFO - resource://testing-common/BrowserTestUtils.jsm:withNewTab:155
[task 2019-11-01T04:08:40.341Z] 04:08:40 INFO - chrome://mochitests/content/browser/dom/ipc/tests/browser_crash_oopiframe.js:null:18
[task 2019-11-01T04:08:40.341Z] 04:08:40 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1069
[task 2019-11-01T04:08:40.341Z] 04:08:40 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1104
[task 2019-11-01T04:08:40.341Z] 04:08:40 INFO - chrome://mochikit/content/browser-test.js:nextTest/<:932
[task 2019-11-01T04:08:40.341Z] 04:08:40 INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:805
[task 2019-11-01T04:08:40.341Z] 04:08:40 INFO - GECKO(9445) | No .extra file for dumpID: 64e1a94e-7f24-ba72-346f-b46ca774d73c
[task 2019-11-01T04:08:40.341Z] 04:08:40 INFO - GECKO(9445) | Crash cleaned up
[task 2019-11-01T04:08:40.341Z] 04:08:40 INFO - GECKO(9445) | [Child 9769, Main Thread] WARNING: '!aWin', file /builds/worker/workspace/build/src/dom/base/Document.cpp, line 1386
[task 2019-11-01T04:08:40.341Z] 04:08:40 INFO - GECKO(9445) | [Child 9769, Main Thread] WARNING: '!aWin', file /builds/worker/workspace/build/src/dom/base/Document.cpp, line 1386
[task 2019-11-01T04:08:40.342Z] 04:08:40 INFO - GECKO(9445) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp1vovFU.mozrunner/runtests_leaks_tab_pid9824.log
[task 2019-11-01T04:08:40.353Z] 04:08:40 INFO - GECKO(9445) | --DOMWINDOW == 6 (0x7f3322588100) [pid = 9769] [serial = 4] [outer = (nil)] [url = ]
[task 2019-11-01T04:08:40.353Z] 04:08:40 INFO - Leaving test bound
[task 2019-11-01T04:08:40.353Z] 04:08:40 INFO - GECKO(9445) | [Child 9824, 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-11-01T04:08:40.353Z] 04:08:40 INFO - GECKO(9445) | --DOCSHELL 0x7f93b27b0800 == 0 [pid = 9650] [id = {439a8c85-b4cb-40c3-a0d8-ac557bbb4256}] [url = about:blank]
[task 2019-11-01T04:08:40.353Z] 04:08:40 INFO - GECKO(9445) | --DOCSHELL 0x7f332114d800 == 2 [pid = 9769] [id = {56b38d3c-d694-4ab9-9e24-274ed1aa3e21}] [url = about:blank]
[task 2019-11-01T04:08:40.353Z] 04:08:40 INFO - GECKO(9445) | --DOCSHELL 0x7f332114c000 == 1 [pid = 9769] [id = {8632cf72-ae43-41ea-bb48-d06ee267d145}] [url = ]
[task 2019-11-01T04:08:40.353Z] 04:08:40 INFO - GECKO(9445) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-11-01T04:08:40.353Z] 04:08:40 INFO - GECKO(9445) | [Child 9824, 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-11-01T04:08:40.353Z] 04:08:40 INFO - GECKO(9445) | MEMORY STAT | vsize 2864MB | residentFast 361MB | heapAllocated 126MB
[task 2019-11-01T04:08:40.353Z] 04:08:40 INFO - TEST-OK | dom/ipc/tests/browser_crash_oopiframe.js | took 2166ms
[task 2019-11-01T04:08:40.353Z] 04:08:40 INFO - checking window state

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

Pernosco trace that I got from an unrelated try push: https://pernos.co/debug/fT1uvSJMFUomyk5j-cjAeg/index.html

It looks like using waitForCondition for a currentWindowGlobal is sometimes giving us the window global for the initial about:blank, not the real document we load immediately after.

Assignee: nobody → matt.woodrow
Pushed by mwoodrow@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/6615bc2e9a81 Wait for a window global that isn't about:blank. r=mconley
Status: REOPENED → RESOLVED
Closed: 6 years ago5 years ago
Resolution: --- → FIXED
Target Milestone: --- → mozilla80
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: