Closed Bug 1640427 Opened 6 years ago Closed 6 years ago

[Fission] Intermittent dom/base/test/browser_force_process_selector.js | shouldn't have created a new process -

Categories

(Core :: DOM: Core & HTML, defect, P2)

Product:
Core
Component:
DOM: Core & HTML
Type:
defect

Tracking

()

Status:
RESOLVED FIXED
Milestone:
mozilla80
Project Flags:
Fission Milestone M6b
Tracking Flags:
Tracking Status
firefox80 --- fixed

People

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

References

Details

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

Attachments

(1 file)

Bug 1640427 - Don't perform flaky process launch check during test,
47 bytes, text/x-phabricator-request
Details | Review

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

[task 2020-05-23T21:04:55.954Z] 21:04:55 INFO - TEST-START | dom/base/test/browser_force_process_selector.js
[task 2020-05-23T21:04:55.972Z] 21:04:55 INFO - GECKO(12199) | Chrome file doesn't exist: /builds/worker/workspace/build/tests/mochitest/browser/dom/base/test/head.js
[task 2020-05-23T21:04:55.972Z] 21:04:55 INFO - GECKO(12199) | [Child 13224: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fad012d4800 == 0 [pid = 13224] [id = {da12eaee-c0ee-49bc-832c-9feed8085b8a}] [url = http://example.com/]
[task 2020-05-23T21:04:56.028Z] 21:04:56 INFO - GECKO(12199) | [Child 13258: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fe56b9d4800 == 1 [pid = 13258] [id = {50b73a2f-73a3-4c3a-a9a8-4d94f7647901}]
[task 2020-05-23T21:04:56.048Z] 21:04:56 INFO - GECKO(12199) | [Child 13258: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7fe581693350) [pid = 13258] [serial = 1] [outer = (nil)]
[task 2020-05-23T21:04:56.050Z] 21:04:56 INFO - GECKO(12199) | [Child 13258: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7fe565924400) [pid = 13258] [serial = 2] [outer = 0x7fe581693350]
[task 2020-05-23T21:04:56.065Z] 21:04:56 INFO - GECKO(12199) | [Child 13224: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x7fad16f93350) [pid = 13224] [serial = 1] [outer = (nil)] [url = http://example.com/]
[task 2020-05-23T21:04:56.069Z] 21:04:56 INFO - GECKO(12199) | [Child 13224: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7facfb224c00) [pid = 13224] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2020-05-23T21:04:56.070Z] 21:04:56 INFO - GECKO(12199) | [Child 13224: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x7facfb22f400) [pid = 13224] [serial = 3] [outer = (nil)] [url = http://example.com/]
[task 2020-05-23T21:04:56.070Z] 21:04:56 INFO - GECKO(12199) | [Child 13224, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/netwerk/base/nsNetUtil.cpp, line 2295
[task 2020-05-23T21:04:56.072Z] 21:04:56 INFO - GECKO(12199) | [Child 13224, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/caps/ContentPrincipal.cpp, line 398
[task 2020-05-23T21:04:56.072Z] 21:04:56 INFO - GECKO(12199) | [Child 13224, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/caps/ContentPrincipal.cpp, line 423
[task 2020-05-23T21:04:56.107Z] 21:04:56 INFO - GECKO(12199) | [Child 13224, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-05-23T21:04:56.204Z] 21:04:56 INFO - GECKO(12199) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-05-23T21:04:56.204Z] 21:04:56 INFO - GECKO(12199) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-05-23T21:04:56.220Z] 21:04:56 INFO - GECKO(12199) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-05-23T21:04:56.220Z] 21:04:56 INFO - GECKO(12199) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-05-23T21:04:56.220Z] 21:04:56 INFO - GECKO(12199) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-05-23T21:04:56.220Z] 21:04:56 INFO - GECKO(12199) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-05-23T21:04:56.220Z] 21:04:56 INFO - GECKO(12199) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-05-23T21:04:56.220Z] 21:04:56 INFO - GECKO(12199) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-05-23T21:04:56.220Z] 21:04:56 INFO - GECKO(12199) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-05-23T21:04:56.277Z] 21:04:56 INFO - GECKO(12199) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost[task 2020-05-23T21:04:56.277Z] 21:04:56 INFO - GECKO(12199) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-05-23T21:04:56.294Z] 21:04:56 INFO - GECKO(12199) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-05-23T21:04:56.294Z] 21:04:56 INFO - GECKO(12199) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-05-23T21:04:56.294Z] 21:04:56 INFO - GECKO(12199) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-05-23T21:04:56.295Z] 21:04:56 INFO - GECKO(12199) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-05-23T21:04:56.295Z] 21:04:56 INFO - GECKO(12199) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-05-23T21:04:56.295Z] 21:04:56 INFO - GECKO(12199) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-05-23T21:04:56.318Z] 21:04:56 INFO - GECKO(12199) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-05-23T21:04:56.319Z] 21:04:56 INFO - GECKO(12199) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-05-23T21:04:56.319Z] 21:04:56 INFO - GECKO(12199) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-05-23T21:04:56.319Z] 21:04:56 INFO - GECKO(12199) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-05-23T21:04:56.516Z] 21:04:56 INFO - GECKO(12199) | [Child 12749, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-05-23T21:04:56.557Z] 21:04:56 INFO - GECKO(12199) | [Child 13034: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fdcd82c3400 == 0 [pid = 13034] [id = {76296e28-7d2a-4686-8b44-c1efbdce9f1a}] [url = about:blank]
[task 2020-05-23T21:04:56.572Z] 21:04:56 INFO - GECKO(12199) | [Child 12784, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-05-23T21:04:56.593Z] 21:04:56 INFO - GECKO(12199) | [Child 12849, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-05-23T21:04:56.595Z] 21:04:56 INFO - GECKO(12199) | [Child 12804, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-05-23T21:04:56.615Z] 21:04:56 INFO - GECKO(12199) | [Child 13258: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7fe56592bc00) [pid = 13258] [serial = 3] [outer = 0x7fe581693350]
[task 2020-05-23T21:04:56.693Z] 21:04:56 INFO - GECKO(12199) | [Child 12636: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (0x7fb76ab80400) [pid = 12636] [serial = 4] [outer = (nil)] [url = about:blank]
[task 2020-05-23T21:04:56.693Z] 21:04:56 INFO - GECKO(12199) | [Child 12636: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fb766704000 == 1 [pid = 12636] [id = {6822a6f3-cdf6-4378-9da6-c105c74b8761}] [url = about:blank]
[task 2020-05-23T21:04:56.734Z] 21:04:56 INFO - GECKO(12199) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp9Lj8P7.mozrunner/runtests_leaks_tab_pid13333.log
[task 2020-05-23T21:04:56.734Z] 21:04:56 INFO - GECKO(12199) | [13333, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp, line 224
[task 2020-05-23T21:04:56.775Z] 21:04:56 INFO - GECKO(12199) | [Child 12875: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7ff733993350) [pid = 12875] [serial = 1] [outer = (nil)] [url = about:blank]
[task 2020-05-23T21:04:56.776Z] 21:04:56 INFO - GECKO(12199) | [Child 12875: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x7ff718587400) [pid = 12875] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2020-05-23T21:04:56.784Z] 21:04:56 INFO - GECKO(12199) | [Child 13034: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7fdcedf93350) [pid = 13034] [serial = 1] [outer = (nil)] [url = about:blank]
[task 2020-05-23T21:04:56.784Z] 21:04:56 INFO - GECKO(12199) | [Child 13034: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x7fdcd2277c00) [pid = 13034] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2020-05-23T21:04:56.840Z] 21:04:56 INFO - GECKO(12199) | [Child 13034, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-05-23T21:04:56.847Z] 21:04:56 INFO - GECKO(12199) | [Child 12875, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-05-23T21:04:56.888Z] 21:04:56 INFO - GECKO(12199) | [Child 12636: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fb76ab7f400 == 0 [pid = 12636] [id = {c8657fc1-df18-4ab3-a35f-55b9e92a3548}] [url = about:plugins]
[task 2020-05-23T21:04:56.896Z] 21:04:56 INFO - GECKO(12199) | [Child 12636: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (0x7fb786093350) [pid = 12636] [serial = 3] [outer = (nil)] [url = about:plugins]
[task 2020-05-23T21:04:56.896Z] 21:04:56 INFO - GECKO(12199) | [Child 12636: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x7fb76abe0090) [pid = 12636] [serial = 7] [outer = (nil)] [url = about:blank]
[task 2020-05-23T21:04:56.896Z] 21:04:56 INFO - GECKO(12199) | [Child 12636: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7fb766705000) [pid = 12636] [serial = 8] [outer = (nil)] [url = about:blank]
[task 2020-05-23T21:04:56.896Z] 21:04:56 INFO - GECKO(12199) | [Child 12636: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x7fb76ab87c00) [pid = 12636] [serial = 5] [outer = (nil)] [url = about:plugins]
[task 2020-05-23T21:04:56.932Z] 21:04:56 INFO - GECKO(12199) | [Child 12636, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-05-23T21:04:56.941Z] 21:04:56 INFO - GECKO(12199) | [Child 13268: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f607abd9800 == 1 [pid = 13268] [id = {e5a68c09-8c4f-46db-b1c5-25aa11ef7ab3}]
[task 2020-05-23T21:04:56.981Z] 21:04:56 INFO - GECKO(12199) | [Child 13268: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7f6090993350) [pid = 13268] [serial = 1] [outer = (nil)]
[task 2020-05-23T21:04:56.989Z] 21:04:56 INFO - GECKO(12199) | [Child 13268: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7f6074b24400) [pid = 13268] [serial = 2] [outer = 0x7f6090993350]
[task 2020-05-23T21:04:57.191Z] 21:04:57 INFO - GECKO(12199) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp9Lj8P7.mozrunner/runtests_leaks_tab_pid13348.log
[task 2020-05-23T21:04:57.192Z] 21:04:57 INFO - GECKO(12199) | [13348, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp, line 224
[task 2020-05-23T21:04:57.248Z] 21:04:57 INFO - GECKO(12199) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp9Lj8P7.mozrunner/runtests_leaks_tab_pid13351.log
[task 2020-05-23T21:04:57.248Z] 21:04:57 INFO - GECKO(12199) | [13351, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp, line 224
[task 2020-05-23T21:04:57.329Z] 21:04:57 INFO - GECKO(12199) | [Child 13268: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7f6074b2bc00) [pid = 13268] [serial = 3] [outer = 0x7f6090993350]
[task 2020-05-23T21:04:57.486Z] 21:04:57 INFO - GECKO(12199) | [Child 13333, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/checkouts/gecko/dom/ipc/ContentChild.cpp, line 1639
[task 2020-05-23T21:04:57.602Z] 21:04:57 INFO - GECKO(12199) | [Child 13306: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f4444fd4800 == 1 [pid = 13306] [id = {32324e50-ece2-4f33-9169-1631146f5152}]
[task 2020-05-23T21:04:57.650Z] 21:04:57 INFO - GECKO(12199) | [Child 13306: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7f445ac93350) [pid = 13306] [serial = 1] [outer = (nil)][task 2020-05-23T21:04:57.654Z] 21:04:57 INFO - GECKO(12199) | [Child 13306: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7f443ef24400) [pid = 13306] [serial = 2] [outer = 0x7f445ac93350]
[task 2020-05-23T21:04:57.904Z] 21:04:57 INFO - GECKO(12199) | [Child 13351, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/checkouts/gecko/dom/ipc/ContentChild.cpp, line 1639
[task 2020-05-23T21:04:57.960Z] 21:04:57 INFO - GECKO(12199) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp9Lj8P7.mozrunner/runtests_leaks_tab_pid13390.log
[task 2020-05-23T21:04:57.968Z] 21:04:57 INFO - GECKO(12199) | [13390, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp, line 224
[task 2020-05-23T21:04:58.105Z] 21:04:58 INFO - GECKO(12199) | [Child 13351, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-05-23T21:04:58.106Z] 21:04:58 INFO - GECKO(12199) | [Child 13348, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/checkouts/gecko/dom/ipc/ContentChild.cpp, line 1639
[task 2020-05-23T21:04:58.147Z] 21:04:58 INFO - GECKO(12199) | [Child 13306: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7f443ef2bc00) [pid = 13306] [serial = 3] [outer = 0x7f445ac93350]
[task 2020-05-23T21:04:58.263Z] 21:04:58 INFO - GECKO(12199) | [Child 13333: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7ff0231d4800 == 1 [pid = 13333] [id = {73f9cf51-d2c1-4809-a89f-d3fec0a4fd33}]
[task 2020-05-23T21:04:58.305Z] 21:04:58 INFO - GECKO(12199) | [Child 13333: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7ff038f93350) [pid = 13333] [serial = 1] [outer = (nil)]
[task 2020-05-23T21:04:58.305Z] 21:04:58 INFO - GECKO(12199) | [Child 13333: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7ff01d124800) [pid = 13333] [serial = 2] [outer = 0x7ff038f93350]
[task 2020-05-23T21:04:58.594Z] 21:04:58 INFO - GECKO(12199) | [Child 13333: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7ff01d12c000) [pid = 13333] [serial = 3] [outer = 0x7ff038f93350]
[task 2020-05-23T21:04:58.602Z] 21:04:58 INFO - GECKO(12199) | [Child 13390, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/checkouts/gecko/dom/ipc/ContentChild.cpp, line 1639
[task 2020-05-23T21:04:58.610Z] 21:04:58 INFO - GECKO(12199) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp9Lj8P7.mozrunner/runtests_leaks_tab_pid13427.log
[task 2020-05-23T21:04:58.610Z] 21:04:58 INFO - GECKO(12199) | [13427, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp, line 224
[task 2020-05-23T21:04:58.751Z] 21:04:58 INFO - GECKO(12199) | [Child 13348: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f81876d4800 == 1 [pid = 13348] [id = {60c369cb-82b2-4c6d-98f7-28f6dff13bca}]
[task 2020-05-23T21:04:58.790Z] 21:04:58 INFO - GECKO(12199) | [Child 13348: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7f819d393350) [pid = 13348] [serial = 1] [outer = (nil)]
[task 2020-05-23T21:04:58.799Z] 21:04:58 INFO - GECKO(12199) | [Child 13348: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7f8181624800) [pid = 13348] [serial = 2] [outer = 0x7f819d393350]
[task 2020-05-23T21:04:59.056Z] 21:04:59 INFO - GECKO(12199) | [Child 13348: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7f818162c000) [pid = 13348] [serial = 3] [outer = 0x7f819d393350]
[task 2020-05-23T21:04:59.140Z] 21:04:59 INFO - GECKO(12199) | [Child 13427, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/checkouts/gecko/dom/ipc/ContentChild.cpp, line 1639
[task 2020-05-23T21:04:59.200Z] 21:04:59 INFO - GECKO(12199) | [Child 13390: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f2d2a6d4400 == 1 [pid = 13390] [id = {0e915eca-f32c-4c1b-9d04-8bfab6798712}]
[task 2020-05-23T21:04:59.241Z] 21:04:59 INFO - GECKO(12199) | [Child 13390: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7f2d40493350) [pid = 13390] [serial = 1] [outer = (nil)]
[task 2020-05-23T21:04:59.241Z] 21:04:59 INFO - GECKO(12199) | [Child 13390: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7f2d24f7a400) [pid = 13390] [serial = 2] [outer = 0x7f2d40493350]
[task 2020-05-23T21:04:59.317Z] 21:04:59 INFO - GECKO(12199) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp9Lj8P7.mozrunner/runtests_leaks_tab_pid13463.log
[task 2020-05-23T21:04:59.317Z] 21:04:59 INFO - GECKO(12199) | [13463, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp, line 224
[task 2020-05-23T21:04:59.526Z] 21:04:59 INFO - GECKO(12199) | [Child 13390: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7f2d24f81c00) [pid = 13390] [serial = 3] [outer = 0x7f2d40493350]
[task 2020-05-23T21:04:59.562Z] 21:04:59 INFO - GECKO(12199) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties[task 2020-05-23T21:04:59.562Z] 21:04:59 INFO - GECKO(12199) | [Child 13463, Main Thread] WARNING: Could not get the program name for a cubeb stream.: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp, line 375
[task 2020-05-23T21:04:59.603Z] 21:04:59 INFO - GECKO(12199) | [Child 12411: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (0x7f0f014b7400) [pid = 12411] [serial = 7] [outer = (nil)] [url = about:blank]
[task 2020-05-23T21:04:59.603Z] 21:04:59 INFO - GECKO(12199) | [Child 12411: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (0x7f0f06ab9800) [pid = 12411] [serial = 10] [outer = (nil)] [url = about:blank]
[task 2020-05-23T21:04:59.603Z] 21:04:59 INFO - GECKO(12199) | [Child 12411: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (0x7f0f014b4c00) [pid = 12411] [serial = 4] [outer = (nil)] [url = about:blank]
[task 2020-05-23T21:04:59.603Z] 21:04:59 INFO - GECKO(12199) | [Child 12411: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (0x7f0f014ba000) [pid = 12411] [serial = 5] [outer = (nil)] [url = about:blank]
[task 2020-05-23T21:04:59.603Z] 21:04:59 INFO - GECKO(12199) | [Child 12411: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x7f0f06ab4000) [pid = 12411] [serial = 9] [outer = (nil)] [url = about:blank]
[task 2020-05-23T21:04:59.699Z] 21:04:59 INFO - GECKO(12199) | [Child 13427: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f119b1d4400 == 1 [pid = 13427] [id = {c0eb0306-2e05-40f7-a3e1-699c852bd809}]
[task 2020-05-23T21:04:59.755Z] 21:04:59 INFO - GECKO(12199) | [Child 13427: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7f11b0e93350) [pid = 13427] [serial = 1] [outer = (nil)]
[task 2020-05-23T21:04:59.757Z] 21:04:59 INFO - GECKO(12199) | [Child 13427: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7f1195a7a400) [pid = 13427] [serial = 2] [outer = 0x7f11b0e93350]
[task 2020-05-23T21:04:59.879Z] 21:04:59 INFO - GECKO(12199) | [Child 13463, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/checkouts/gecko/dom/ipc/ContentChild.cpp, line 1639
[task 2020-05-23T21:04:59.960Z] 21:04:59 INFO - GECKO(12199) | [Child 13427: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7f1195a81c00) [pid = 13427] [serial = 3] [outer = 0x7f11b0e93350]
[task 2020-05-23T21:04:59.976Z] 21:04:59 INFO - GECKO(12199) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp9Lj8P7.mozrunner/runtests_leaks_tab_pid13487.log
[task 2020-05-23T21:04:59.976Z] 21:04:59 INFO - GECKO(12199) | [13487, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp, line 224
[task 2020-05-23T21:05:00.119Z] 21:05:00 INFO - GECKO(12199) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp9Lj8P7.mozrunner/runtests_leaks_tab_pid13499.log
[task 2020-05-23T21:05:00.120Z] 21:05:00 INFO - GECKO(12199) | [13499, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp, line 224
[task 2020-05-23T21:05:00.120Z] 21:05:00 INFO - GECKO(12199) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp9Lj8P7.mozrunner/runtests_leaks_tab_pid13500.log
[task 2020-05-23T21:05:00.121Z] 21:05:00 INFO - GECKO(12199) | [13500, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp, line 224
[task 2020-05-23T21:05:00.498Z] 21:05:00 INFO - GECKO(12199) | [Child 13463: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f0d96995400 == 1 [pid = 13463] [id = {d5a8d995-0a79-423b-8ac8-983a3e903b4f}]
[task 2020-05-23T21:05:00.534Z] 21:05:00 INFO - GECKO(12199) | [Child 13463: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7f0dac793350) [pid = 13463] [serial = 1] [outer = (nil)]
[task 2020-05-23T21:05:00.549Z] 21:05:00 INFO - GECKO(12199) | [Child 13463: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7f0d90b7d400) [pid = 13463] [serial = 2] [outer = 0x7f0dac793350]
[task 2020-05-23T21:05:00.933Z] 21:05:00 INFO - GECKO(12199) | [Child 13463: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7f0d90b84c00) [pid = 13463] [serial = 3] [outer = 0x7f0dac793350]
[task 2020-05-23T21:05:00.956Z] 21:05:00 INFO - GECKO(12199) | [Child 13487, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/checkouts/gecko/dom/ipc/ContentChild.cpp, line 1639
[task 2020-05-23T21:05:00.957Z] 21:05:00 INFO - GECKO(12199) | [Child 13500, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/checkouts/gecko/dom/ipc/ContentChild.cpp, line 1639
[task 2020-05-23T21:05:01.010Z] 21:05:01 INFO - GECKO(12199) | [Child 13499, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/checkouts/gecko/dom/ipc/ContentChild.cpp, line 1639
[task 2020-05-23T21:05:01.133Z] 21:05:01 INFO - GECKO(12199) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost[task 2020-05-23T21:05:01.149Z] 21:05:01 INFO - GECKO(12199) | [Child 13487: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f84e73d4800 == 1 [pid = 13487] [id = {9095c91a-355f-42c3-af24-51fe2f333483}]
[task 2020-05-23T21:05:01.189Z] 21:05:01 INFO - GECKO(12199) | [Child 13487: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7f84fd093350) [pid = 13487] [serial = 1] [outer = (nil)]
[task 2020-05-23T21:05:01.197Z] 21:05:01 INFO - GECKO(12199) | [Child 13487: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7f84e1324800) [pid = 13487] [serial = 2] [outer = 0x7f84fd093350]
[task 2020-05-23T21:05:01.198Z] 21:05:01 INFO - GECKO(12199) | [Child 13500, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-05-23T21:05:01.367Z] 21:05:01 INFO - GECKO(12199) | [Child 13487: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7f84e132c000) [pid = 13487] [serial = 3] [outer = 0x7f84fd093350]
[task 2020-05-23T21:05:01.448Z] 21:05:01 INFO - GECKO(12199) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp9Lj8P7.mozrunner/runtests_leaks_tab_pid13565.log
[task 2020-05-23T21:05:01.448Z] 21:05:01 INFO - GECKO(12199) | [13565, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp, line 224
[task 2020-05-23T21:05:01.647Z] 21:05:01 INFO - GECKO(12199) | [Child 13499: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f22529d4800 == 1 [pid = 13499] [id = {fa132c57-f79c-49e3-afc5-d86bf2c5edc2}]
[task 2020-05-23T21:05:01.663Z] 21:05:01 INFO - GECKO(12199) | [Child 13499: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7f2268693350) [pid = 13499] [serial = 1] [outer = (nil)]
[task 2020-05-23T21:05:01.664Z] 21:05:01 INFO - GECKO(12199) | [Child 13499: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7f224c924800) [pid = 13499] [serial = 2] [outer = 0x7f2268693350]
[task 2020-05-23T21:05:01.820Z] 21:05:01 INFO - GECKO(12199) | [Child 13499: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7f224c92c000) [pid = 13499] [serial = 3] [outer = 0x7f2268693350]
[task 2020-05-23T21:05:02.008Z] 21:05:02 INFO - GECKO(12199) | [Child 13565, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/checkouts/gecko/dom/ipc/ContentChild.cpp, line 1639
[task 2020-05-23T21:05:02.024Z] 21:05:02 INFO - GECKO(12199) | [Child 12411: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f0f014b7400 == 2 [pid = 12411] [id = {bc15818f-7f24-47d1-9eaa-cd32c0af7987}]
[task 2020-05-23T21:05:02.025Z] 21:05:02 INFO - GECKO(12199) | [Child 12411: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7f0f06b34090) [pid = 12411] [serial = 13] [outer = (nil)]
[task 2020-05-23T21:05:02.025Z] 21:05:02 INFO - GECKO(12199) | [Child 12411: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (0x7f0f014ba000) [pid = 12411] [serial = 14] [outer = 0x7f0f06b34090]
[task 2020-05-23T21:05:02.081Z] 21:05:02 INFO - GECKO(12199) | [Child 12411: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (0x7f0f06ab6400) [pid = 12411] [serial = 15] [outer = 0x7f0f06b34090]
[task 2020-05-23T21:05:02.225Z] 21:05:02 INFO - GECKO(12199) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp9Lj8P7.mozrunner/runtests_leaks_tab_pid13591.log
[task 2020-05-23T21:05:02.226Z] 21:05:02 INFO - GECKO(12199) | [13591, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp, line 224
[task 2020-05-23T21:05:02.276Z] 21:05:02 INFO - TEST-INFO | started process screentopng
[task 2020-05-23T21:05:02.908Z] 21:05:02 INFO - TEST-INFO | screentopng: exit 0
[task 2020-05-23T21:05:02.909Z] 21:05:02 INFO - Buffered messages logged at 21:04:55
[task 2020-05-23T21:05:02.909Z] 21:05:02 INFO - Entering test bound test
[task 2020-05-23T21:05:02.910Z] 21:05:02 INFO - Buffered messages logged at 21:04:56
[task 2020-05-23T21:05:02.910Z] 21:05:02 INFO - TEST-PASS | dom/base/test/browser_force_process_selector.js | new tab is in its own process: 9 -
[task 2020-05-23T21:05:02.910Z] 21:05:02 INFO - Buffered messages logged at 21:04:57
[task 2020-05-23T21:05:02.911Z] 21:05:02 INFO - TEST-PASS | dom/base/test/browser_force_process_selector.js | new tab is in its own process: 8 -
[task 2020-05-23T21:05:02.911Z] 21:05:02 INFO - Buffered messages logged at 21:04:58[task 2020-05-23T21:05:02.912Z] 21:05:02 INFO - TEST-PASS | dom/base/test/browser_force_process_selector.js | new tab is in its own process: 7 -
[task 2020-05-23T21:05:02.912Z] 21:05:02 INFO - TEST-PASS | dom/base/test/browser_force_process_selector.js | new tab is in its own process: 6 -
[task 2020-05-23T21:05:02.913Z] 21:05:02 INFO - Buffered messages logged at 21:04:59
[task 2020-05-23T21:05:02.913Z] 21:05:02 INFO - TEST-PASS | dom/base/test/browser_force_process_selector.js | new tab is in its own process: 5 -
[task 2020-05-23T21:05:02.914Z] 21:05:02 INFO - TEST-PASS | dom/base/test/browser_force_process_selector.js | new tab is in its own process: 4 -
[task 2020-05-23T21:05:02.914Z] 21:05:02 INFO - Buffered messages logged at 21:05:00
[task 2020-05-23T21:05:02.914Z] 21:05:02 INFO - TEST-PASS | dom/base/test/browser_force_process_selector.js | new tab is in its own process: 3 -
[task 2020-05-23T21:05:02.921Z] 21:05:02 INFO - Buffered messages logged at 21:05:01
[task 2020-05-23T21:05:02.921Z] 21:05:02 INFO - TEST-PASS | dom/base/test/browser_force_process_selector.js | new tab is in its own process: 2 -
[task 2020-05-23T21:05:02.921Z] 21:05:02 INFO - TEST-PASS | dom/base/test/browser_force_process_selector.js | new tab is in its own process: 1 -
[task 2020-05-23T21:05:02.921Z] 21:05:02 INFO - Buffered messages logged at 21:05:02
[task 2020-05-23T21:05:02.922Z] 21:05:02 INFO - TEST-PASS | dom/base/test/browser_force_process_selector.js | new tab is in its own process: 0 -
[task 2020-05-23T21:05:02.922Z] 21:05:02 INFO - Buffered messages finished
[task 2020-05-23T21:05:02.923Z] 21:05:02 INFO - TEST-UNEXPECTED-FAIL | dom/base/test/browser_force_process_selector.js | shouldn't have created a new process -

Hsin-Yi, could you please redirect this to someone who can take a look at it? It's now on the disable recommended list with:

Given that the failure rate in the last 7 days is pretty low maybe we can postpone disabling it for now but a fix would be welcomed. Thank you.

Flags: needinfo?(htsai)
Whiteboard: [stockwell disable-recommended] → [stockwell needswork:owner]

Hi Chris,
The failures for the past week looked all happen with Fission enabled. It's about new process selection. Can you help forward this to the Fission team?

Flags: needinfo?(htsai) → needinfo?(cpeterson)

(In reply to Hsin-Yi Tsai [:hsinyi] from comment #10)

The failures for the past week looked all happen with Fission enabled. It's about new process selection. Can you help forward this to the Fission team?

Thanks for the heads up. I will send this bug to Fission triage.

Fission Milestone: --- → ?
Flags: needinfo?(cpeterson)
Priority: -- → P2
Summary: Intermittent dom/base/test/browser_force_process_selector.js | shouldn't have created a new process - → [Fission] Intermittent dom/base/test/browser_force_process_selector.js | shouldn't have created a new process -
Flags: needinfo?(nika)
Assignee: nobody → nika
Status: NEW → ASSIGNED
Flags: needinfo?(nika)
Fission Milestone: ? → M6b
Pushed by nlayzell@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/3c5bdf16febd Don't perform flaky process launch check during test, r=kmag

https://hg.mozilla.org/mozilla-central/rev/3c5bdf16febd

Status: ASSIGNED → RESOLVED
Closed: 6 years ago
status-firefox80: --- → fixed
Resolution: --- → FIXED
Target Milestone: --- → mozilla80
See Also: → 1651357
Regressions: 1651357
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: