1576423 - Perma fission browser/base/content/test/siteIdentity/browser_no_mcb_for_loopback.js | Test timed out -

Status: RESOLVED FIXED

(Firefox :: Site Identity, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: dluca [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=263355297&repo=mozilla-central
Full log: https://queue.taskcluster.net/v1/task/duFgFqamTwGZqIaEdi3FQQ/runs/0/artifacts/public/logs/live_backing.log

---

INFO - TEST-START | browser/base/content/test/siteIdentity/browser_no_mcb_for_loopback.js
[task 2019-08-24T22:55:11.011Z] 22:55:11     INFO - GECKO(1719) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2019-08-24T22:55:11.074Z] 22:55:11     INFO - GECKO(1719) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpvnxwji.mozrunner/runtests_leaks_tab_pid4373.log
[task 2019-08-24T22:55:11.276Z] 22:55:11     INFO - GECKO(1719) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-08-24T22:55:11.332Z] 22:55:11     INFO - GECKO(1719) | --DOCSHELL 0x7f1a941b9000 == 0 [pid = 4346] [id = {a25a074b-02d8-46fa-aaad-2cac84e8ad5f}] [url = https://localhost:36729/]
[task 2019-08-24T22:55:11.333Z] 22:55:11     INFO - GECKO(1719) | --DOMWINDOW == 2 (0x7f1a951c6f20) [pid = 4346] [serial = 1] [outer = (nil)] [url = about:neterror?e=nssFailure2&u=https%3A//localhost%3A36729/&c=UTF-8&f=regular&d=The%20connection%20to%20the%20server%20was%20reset%20while%20the%20page%20was%20loading.]
[task 2019-08-24T22:55:11.341Z] 22:55:11     INFO - GECKO(1719) | --DOMWINDOW == 1 (0x7f1a94165c00) [pid = 4346] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2019-08-24T22:55:11.342Z] 22:55:11     INFO - GECKO(1719) | --DOMWINDOW == 0 (0x7f1a952c1000) [pid = 4346] [serial = 3] [outer = (nil)] [url = about:neterror?e=nssFailure2&u=https%3A//localhost%3A36729/&c=UTF-8&f=regular&d=The%20connection%20to%20the%20server%20was%20reset%20while%20the%20page%20was%20loading.]
[task 2019-08-24T22:55:11.383Z] 22:55:11     INFO - GECKO(1719) | [Child 4346, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-08-24T22:55:11.383Z] 22:55:11     INFO - GECKO(1719) | [Child 4346, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-08-24T22:55:11.383Z] 22:55:11     INFO - GECKO(1719) | nsStringStats
[task 2019-08-24T22:55:11.384Z] 22:55:11     INFO - GECKO(1719) |  => mAllocCount:           9502
[task 2019-08-24T22:55:11.384Z] 22:55:11     INFO - GECKO(1719) |  => mReallocCount:            0
[task 2019-08-24T22:55:11.384Z] 22:55:11     INFO - GECKO(1719) |  => mFreeCount:            9502
[task 2019-08-24T22:55:11.384Z] 22:55:11     INFO - GECKO(1719) |  => mShareCount:           7202
[task 2019-08-24T22:55:11.384Z] 22:55:11     INFO - GECKO(1719) |  => mAdoptCount:            542
[task 2019-08-24T22:55:11.384Z] 22:55:11     INFO - GECKO(1719) |  => mAdoptFreeCount:        548
[task 2019-08-24T22:55:11.384Z] 22:55:11     INFO - GECKO(1719) |  => Process ID: 4346, Thread ID: 139752577787712
[task 2019-08-24T22:55:11.465Z] 22:55:11     INFO - GECKO(1719) |  WARN 2019-08-24T22:55:11Z: audio_thread_priority::rt_linux: Could not make thread real-time.
[task 2019-08-24T22:55:11.465Z] 22:55:11     INFO - GECKO(1719) |  WARN 2019-08-24T22:55:11Z: audioipc_client: Could not promote audio threads to real-time during initialization.
[task 2019-08-24T22:55:11.473Z] 22:55:11     INFO - GECKO(1719) | ++DOCSHELL 0x7fb3484b8800 == 1 [pid = 4373] [id = {4a1d3aa3-b120-4bf7-b531-626eeffe2f41}]
[task 2019-08-24T22:55:11.515Z] 22:55:11     INFO - GECKO(1719) | ++DOMWINDOW == 1 (0x7fb34948af20) [pid = 4373] [serial = 1] [outer = (nil)]
[task 2019-08-24T22:55:11.518Z] 22:55:11     INFO - GECKO(1719) | ++DOMWINDOW == 2 (0x7fb348b90800) [pid = 4373] [serial = 2] [outer = 0x7fb34948af20]
[task 2019-08-24T22:55:11.715Z] 22:55:11     INFO - GECKO(1719) | ++DOMWINDOW == 3 (0x7fb3618d5400) [pid = 4373] [serial = 3] [outer = 0x7fb34948af20]
[task 2019-08-24T22:55:11.751Z] 22:55:11     INFO - GECKO(1719) | [Child 4373, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 139
[task 2019-08-24T22:55:11.752Z] 22:55:11     INFO - GECKO(1719) | [Child 4373, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 139
[task 2019-08-24T22:55:11.752Z] 22:55:11     INFO - GECKO(1719) | [Child 4373, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 139
[task 2019-08-24T22:55:11.771Z] 22:55:11     INFO - GECKO(1719) | ++DOCSHELL 0x7fb347fc7000 == 2 [pid = 4373] [id = {63451a5c-3261-480b-b343-66b3e4ce4018}]
[task 2019-08-24T22:55:11.772Z] 22:55:11     INFO - GECKO(1719) | ++DOMWINDOW == 4 (0x7fb34948ba60) [pid = 4373] [serial = 4] [outer = (nil)]
[task 2019-08-24T22:55:11.772Z] 22:55:11     INFO - GECKO(1719) | ++DOCSHELL 0x7fb347fc7800 == 3 [pid = 4373] [id = {9329cfea-4ed3-4d09-ad68-cc6d1a74796e}]
[task 2019-08-24T22:55:11.773Z] 22:55:11     INFO - GECKO(1719) | ++DOMWINDOW == 5 (0x7fb34948bc40) [pid = 4373] [serial = 5] [outer = (nil)]
[task 2019-08-24T22:55:11.773Z] 22:55:11     INFO - GECKO(1719) | ++DOCSHELL 0x7fb347fc8000 == 4 [pid = 4373] [id = {bd05b339-96ae-4bb9-8c30-ffbaee1005a8}]
[task 2019-08-24T22:55:11.773Z] 22:55:11     INFO - GECKO(1719) | ++DOMWINDOW == 6 (0x7fb34948be20) [pid = 4373] [serial = 6] [outer = (nil)]
[task 2019-08-24T22:55:11.788Z] 22:55:11     INFO - GECKO(1719) | [Child 4373, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 139
[task 2019-08-24T22:55:11.804Z] 22:55:11     INFO - GECKO(1719) | [Child 4373, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 139
[task 2019-08-24T22:55:11.865Z] 22:55:11     INFO - GECKO(1719) | [Parent 1719, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 139
[task 2019-08-24T22:55:11.867Z] 22:55:11     INFO - GECKO(1719) | [Parent 1719, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 139
[task 2019-08-24T22:55:11.868Z] 22:55:11     INFO - GECKO(1719) | [Parent 1719, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 139
[task 2019-08-24T22:55:11.868Z] 22:55:11     INFO - GECKO(1719) | [Parent 1719, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 139
[task 2019-08-24T22:55:11.869Z] 22:55:11     INFO - GECKO(1719) | [Parent 1719, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 139
[task 2019-08-24T22:55:11.869Z] 22:55:11     INFO - GECKO(1719) | [Parent 1719, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 139
[task 2019-08-24T22:55:11.897Z] 22:55:11     INFO - GECKO(1719) | [Parent 1719, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 139
[task 2019-08-24T22:55:11.898Z] 22:55:11     INFO - GECKO(1719) | [Parent 1719, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 139
[task 2019-08-24T22:55:11.900Z] 22:55:11     INFO - GECKO(1719) | [Parent 1719, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 139
[task 2019-08-24T22:55:11.903Z] 22:55:11     INFO - GECKO(1719) | [Parent 1719, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 139
[task 2019-08-24T22:55:11.904Z] 22:55:11     INFO - GECKO(1719) | [Parent 1719, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 139
[task 2019-08-24T22:55:11.904Z] 22:55:11     INFO - GECKO(1719) | [Parent 1719, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 139
[task 2019-08-24T22:55:11.925Z] 22:55:11     INFO - GECKO(1719) | [Child 4373, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 139
[task 2019-08-24T22:55:11.926Z] 22:55:11     INFO - GECKO(1719) | [Child 4373, Main Thread] WARNING: CompleteRedirectSetup failed, HttpChannelChild already open?: file /builds/worker/workspace/build/src/netwerk/protocol/http/HttpChannelChild.cpp, line 2125
[task 2019-08-24T22:55:11.930Z] 22:55:11     INFO - GECKO(1719) | [Child 4373, Main Thread] WARNING: CompleteRedirectSetup failed, HttpChannelChild already open?: file /builds/worker/workspace/build/src/netwerk/protocol/http/HttpChannelChild.cpp, line 2125
[task 2019-08-24T22:55:11.932Z] 22:55:11     INFO - GECKO(1719) | [Child 4373, Main Thread] WARNING: CompleteRedirectSetup failed, HttpChannelChild already open?: file /builds/worker/workspace/build/src/netwerk/protocol/http/HttpChannelChild.cpp, line 2125
[task 2019-08-24T22:55:12.008Z] 22:55:12     INFO - GECKO(1719) | [Child 4373, Main Thread] WARNING: NS_ENSURE_TRUE(currentInner) failed: file /builds/worker/workspace/build/src/dom/base/WindowDestroyedEvent.cpp, line 106
[task 2019-08-24T22:55:12.009Z] 22:55:12     INFO - GECKO(1719) | [Child 4373, Main Thread] WARNING: NS_ENSURE_TRUE(currentInner) failed: file /builds/worker/workspace/build/src/dom/base/WindowDestroyedEvent.cpp, line 106
[task 2019-08-24T22:55:12.010Z] 22:55:12     INFO - GECKO(1719) | [Child 4373, Main Thread] WARNING: NS_ENSURE_TRUE(currentInner) failed: file /builds/worker/workspace/build/src/dom/base/WindowDestroyedEvent.cpp, line 106
[task 2019-08-24T22:55:12.010Z] 22:55:12     INFO - GECKO(1719) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpvnxwji.mozrunner/runtests_leaks_tab_pid4402.log
[task 2019-08-24T22:55:12.148Z] 22:55:12     INFO - GECKO(1719) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpvnxwji.mozrunner/runtests_leaks_tab_pid4412.log
[task 2019-08-24T22:55:12.196Z] 22:55:12     INFO - GECKO(1719) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-08-24T22:55:12.353Z] 22:55:12     INFO - GECKO(1719) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-08-24T22:55:12.414Z] 22:55:12     INFO - GECKO(1719) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpvnxwji.mozrunner/runtests_leaks_tab_pid4427.log
[task 2019-08-24T22:55:12.524Z] 22:55:12     INFO - GECKO(1719) |  WARN 2019-08-24T22:55:12Z: audio_thread_priority::rt_linux: Could not make thread real-time.
[task 2019-08-24T22:55:12.524Z] 22:55:12     INFO - GECKO(1719) |  WARN 2019-08-24T22:55:12Z: audioipc_client: Could not promote audio threads to real-time during initialization.
[task 2019-08-24T22:55:12.525Z] 22:55:12     INFO - GECKO(1719) | ++DOCSHELL 0x7fae0e5b6000 == 1 [pid = 4412] [id = {4226cd22-869e-4380-bf36-774448f12d49}]
[task 2019-08-24T22:55:12.564Z] 22:55:12     INFO - GECKO(1719) |  WARN 2019-08-24T22:55:12Z: audio_thread_priority::rt_linux: Could not make thread real-time.
[task 2019-08-24T22:55:12.564Z] 22:55:12     INFO - GECKO(1719) |  WARN 2019-08-24T22:55:12Z: audioipc_client: Could not promote audio threads to real-time during initialization.
[task 2019-08-24T22:55:12.584Z] 22:55:12     INFO - GECKO(1719) | ++DOCSHELL 0x7f1d40fb6000 == 1 [pid = 4402] [id = {c469c2cb-2060-45f7-a0d5-57bf4a317df4}]
[task 2019-08-24T22:55:12.668Z] 22:55:12     INFO - GECKO(1719) | ++DOMWINDOW == 1 (0x7fae0f3ddf20) [pid = 4412] [serial = 1] [outer = (nil)]
[task 2019-08-24T22:55:12.669Z] 22:55:12     INFO - GECKO(1719) | ++DOMWINDOW == 2 (0x7fae0e57ec00) [pid = 4412] [serial = 2] [outer = 0x7fae0f3ddf20]
[task 2019-08-24T22:55:12.691Z] 22:55:12     INFO - GECKO(1719) | [Child 4412, Main Thread] WARNING: '!topWindow', file /builds/worker/workspace/build/src/toolkit/components/antitracking/AntiTrackingCommon.cpp, line 1306
[task 2019-08-24T22:55:12.731Z] 22:55:12     INFO - GECKO(1719) | [Parent 1719, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 139
[task 2019-08-24T22:55:12.733Z] 22:55:12     INFO - GECKO(1719) | [Parent 1719, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 139
[task 2019-08-24T22:55:12.734Z] 22:55:12     INFO - GECKO(1719) | [Parent 1719, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 139
[task 2019-08-24T22:55:12.770Z] 22:55:12     INFO - GECKO(1719) | ++DOMWINDOW == 1 (0x7f1d41ae5f20) [pid = 4402] [serial = 1] [outer = (nil)]
[task 2019-08-24T22:55:12.771Z] 22:55:12     INFO - GECKO(1719) | ++DOMWINDOW == 2 (0x7f1d4197cc00) [pid = 4402] [serial = 2] [outer = 0x7f1d41ae5f20]
[task 2019-08-24T22:55:12.787Z] 22:55:12     INFO - GECKO(1719) | [Child 4402, Main Thread] WARNING: '!topWindow', file /builds/worker/workspace/build/src/toolkit/components/antitracking/AntiTrackingCommon.cpp, line 1306
[task 2019-08-24T22:55:12.834Z] 22:55:12     INFO - GECKO(1719) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-08-24T22:55:12.849Z] 22:55:12     INFO - GECKO(1719) | [Parent 1719, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 139
[task 2019-08-24T22:55:12.850Z] 22:55:12     INFO - GECKO(1719) | [Parent 1719, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 139
[task 2019-08-24T22:55:12.851Z] 22:55:12     INFO - GECKO(1719) | [Parent 1719, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 139
[task 2019-08-24T22:55:12.851Z] 22:55:12     INFO - GECKO(1719) | [Parent 1719, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 139
[task 2019-08-24T22:55:12.928Z] 22:55:12     INFO - GECKO(1719) | [Child 4373, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 139
[task 2019-08-24T22:55:12.948Z] 22:55:12     INFO - GECKO(1719) | ++DOMWINDOW == 3 (0x7fae0ee85400) [pid = 4412] [serial = 3] [outer = 0x7fae0f3ddf20]
[task 2019-08-24T22:55:12.964Z] 22:55:12     INFO - GECKO(1719) | [Child 4412, Main Thread] WARNING: '!topWindow', file /builds/worker/workspace/build/src/toolkit/components/antitracking/AntiTrackingCommon.cpp, line 1306
[task 2019-08-24T22:55:13.052Z] 22:55:13     INFO - GECKO(1719) | [Parent 1719, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 139
[task 2019-08-24T22:55:13.052Z] 22:55:13     INFO - GECKO(1719) | [Parent 1719, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 139
[task 2019-08-24T22:55:13.052Z] 22:55:13     INFO - GECKO(1719) | [Parent 1719, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 139
[task 2019-08-24T22:55:13.099Z] 22:55:13     INFO - GECKO(1719) | ++DOMWINDOW == 3 (0x7f1d42066400) [pid = 4402] [serial = 3] [outer = 0x7f1d41ae5f20]
[task 2019-08-24T22:55:13.115Z] 22:55:13     INFO - GECKO(1719) | [Child 4402, Main Thread] WARNING: '!topWindow', file /builds/worker/workspace/build/src/toolkit/components/antitracking/AntiTrackingCommon.cpp, line 1306
[task 2019-08-24T22:55:13.135Z] 22:55:13     INFO - GECKO(1719) |  WARN 2019-08-24T22:55:13Z: audio_thread_priority::rt_linux: Could not make thread real-time.
[task 2019-08-24T22:55:13.136Z] 22:55:13     INFO - GECKO(1719) |  WARN 2019-08-24T22:55:13Z: audioipc_client: Could not promote audio threads to real-time during initialization.
[task 2019-08-24T22:55:13.151Z] 22:55:13     INFO - GECKO(1719) | ++DOCSHELL 0x7fca0feb8000 == 1 [pid = 4427] [id = {0cdf83c2-689f-432d-b454-3158a94fa762}]
[task 2019-08-24T22:55:13.219Z] 22:55:13     INFO - GECKO(1719) | [Child 4373, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 139
[task 2019-08-24T22:55:13.260Z] 22:55:13     INFO - GECKO(1719) | ++DOMWINDOW == 1 (0x7fca10ee5f20) [pid = 4427] [serial = 1] [outer = (nil)]
[task 2019-08-24T22:55:13.260Z] 22:55:13     INFO - GECKO(1719) | ++DOMWINDOW == 2 (0x7fca0fe68800) [pid = 4427] [serial = 2] [outer = 0x7fca10ee5f20]
[task 2019-08-24T22:55:13.276Z] 22:55:13     INFO - GECKO(1719) | [Child 4427, Main Thread] WARNING: '!topWindow', file /builds/worker/workspace/build/src/toolkit/components/antitracking/AntiTrackingCommon.cpp, line 1306
[task 2019-08-24T22:55:13.352Z] 22:55:13     INFO - GECKO(1719) | --DOMWINDOW == 2 (0x7f942db32400) [pid = 1874] [serial = 42] [outer = (nil)] [url = about:blank]
[task 2019-08-24T22:55:13.373Z] 22:55:13     INFO - GECKO(1719) | --DOCSHELL 0x7f942db45000 == 0 [pid = 1874] [id = {d0fc9f03-272d-48a1-9317-97bf425d3e20}] [url = about:blank]
[task 2019-08-24T22:55:13.388Z] 22:55:13     INFO - GECKO(1719) | JavaScript error: , line 0: TypeError: NetworkError when attempting to fetch resource.
[task 2019-08-24T22:55:13.396Z] 22:55:13     INFO - GECKO(1719) | JavaScript error: , line 0: TypeError: NetworkError when attempting to fetch resource.
[task 2019-08-24T22:55:13.396Z] 22:55:13     INFO - GECKO(1719) | JavaScript error: , line 0: TypeError: NetworkError when attempting to fetch resource.
[task 2019-08-24T22:55:13.458Z] 22:55:13     INFO - GECKO(1719) | ++DOMWINDOW == 3 (0x7fca10e11800) [pid = 4427] [serial = 3] [outer = 0x7fca10ee5f20]
[task 2019-08-24T22:55:13.474Z] 22:55:13     INFO - GECKO(1719) | [Child 4427, Main Thread] WARNING: '!topWindow', file /builds/worker/workspace/build/src/toolkit/components/antitracking/AntiTrackingCommon.cpp, line 1306
[task 2019-08-24T22:55:13.510Z] 22:55:13     INFO - GECKO(1719) | --DOMWINDOW == 5 (0x7f51a9303000) [pid = 1846] [serial = 30] [outer = (nil)] [url = about:blank]
[task 2019-08-24T22:55:13.511Z] 22:55:13     INFO - GECKO(1719) | --DOMWINDOW == 4 (0x7f51a930dc00) [pid = 1846] [serial = 35] [outer = (nil)] [url = about:blank]
[task 2019-08-24T22:55:13.511Z] 22:55:13     INFO - GECKO(1719) | --DOMWINDOW == 3 (0x7f51a92ba000) [pid = 1846] [serial = 34] [outer = (nil)] [url = about:blank]
[task 2019-08-24T22:55:13.511Z] 22:55:13     INFO - GECKO(1719) | --DOMWINDOW == 2 (0x7f51a92aec00) [pid = 1846] [serial = 32] [outer = (nil)] [url = about:blank]
[task 2019-08-24T22:55:17.449Z] 22:55:17     INFO - GECKO(1719) | --DOMWINDOW == 1 (0x7f9431211880) [pid = 1874] [serial = 43] [outer = (nil)] [url = about:blank]
[task 2019-08-24T22:55:19.732Z] 22:55:19     INFO - GECKO(1719) | --DOMWINDOW == 5 (0x7fb34948ba60) [pid = 4373] [serial = 4] [outer = (nil)] [url = ]
[task 2019-08-24T22:55:19.732Z] 22:55:19     INFO - GECKO(1719) | --DOMWINDOW == 4 (0x7fb34948bc40) [pid = 4373] [serial = 5] [outer = (nil)] [url = ]
[task 2019-08-24T22:55:19.733Z] 22:55:19     INFO - GECKO(1719) | --DOMWINDOW == 3 (0x7fb34948be20) [pid = 4373] [serial = 6] [outer = (nil)] [url = ]
[task 2019-08-24T22:55:19.789Z] 22:55:19     INFO - GECKO(1719) | --DOCSHELL 0x7fb347fc8000 == 3 [pid = 4373] [id = {bd05b339-96ae-4bb9-8c30-ffbaee1005a8}] [url = ]
[task 2019-08-24T22:55:19.789Z] 22:55:19     INFO - GECKO(1719) | --DOCSHELL 0x7fb347fc7800 == 2 [pid = 4373] [id = {9329cfea-4ed3-4d09-ad68-cc6d1a74796e}] [url = ]
[task 2019-08-24T22:55:19.789Z] 22:55:19     INFO - GECKO(1719) | --DOCSHELL 0x7fb347fc7000 == 1 [pid = 4373] [id = {63451a5c-3261-480b-b343-66b3e4ce4018}] [url = ]
[task 2019-08-24T22:55:21.495Z] 22:55:21     INFO - GECKO(1719) | --DOMWINDOW == 0 (0x7f942db32800) [pid = 1874] [serial = 44] [outer = (nil)] [url = about:blank]
[task 2019-08-24T22:55:23.863Z] 22:55:23     INFO - GECKO(1719) | --DOMWINDOW == 2 (0x7fb348b90800) [pid = 4373] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2019-08-24T22:55:27.087Z] 22:55:27     INFO - GECKO(1719) | --DOMWINDOW == 2 (0x7fae0e57ec00) [pid = 4412] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2019-08-24T22:55:27.230Z] 22:55:27     INFO - GECKO(1719) | --DOMWINDOW == 2 (0x7f1d4197cc00) [pid = 4402] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2019-08-24T22:55:27.596Z] 22:55:27     INFO - GECKO(1719) | --DOMWINDOW == 2 (0x7fca0fe68800) [pid = 4427] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2019-08-24T22:56:41.188Z] 22:56:41     INFO - TEST-INFO | started process screentopng
[task 2019-08-24T22:56:41.650Z] 22:56:41     INFO - TEST-INFO | screentopng: exit 0
[task 2019-08-24T22:56:41.650Z] 22:56:41     INFO - Buffered messages logged at 22:55:10
[task 2019-08-24T22:56:41.650Z] 22:56:41     INFO - Entering test bound allowLoopbackMixedContent
[task 2019-08-24T22:56:41.650Z] 22:56:41     INFO - Buffered messages logged at 22:55:12
[task 2019-08-24T22:56:41.650Z] 22:56:41     INFO - Console message: [JavaScript Warning: "Loading failed for the <script> with source “http://127.0.0.1:8/test.js”." {file: "https://example.com/browser/browser/base/content/test/siteIdentity/test_no_mcb_for_loopback.html" line: 42}]
[task 2019-08-24T22:56:41.651Z] 22:56:41     INFO - Console message: [JavaScript Warning: "Cross-Origin Request Blocked: The Same Origin Policy disallows reading the remote resource at http://127.0.0.1:8/test.ttf. (Reason: CORS request did not succeed)."]
[task 2019-08-24T22:56:41.651Z] 22:56:41     INFO - Console message: [JavaScript Warning: "Cross-Origin Request Blocked: The Same Origin Policy disallows reading the remote resource at http://[::1]:8/test.ttf. (Reason: CORS request did not succeed)."]
[task 2019-08-24T22:56:41.651Z] 22:56:41     INFO - Console message: [JavaScript Error: "downloadable font: download failed (font-family: "Font-IPv4" style:normal weight:400 stretch:100 src index:0): bad URI or cross-site access not allowed source: http://127.0.0.1:8/test.ttf" {file: "unknown" line: 10 column: 15 source: "@font-face {
[task 2019-08-24T22:56:41.652Z] 22:56:41     INFO -   font-family: "Font-IPv4";
[task 2019-08-24T22:56:41.652Z] 22:56:41     INFO -   src: url("http://127.0.0.1:8/test.ttf");
[task 2019-08-24T22:56:41.652Z] 22:56:41     INFO - }"}]
[task 2019-08-24T22:56:41.652Z] 22:56:41     INFO - Console message: [JavaScript Error: "downloadable font: download failed (font-family: "Font-IPv6" style:normal weight:400 stretch:100 src index:0): bad URI or cross-site access not allowed source: http://[::1]:8/test.ttf" {file: "unknown" line: 15 column: 15 source: "@font-face {
[task 2019-08-24T22:56:41.652Z] 22:56:41     INFO -   font-family: "Font-IPv6";
[task 2019-08-24T22:56:41.654Z] 22:56:41     INFO -   src: url("http://[::1]:8/test.ttf");
[task 2019-08-24T22:56:41.655Z] 22:56:41     INFO - }"}]
[task 2019-08-24T22:56:41.655Z] 22:56:41     INFO - Console message: [JavaScript Warning: "Loading failed for the <script> with source “http://[::1]:8/test.js”." {file: "https://example.com/browser/browser/base/content/test/siteIdentity/test_no_mcb_for_loopback.html" line: 43}]
[task 2019-08-24T22:56:41.655Z] 22:56:41     INFO - Console message: [JavaScript Warning: "Loading failed for the <script> with source “http://localhost:8/test.js”." {file: "https://example.com/browser/browser/base/content/test/siteIdentity/test_no_mcb_for_loopback.html" line: 44}]
[task 2019-08-24T22:56:41.656Z] 22:56:41     INFO - Buffered messages logged at 22:55:13
[task 2019-08-24T22:56:41.656Z] 22:56:41     INFO - Console message: [JavaScript Warning: "Cross-Origin Request Blocked: The Same Origin Policy disallows reading the remote resource at http://127.0.0.1:8/. (Reason: CORS request did not succeed)."]
[task 2019-08-24T22:56:41.656Z] 22:56:41     INFO - Console message: [JavaScript Error: "TypeError: NetworkError when attempting to fetch resource."]
[task 2019-08-24T22:56:41.657Z] 22:56:41     INFO - Console message: [JavaScript Warning: "Cross-Origin Request Blocked: The Same Origin Policy disallows reading the remote resource at http://localhost:8/. (Reason: CORS request did not succeed)."]
[task 2019-08-24T22:56:41.657Z] 22:56:41     INFO - Console message: [JavaScript Error: "TypeError: NetworkError when attempting to fetch resource."]
[task 2019-08-24T22:56:41.657Z] 22:56:41     INFO - Console message: [JavaScript Warning: "Cross-Origin Request Blocked: The Same Origin Policy disallows reading the remote resource at http://[::1]:8/. (Reason: CORS request did not succeed)."]
[task 2019-08-24T22:56:41.658Z] 22:56:41     INFO - Console message: [JavaScript Error: "TypeError: NetworkError when attempting to fetch resource."]
[task 2019-08-24T22:56:41.658Z] 22:56:41     INFO - Buffered messages finished
[task 2019-08-24T22:56:41.659Z] 22:56:41     INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/siteIdentity/browser_no_mcb_for_loopback.js | Test timed out - 
[task 2019-08-24T22:56:41.659Z] 22:56:41     INFO - GECKO(1719) | JavaScript error: resource://testing-common/PromiseTestUtils.jsm, line 112: uncaught exception: Object
[task 2019-08-24T22:56:41.659Z] 22:56:41     INFO - Console message: [JavaScript Error: "uncaught exception: Object" {file: "resource://testing-common/PromiseTestUtils.jsm" line: 112}]
[task 2019-08-24T22:56:41.659Z] 22:56:41     INFO - GECKO(1719) | MEMORY STAT | vsize 3176MB | residentFast 336MB | heapAllocated 101MB
[task 2019-08-24T22:56:41.659Z] 22:56:41     INFO - TEST-OK | browser/base/content/test/siteIdentity/browser_no_mcb_for_loopback.js | took 90417ms
[task 2019-08-24T22:56:41.660Z] 22:56:41     INFO - GECKO(1719) | ++DOCSHELL 0x7f942db41800 == 1 [pid = 1874] [id = {930a28bb-0fb0-47f4-90f0-966c70b8d92f}]
[task 2019-08-24T22:56:41.661Z] 22:56:41     INFO - GECKO(1719) | ++DOMWINDOW == 1 (0x7f94312116a0) [pid = 1874] [serial = 45] [outer = (nil)]
[task 2019-08-24T22:56:41.662Z] 22:56:41     INFO - GECKO(1719) | ++DOMWINDOW == 2 (0x7f942db2fc00) [pid = 1874] [serial = 46] [outer = 0x7f94312116a0]
[task 2019-08-24T22:56:41.663Z] 22:56:41     INFO - checking window state
[task 2019-08-24T22:56:41.663Z] 22:56:41     INFO - TEST-START | browser/base/content/test/siteIdentity/browser_no_mcb_for_onions.js
[task 2019-08-24T22:56:41.805Z] 22:56:41     INFO - GECKO(1719) | --DOCSHELL 0x7fb3484b8800 == 0 [pid = 4373] [id = {4a1d3aa3-b120-4bf7-b531-626eeffe2f41}] [url = https://example.com/browser/browser/base/content/test/siteIdentity/test_no_mcb_for_loopback.html]
[task 2019-08-24T22:56:41.934Z] 22:56:41     INFO - GECKO(1719) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpvnxwji.mozrunner/runtests_leaks_tab_pid4485.log
[task 2019-08-24T22:56:41.961Z] 22:56:41     INFO - GECKO(1719) | --DOCSHELL 0x7f1d40fb6000 == 0 [pid = 4402] [id = {c469c2cb-2060-45f7-a0d5-57bf4a317df4}] [url = http://127.0.0.1:8/test.html]
[task 2019-08-24T22:56:41.961Z] 22:56:41     INFO - GECKO(1719) | --DOMWINDOW == 1 (0x7f1d41ae5f20) [pid = 4402] [serial = 1] [outer = (nil)] [url = about:neterror?e=connectionFailure&u=http%3A//127.0.0.1%3A8/test.html&c=UTF-8&f=regular&d=Firefox%20can%E2%80%99t%20establish%20a%20connection%20to%20the%20server%20at%20127.0.0.1%3A8.]
[task 2019-08-24T22:56:41.962Z] 22:56:41     INFO - GECKO(1719) | --DOMWINDOW == 0 (0x7f1d42066400) [pid = 4402] [serial = 3] [outer = (nil)] [url = about:neterror?e=connectionFailure&u=http%3A//127.0.0.1%3A8/test.html&c=UTF-8&f=regular&d=Firefox%20can%E2%80%99t%20establish%20a%20connection%20to%20the%20server%20at%20127.0.0.1%3A8.]
[task 2019-08-24T22:56:41.969Z] 22:56:41     INFO - GECKO(1719) | --DOMWINDOW == 1 (0x7fb34948af20) [pid = 4373] [serial = 1] [outer = (nil)] [url = https://example.com/browser/browser/base/content/test/siteIdentity/test_no_mcb_for_loopback.html]
[task 2019-08-24T22:56:41.972Z] 22:56:41     INFO - GECKO(1719) | --DOMWINDOW == 0 (0x7fb3618d5400) [pid = 4373] [serial = 3] [outer = (nil)] [url = https://example.com/browser/browser/base/content/test/siteIdentity/test_no_mcb_for_loopback.html]
[task 2019-08-24T22:56:42.029Z] 22:56:42     INFO - GECKO(1719) | --DOCSHELL 0x7fae0e5b6000 == 0 [pid = 4412] [id = {4226cd22-869e-4380-bf36-774448f12d49}] [url = http://[::1]:8/test.html]
[task 2019-08-24T22:56:42.029Z] 22:56:42     INFO - GECKO(1719) | --DOCSHELL 0x7fca0feb8000 == 0 [pid = 4427] [id = {0cdf83c2-689f-432d-b454-3158a94fa762}] [url = http://localhost:8/test.html]
[task 2019-08-24T22:56:42.032Z] 22:56:42     INFO - GECKO(1719) | --DOMWINDOW == 1 (0x7fca10ee5f20) [pid = 4427] [serial = 1] [outer = (nil)] [url = about:neterror?e=connectionFailure&u=http%3A//localhost%3A8/test.html&c=UTF-8&f=regular&d=Firefox%20can%E2%80%99t%20establish%20a%20connection%20to%20the%20server%20at%20localhost%3A8.]
[task 2019-08-24T22:56:42.033Z] 22:56:42     INFO - GECKO(1719) | --DOMWINDOW == 0 (0x7fca10e11800) [pid = 4427] [serial = 3] [outer = (nil)] [url = about:neterror?e=connectionFailure&u=http%3A//localhost%3A8/test.html&c=UTF-8&f=regular&d=Firefox%20can%E2%80%99t%20establish%20a%20connection%20to%20the%20server%20at%20localhost%3A8.]
[task 2019-08-24T22:56:42.034Z] 22:56:42     INFO - GECKO(1719) | --DOMWINDOW == 1 (0x7fae0f3ddf20) [pid = 4412] [serial = 1] [outer = (nil)] [url = about:neterror?e=connectionFailure&u=http%3A//%5B%3A%3A1%5D%3A8/test.html&c=UTF-8&f=regular&d=Firefox%20can%E2%80%99t%20establish%20a%20connection%20to%20the%20server%20at%20%5B%3A%3A1%5D%3A8.]
[task 2019-08-24T22:56:42.035Z] 22:56:42     INFO - GECKO(1719) | [Child 4402, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/workspace/build/src/xpcom/base/nsCycleCollector.cpp, line 3361
[task 2019-08-24T22:56:42.035Z] 22:56:42     INFO - GECKO(1719) | --DOMWINDOW == 0 (0x7fae0ee85400) [pid = 4412] [serial = 3] [outer = (nil)] [url = about:neterror?e=connectionFailure&u=http%3A//%5B%3A%3A1%5D%3A8/test.html&c=UTF-8&f=regular&d=Firefox%20can%E2%80%99t%20establish%20a%20connection%20to%20the%20server%20at%20%5B%3A%3A1%5D%3A8.]
[task 2019-08-24T22:56:42.056Z] 22:56:42     INFO - GECKO(1719) | [Child 4373, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/workspace/build/src/xpcom/base/nsCycleCollector.cpp, line 3361
[task 2019-08-24T22:56:42.100Z] 22:56:42     INFO - GECKO(1719) | [Child 4427, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/workspace/build/src/xpcom/base/nsCycleCollector.cpp, line 3361
[task 2019-08-24T22:56:42.136Z] 22:56:42     INFO - GECKO(1719) | [Child 4402, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-08-24T22:56:42.136Z] 22:56:42     INFO - GECKO(1719) | [Child 4402, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-08-24T22:56:42.136Z] 22:56:42     INFO - GECKO(1719) | nsStringStats
[task 2019-08-24T22:56:42.136Z] 22:56:42     INFO - GECKO(1719) |  => mAllocCount:           7741
[task 2019-08-24T22:56:42.136Z] 22:56:42     INFO - GECKO(1719) |  => mReallocCount:            0
[task 2019-08-24T22:56:42.136Z] 22:56:42     INFO - GECKO(1719) |  => mFreeCount:            7741
[task 2019-08-24T22:56:42.137Z] 22:56:42     INFO - GECKO(1719) |  => mShareCount:           5101
[task 2019-08-24T22:56:42.137Z] 22:56:42     INFO - GECKO(1719) |  => mAdoptCount:            258
[task 2019-08-24T22:56:42.137Z] 22:56:42     INFO - GECKO(1719) |  => mAdoptFreeCount:        264
[task 2019-08-24T22:56:42.137Z] 22:56:42     INFO - GECKO(1719) |  => Process ID: 4402, Thread ID: 139764068349760
[task 2019-08-24T22:56:42.153Z] 22:56:42     INFO - GECKO(1719) | [Child 4412, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-08-24T22:56:42.153Z] 22:56:42     INFO - GECKO(1719) | [Child 4412, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-08-24T22:56:42.153Z] 22:56:42     INFO - GECKO(1719) | nsStringStats
[task 2019-08-24T22:56:42.153Z] 22:56:42     INFO - GECKO(1719) |  => mAllocCount:           7706
[task 2019-08-24T22:56:42.153Z] 22:56:42     INFO - GECKO(1719) |  => mReallocCount:            0
[task 2019-08-24T22:56:42.153Z] 22:56:42     INFO - GECKO(1719) |  => mFreeCount:            7706
[task 2019-08-24T22:56:42.153Z] 22:56:42     INFO - GECKO(1719) |  => mShareCount:           5082
[task 2019-08-24T22:56:42.153Z] 22:56:42     INFO - GECKO(1719) |  => mAdoptCount:            258
[task 2019-08-24T22:56:42.153Z] 22:56:42     INFO - GECKO(1719) |  => mAdoptFreeCount:        264
[task 2019-08-24T22:56:42.153Z] 22:56:42     INFO - GECKO(1719) |  => Process ID: 4412, Thread ID: 140385988818752
[task 2019-08-24T22:56:42.171Z] 22:56:42     INFO - GECKO(1719) | [Child 4373, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-08-24T22:56:42.172Z] 22:56:42     INFO - GECKO(1719) | [Child 4373, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-08-24T22:56:42.172Z] 22:56:42     INFO - GECKO(1719) | nsStringStats
[task 2019-08-24T22:56:42.172Z] 22:56:42     INFO - GECKO(1719) |  => mAllocCount:           9121
[task 2019-08-24T22:56:42.173Z] 22:56:42     INFO - GECKO(1719) |  => mReallocCount:            0
[task 2019-08-24T22:56:42.173Z] 22:56:42     INFO - GECKO(1719) |  => mFreeCount:            9121
[task 2019-08-24T22:56:42.174Z] 22:56:42     INFO - GECKO(1719) |  => mShareCount:          10763
[task 2019-08-24T22:56:42.174Z] 22:56:42     INFO - GECKO(1719) |  => mAdoptCount:            637
[task 2019-08-24T22:56:42.175Z] 22:56:42     INFO - GECKO(1719) |  => mAdoptFreeCount:        653
[task 2019-08-24T22:56:42.175Z] 22:56:42     INFO - GECKO(1719) |  => Process ID: 4373, Thread ID: 140408435713856
[task 2019-08-24T22:56:42.181Z] 22:56:42     INFO - GECKO(1719) | [Child 4427, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-08-24T22:56:42.181Z] 22:56:42     INFO - GECKO(1719) | [Child 4427, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-08-24T22:56:42.181Z] 22:56:42     INFO - GECKO(1719) | nsStringStats
[task 2019-08-24T22:56:42.181Z] 22:56:42     INFO - GECKO(1719) |  => mAllocCount:           7665
[task 2019-08-24T22:56:42.181Z] 22:56:42     INFO - GECKO(1719) |  => mReallocCount:            0
[task 2019-08-24T22:56:42.181Z] 22:56:42     INFO - GECKO(1719) |  => mFreeCount:            7665
[task 2019-08-24T22:56:42.181Z] 22:56:42     INFO - GECKO(1719) |  => mShareCount:           5069
[task 2019-08-24T22:56:42.181Z] 22:56:42     INFO - GECKO(1719) |  => mAdoptCount:            258
[task 2019-08-24T22:56:42.181Z] 22:56:42     INFO - GECKO(1719) |  => mAdoptFreeCount:        264
[task 2019-08-24T22:56:42.181Z] 22:56:42     INFO - GECKO(1719) |  => Process ID: 4427, Thread ID: 140506274330432
[task 2019-08-24T22:56:42.277Z] 22:56:42     INFO - GECKO(1719) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-08-24T22:56:42.446Z] 22:56:42     INFO - GECKO(1719) |  WARN 2019-08-24T22:56:42Z: audio_thread_priority::rt_linux: Could not make thread real-time.
[task 2019-08-24T22:56:42.446Z] 22:56:42     INFO - GECKO(1719) |  WARN 2019-08-24T22:56:42Z: audioipc_client: Could not promote audio threads to real-time during initialization.
[task 2019-08-24T22:56:42.467Z] 22:56:42     INFO - GECKO(1719) | ++DOCSHELL 0x7f3f45bb9000 == 1 [pid = 4485] [id = {3e3e57c5-0c5d-4477-8254-d8de3e94f853}]
[task 2019-08-24T22:56:42.530Z] 22:56:42     INFO - GECKO(1719) | ++DOMWINDOW == 1 (0x7f3f46bddf20) [pid = 4485] [serial = 1] [outer = (nil)]
[task 2019-08-24T22:56:42.531Z] 22:56:42     INFO - GECKO(1719) | ++DOMWINDOW == 2 (0x7f3f45b6ac00) [pid = 4485] [serial = 2] [outer = 0x7f3f46bddf20]
[task 2019-08-24T22:56:42.795Z] 22:56:42     INFO - GECKO(1719) | ++DOMWINDOW == 3 (0x7f3f46cc9000) [pid = 4485] [serial = 3] [outer = 0x7f3f46bddf20]
[task 2019-08-24T22:56:42.871Z] 22:56:42     INFO - GECKO(1719) | ++DOCSHELL 0x7f3f45653800 == 2 [pid = 4485] [id = {de2884a4-73c7-444f-85a2-2e411636ac50}]
[task 2019-08-24T22:56:42.872Z] 22:56:42     INFO - GECKO(1719) | ++DOMWINDOW == 4 (0x7f3f46bde6a0) [pid = 4485] [serial = 4] [outer = (nil)]
[task 2019-08-24T22:56:43.029Z] 22:56:43     INFO - GECKO(1719) | [Child 4485, Main Thread] WARNING: NS_ENSURE_TRUE(currentInner) failed: file /builds/worker/workspace/build/src/dom/base/WindowDestroyedEvent.cpp, line 106
[task 2019-08-24T22:56:43.030Z] 22:56:43     INFO - GECKO(1719) | [Child 4485, Main Thread] WARNING: CompleteRedirectSetup failed, HttpChannelChild already open?: file /builds/worker/workspace/build/src/netwerk/protocol/http/HttpChannelChild.cpp, line 2125
[task 2019-08-24T22:56:43.078Z] 22:56:43     INFO - GECKO(1719) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpvnxwji.mozrunner/runtests_leaks_tab_pid4513.log
[task 2019-08-24T22:56:43.174Z] 22:56:43     INFO - GECKO(1719) | JavaScript error: , line 0: TypeError: NetworkError when attempting to fetch resource.
[task 2019-08-24T22:56:43.255Z] 22:56:43     INFO - GECKO(1719) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-08-24T22:56:43.364Z] 22:56:43     INFO - GECKO(1719) |  WARN 2019-08-24T22:56:43Z: audio_thread_priority::rt_linux: Could not make thread real-time.
[task 2019-08-24T22:56:43.364Z] 22:56:43     INFO - GECKO(1719) |  WARN 2019-08-24T22:56:43Z: audioipc_client: Could not promote audio threads to real-time during initialization.
[task 2019-08-24T22:56:43.367Z] 22:56:43     INFO - GECKO(1719) | ++DOCSHELL 0x7fcc1ffb9800 == 1 [pid = 4513] [id = {4cfb0516-3154-4a23-a22c-3ba161bfe6b4}]
[task 2019-08-24T22:56:43.416Z] 22:56:43     INFO - GECKO(1719) | ++DOMWINDOW == 1 (0x7fcc20eb5f20) [pid = 4513] [serial = 1] [outer = (nil)]
[task 2019-08-24T22:56:43.417Z] 22:56:43     INFO - GECKO(1719) | ++DOMWINDOW == 2 (0x7fcc1ff6b800) [pid = 4513] [serial = 2] [outer = 0x7fcc20eb5f20]
[task 2019-08-24T22:56:43.418Z] 22:56:43     INFO - GECKO(1719) | [Child 4513, Main Thread] WARNING: '!topWindow', file /builds/worker/workspace/build/src/toolkit/components/antitracking/AntiTrackingCommon.cpp, line 1306
[task 2019-08-24T22:56:43.479Z] 22:56:43     INFO - GECKO(1719) | ++DOMWINDOW == 3 (0x7fcc20e11400) [pid = 4513] [serial = 3] [outer = 0x7fcc20eb5f20]
[task 2019-08-24T22:56:43.480Z] 22:56:43     INFO - GECKO(1719) | [Child 4513, Main Thread] WARNING: '!topWindow', file /builds/worker/workspace/build/src/toolkit/components/antitracking/AntiTrackingCommon.cpp, line 1306
[task 2019-08-24T22:56:49.579Z] 22:56:49     INFO - GECKO(1719) | --DOCSHELL 0x7f51a855c800 == 0 [pid = 1846] [id = {16faddf4-8016-4741-9464-4d1de01af3d1}] [url = about:blank]
[task 2019-08-24T22:56:50.826Z] 22:56:50     INFO - GECKO(1719) | --DOMWINDOW == 3 (0x7f3f46bde6a0) [pid = 4485] [serial = 4] [outer = (nil)] [url = ]
[task 2019-08-24T22:56:50.882Z] 22:56:50     INFO - GECKO(1719) | --DOCSHELL 0x7f3f45653800 == 1 [pid = 4485] [id = {de2884a4-73c7-444f-85a2-2e411636ac50}] [url = ]
[task 2019-08-24T22:56:53.656Z] 22:56:53     INFO - GECKO(1719) | --DOMWINDOW == 1 (0x7f51a89f62e0) [pid = 1846] [serial = 36] [outer = (nil)] [url = about:blank]
[task 2019-08-24T22:56:54.959Z] 22:56:54     INFO - GECKO(1719) | --DOMWINDOW == 2 (0x7f3f45b6ac00) [pid = 4485] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2019-08-24T22:56:57.608Z] 22:56:57     INFO - GECKO(1719) | --DOMWINDOW == 2 (0x7fcc1ff6b800) [pid = 4513] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2019-08-24T22:56:57.689Z] 22:56:57     INFO - GECKO(1719) | --DOMWINDOW == 0 (0x7f51a864e800) [pid = 1846] [serial = 37] [outer = (nil)] [url = about:blank]
[task 2019-08-24T22:58:11.602Z] 22:58:11     INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-08-24T22:58:11.602Z] 22:58:11     INFO - Buffered messages logged at 22:56:41
[task 2019-08-24T22:58:11.602Z] 22:58:11     INFO - Entering test bound allowOnionMixedContent
[task 2019-08-24T22:58:11.603Z] 22:58:11     INFO - Buffered messages logged at 22:56:43
[task 2019-08-24T22:58:11.603Z] 22:58:11     INFO - Console message: [JavaScript Warning: "Loading failed for the <script> with source “http://123456789abcdef.onion:8/test.js”." {file: "https://example.com/browser/browser/base/content/test/siteIdentity/test_no_mcb_for_onions.html" line: 21}]
[task 2019-08-24T22:58:11.604Z] 22:58:11     INFO - Console message: [JavaScript Warning: "Cross-Origin Request Blocked: The Same Origin Policy disallows reading the remote resource at http://123456789abcdef.onion:8/. (Reason: CORS request did not succeed)."]
[task 2019-08-24T22:58:11.605Z] 22:58:11     INFO - Console message: [JavaScript Error: "TypeError: NetworkError when attempting to fetch resource."]
[task 2019-08-24T22:58:11.605Z] 22:58:11     INFO - Buffered messages logged at 22:57:17
[task 2019-08-24T22:58:11.606Z] 22:58:11     INFO - Console message: [JavaScript Error: "getScreenshot(https://example.com/) failed: TypeError: NetworkError when attempting to fetch resource." {file: "resource://activity-stream/lib/Screenshots.jsm" line: 59}]

Comment 1

[Cosmin Sabou [:CosminS]]

Looks to have started with Bug 1559841: https://treeherder.mozilla.org/#/jobs?repo=autoland&group_state=expanded&resultStatus=pending%2Crunning%2Csuccess%2Ctestfailed%2Cbusted%2Cexception&searchStr=linux%2Cx64%2Cdebug%2Cmochitests%2Cwith%2Cfission%2Cenabled%2Ctest-linux64%2Fdebug-mochitest-browser-chrome-fis-e10s-3%2Cm-fis%28bc3%29&fromchange=e22f8708f313b72be680291e1c59429f579abbc0&tochange=3177490c6c0fba5c703a08d129ec55f3fd53bece&selectedJob=263374641

Comment 2

[Intermittent Failures Robot]

40 failures in 5073 pushes (0.008 failures/push) were associated with this bug in the last 7 days.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• mozilla-central: 28
• autoland: 12

Platform breakdown:

• linux64: 28
• windows10-64-qr: 4
• windows10-64: 8

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1576423&startday=2019-08-19&endday=2019-08-25&tree=all

Comment 3

[Intermittent Failures Robot]

19 failures in 643 pushes (0.03 failures/push) were associated with this bug yesterday.

Repository breakdown:

• mozilla-central: 14
• try: 5

Platform breakdown:

• linux64: 8
• windows10-64: 5
• windows10-64-qr: 6

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1576423&startday=2019-08-26&endday=2019-08-26&tree=all

Comment 4

[Intermittent Failures Robot]

19 failures in 644 pushes (0.03 failures/push) were associated with this bug yesterday.

Repository breakdown:

• mozilla-central: 14
• try: 5

Platform breakdown:

• linux64: 8
• windows10-64: 5
• windows10-64-qr: 6

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1576423&startday=2019-08-26&endday=2019-08-26&tree=all

Comment 5

[Intermittent Failures Robot]

31 failures in 5047 pushes (0.006 failures/push) were associated with this bug in the last 7 days.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• mozilla-central: 26
• try: 5

Platform breakdown:

• linux64: 14
• windows10-64: 8
• windows10-64-qr: 9

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1576423&startday=2019-08-26&endday=2019-09-01&tree=all

Comment 6

[Henrik Skupin [:whimboo][⌚️UTC+2]]

This was fixed by the backout of the patch from bug 1559841 on Aug 28th.

Comment 7

[Jonathan Watt [:jwatt]]

FWIW this went away after making the changes Nika suggested in bug 1576413. To be fair, there were so many failures with the first suggested approach that I'm not 100% sure it went away, but I'm pursuing the latter approach (storing a strong ref to the docShell).

Comment 8

[Chris Peterson [:cpeterson]]

Retroactively moving fixed bugs whose summaries mention "Fission" (or other Fission-related keywords) but are not assigned to a Fission Milestone to an appropriate Fission Milestone.

This will generate a lot of bugmail, so you can filter your bugmail for the following UUID and delete them en masse:

0ee3c76a-bc79-4eb2-8d12-05dc0b68e732