[task 2021-07-27T17:21:36.622Z] 17:21:36 INFO - TEST-START | devtools/client/storage/test/browser_storage_dfpi.js
[task 2021-07-27T17:21:36.756Z] 17:21:36 INFO - GECKO(1548) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp_29hicl8.mozrunner/runtests_leaks_tab_pid4056.log
(7f4188127000) [pid = 4056] [serial = 2] [outer = 0] [url = about:blank]
[task 2021-07-27T17:21:42.099Z] 17:21:42 INFO - GECKO(1548) | [Child 4056: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7f418812cc00) [pid = 4056] [serial = 3] [outer = 0] [url = http://sub1.test1.example.org/browser/devtools/client/storage/test/storage-dfpi.html]
[task 2021-07-27T17:21:42.168Z] 17:21:42 INFO - GECKO(1548) | [Child 4080, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-07-27T17:21:42.187Z] 17:21:42 INFO - GECKO(1548) | [Child 4080, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4438
[task 2021-07-27T17:21:42.218Z] 17:21:42 INFO - GECKO(1548) | [Child 4105, Main Thread] WARNING: NS_ENSURE_TRUE(currentInner) failed: file /builds/worker/checkouts/gecko/dom/base/WindowDestroyedEvent.cpp:113
[task 2021-07-27T17:21:42.258Z] 17:21:42 INFO - GECKO(1548) | [Child 4056, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-07-27T17:21:42.273Z] 17:21:42 INFO - GECKO(1548) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp_29hicl8.mozrunner/runtests_leaks_tab_pid4129.log
[task 2021-07-27T17:21:42.286Z] 17:21:42 INFO - GECKO(1548) | [4129, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:250
[task 2021-07-27T17:21:42.325Z] 17:21:42 INFO - GECKO(1548) | [Child 4056, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4438
[task 2021-07-27T17:21:42.331Z] 17:21:42 INFO - GECKO(1548) | [Child 4129, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:619
[task 2021-07-27T17:21:42.493Z] 17:21:42 INFO - GECKO(1548) | [Child 4129: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7fc1e2322800 == 1 [pid = 4129] [id = 0][task 2021-07-27T17:21:42.494Z] 17:21:42 INFO - GECKO(1548) | [Child 4129: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7fc1fd0643c0) [pid = 4129] [serial = 1] [outer = 0]
[task 2021-07-27T17:21:42.495Z] 17:21:42 INFO - GECKO(1548) | [Child 4129: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7fc1e2327000) [pid = 4129] [serial = 2] [outer = 7fc1fd0643c0]
[task 2021-07-27T17:21:42.538Z] 17:21:42 INFO - GECKO(1548) | [Child 4129: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (7fc1e232d000) [pid = 4129] [serial = 3] [outer = 7fc1fd0643c0]
[task 2021-07-27T17:21:42.838Z] 17:21:42 INFO - GECKO(1548) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp_29hicl8.mozrunner/runtests_leaks_tab_pid4153.log
[task 2021-07-27T17:21:42.840Z] 17:21:42 INFO - GECKO(1548) | [4153, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:250
[task 2021-07-27T17:21:42.883Z] 17:21:42 INFO - GECKO(1548) | [Child 4153, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:619
[task 2021-07-27T17:21:43.026Z] 17:21:43 INFO - GECKO(1548) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-07-27T17:21:43.032Z] 17:21:43 INFO - GECKO(1548) | [Child 4105: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f9f7b48c400 == 1 [pid = 4105] [id = 1] [url = ]
[task 2021-07-27T17:21:43.033Z] 17:21:43 INFO - GECKO(1548) | [Child 4105: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (7f9f96664740) [pid = 4105] [serial = 4] [outer = 0] [url = ]
[task 2021-07-27T17:21:43.041Z] 17:21:43 INFO - GECKO(1548) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-07-27T17:21:43.165Z] 17:21:43 INFO - GECKO(1548) | [Child 4129: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7fc1e2322800 == 0 [pid = 4129] [id = 0] [url = http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html]
[task 2021-07-27T17:21:43.210Z] 17:21:43 INFO - GECKO(1548) | [Child 4153: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f9093022800 == 1 [pid = 4153] [id = 0]
[task 2021-07-27T17:21:43.212Z] 17:21:43 INFO - GECKO(1548) | [Child 4153: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7f90add64200) [pid = 4153] [serial = 1] [outer = 0]
[task 2021-07-27T17:21:43.214Z] 17:21:43 INFO - GECKO(1548) | [Child 4153: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7f9093026c00) [pid = 4153] [serial = 2] [outer = 7f90add64200]
[task 2021-07-27T17:21:43.242Z] 17:21:43 INFO - GECKO(1548) | [Child 4105: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f9f7b922800 == 0 [pid = 4105] [id = 0] [url = http://sub1.test1.example.org/browser/devtools/client/storage/test/storage-dfpi.html]
[task 2021-07-27T17:21:43.275Z] 17:21:43 INFO - GECKO(1548) | [Child 4129: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (7fc1fd0643c0) [pid = 4129] [serial = 1] [outer = 0] [url = http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html]
[task 2021-07-27T17:21:43.277Z] 17:21:43 INFO - GECKO(1548) | [Child 4129: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7fc1e2327000) [pid = 4129] [serial = 2] [outer = 0] [url = about:blank]
[task 2021-07-27T17:21:43.279Z] 17:21:43 INFO - GECKO(1548) | [Child 4129: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7fc1e232d000) [pid = 4129] [serial = 3] [outer = 0] [url = http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html]
[task 2021-07-27T17:21:43.280Z] 17:21:43 INFO - GECKO(1548) | [Child 4129, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-07-27T17:21:43.314Z] 17:21:43 INFO - GECKO(1548) | [Child 4129, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4438
[task 2021-07-27T17:21:43.370Z] 17:21:43 INFO - GECKO(1548) | [Child 4105: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (7f9f96664200) [pid = 4105] [serial = 1] [outer = 0] [url = http://sub1.test1.example.org/browser/devtools/client/storage/test/storage-dfpi.html]
[task 2021-07-27T17:21:43.372Z] 17:21:43 INFO - GECKO(1548) | [Child 4105: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7f9f7b927c00) [pid = 4105] [serial = 2] [outer = 0] [url = about:blank]
[task 2021-07-27T17:21:43.374Z] 17:21:43 INFO - GECKO(1548) | [Child 4105: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7f9f7b92cc00) [pid = 4105] [serial = 3] [outer = 0] [url = http://sub1.test1.example.org/browser/devtools/client/storage/test/storage-dfpi.html]
[task 2021-07-27T17:21:43.378Z] 17:21:43 INFO - GECKO(1548) | [Child 4105, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-07-27T17:21:43.408Z] 17:21:43 INFO - GECKO(1548) | [Child 4153, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp:348
[task 2021-07-27T17:21:43.409Z] 17:21:43 INFO - GECKO(1548) | [Child 4153, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp:348
[task 2021-07-27T17:21:43.411Z] 17:21:43 INFO - GECKO(1548) | [Child 4153, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp:348
[task 2021-07-27T17:21:43.425Z] 17:21:43 INFO - GECKO(1548) | [Child 4153: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (7f909302d400) [pid = 4153] [serial = 3] [outer = 7f90add64200]
[task 2021-07-27T17:21:43.431Z] 17:21:43 INFO - GECKO(1548) | [Child 4105, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4438
[task 2021-07-27T17:21:43.528Z] 17:21:43 INFO - GECKO(1548) | [Child 4153: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f9092b8f800 == 2 [pid = 4153] [id = 1]
[task 2021-07-27T17:21:43.529Z] 17:21:43 INFO - GECKO(1548) | [Child 4153: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (7f90add64580) [pid = 4153] [serial = 4] [outer = 0]
[task 2021-07-27T17:21:43.663Z] 17:21:43 INFO - GECKO(1548) | [Child 4153, Main Thread] WARNING: NS_ENSURE_TRUE(currentInner) failed: file /builds/worker/checkouts/gecko/dom/base/WindowDestroyedEvent.cpp:113
[task 2021-07-27T17:21:43.750Z] 17:21:43 INFO - GECKO(1548) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp_29hicl8.mozrunner/runtests_leaks_tab_pid4177.log
[task 2021-07-27T17:21:43.753Z] 17:21:43 INFO - GECKO(1548) | [4177, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:250
[task 2021-07-27T17:21:43.821Z] 17:21:43 INFO - GECKO(1548) | [Child 4177, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:619
[task 2021-07-27T17:21:43.965Z] 17:21:43 INFO - GECKO(1548) | [Child 4177: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f699e322800 == 1 [pid = 4177] [id = 0]
[task 2021-07-27T17:21:43.967Z] 17:21:43 INFO - GECKO(1548) | [Child 4177: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7f69b90643c0) [pid = 4177] [serial = 1] [outer = 0]
[task 2021-07-27T17:21:43.968Z] 17:21:43 INFO - GECKO(1548) | [Child 4177: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7f699e327000) [pid = 4177] [serial = 2] [outer = 7f69b90643c0]
[task 2021-07-27T17:21:44.011Z] 17:21:44 INFO - GECKO(1548) | [Child 4177: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (7f699e32d400) [pid = 4177] [serial = 3] [outer = 7f69b90643c0]
[task 2021-07-27T17:21:44.198Z] 17:21:44 INFO - GECKO(1548) | [Parent 1548: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f2c9a9bac00 == 8 [pid = 1548] [id = 61][task 2021-07-27T17:21:44.200Z] 17:21:44 INFO - GECKO(1548) | [Parent 1548: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 18 (7f2cac3b8c80) [pid = 1548] [serial = 152] [outer = 0]
[task 2021-07-27T17:21:44.202Z] 17:21:44 INFO - GECKO(1548) | [Parent 1548: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 19 (7f2c9ad65800) [pid = 1548] [serial = 153] [outer = 7f2cac3b8c80]
[task 2021-07-27T17:21:44.223Z] 17:21:44 INFO - GECKO(1548) | [Child 4153, Main Thread] WARNING: Scrolled rect smaller than scrollport?: file /builds/worker/checkouts/gecko/layout/generic/nsGfxScrollFrame.cpp:7051
[task 2021-07-27T17:21:44.251Z] 17:21:44 INFO - GECKO(1548) | [Parent 1548: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 20 (7f2c9d038400) [pid = 1548] [serial = 154] [outer = 7f2cac3b8c80]
[task 2021-07-27T17:21:44.446Z] 17:21:44 INFO - GECKO(1548) | [Parent 1548, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/dom/base/nsContentUtils.cpp:4018
[task 2021-07-27T17:21:44.776Z] 17:21:44 INFO - GECKO(1548) | [Child 1691: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7fe7c0164580) [pid = 1691] [serial = 10] [outer = 0] [url = about:blank]
[task 2021-07-27T17:21:45.250Z] 17:21:45 INFO - GECKO(1548) | [Parent 1548: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f2c9a94f400 == 9 [pid = 1548] [id = 62]
[task 2021-07-27T17:21:45.250Z] 17:21:45 INFO - GECKO(1548) | [Parent 1548: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 21 (7f2cae7fa580) [pid = 1548] [serial = 155] [outer = 0]
[task 2021-07-27T17:21:45.252Z] 17:21:45 INFO - GECKO(1548) | [Parent 1548: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 22 (7f2c9ad72c00) [pid = 1548] [serial = 156] [outer = 7f2cae7fa580]
[task 2021-07-27T17:21:45.269Z] 17:21:45 INFO - GECKO(1548) | [Parent 1548, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/chrome/nsChromeRegistry.cpp:180
[task 2021-07-27T17:21:45.269Z] 17:21:45 INFO - GECKO(1548) | [Parent 1548, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/dom/security/nsCSPService.cpp:191
[task 2021-07-27T17:21:45.721Z] 17:21:45 INFO - GECKO(1548) | [Parent 1548, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/dom/base/nsContentUtils.cpp:4018
[task 2021-07-27T17:21:45.976Z] 17:21:45 INFO - GECKO(1548) | console.warn: "Failed to enumerate CacheStorage for host http://sub1.test1.example.com: SecurityError: The operation is insecure."
[task 2021-07-27T17:21:47.305Z] 17:21:47 INFO - GECKO(1548) | console.error: "Tried to send a 'target-destroyed-form' event on an already destroyed actor 'watcher'"
[task 2021-07-27T17:21:47.314Z] 17:21:47 INFO - GECKO(1548) | [Parent 1548, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:184
[task 2021-07-27T17:21:48.482Z] 17:21:48 INFO - GECKO(1548) | [Parent 1548: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 21 (7f2c9ac0a800) [pid = 1548] [serial = 148] [outer = 0] [url = about:blank]
[task 2021-07-27T17:21:48.484Z] 17:21:48 INFO - GECKO(1548) | [Parent 1548: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f2c9a9b7800 == 8 [pid = 1548] [id = 59] [url = about:devtools-toolbox]
[task 2021-07-27T17:21:48.487Z] 17:21:48 INFO - GECKO(1548) | [Parent 1548: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f2c9ac09800 == 7 [pid = 1548] [id = 60] [url = chrome://devtools/content/storage/index.xhtml]
[task 2021-07-27T17:21:48.488Z] 17:21:48 INFO - GECKO(1548) | [Parent 1548: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 20 (7f2c9ac0b800) [pid = 1548] [serial = 146] [outer = 0] [url = about:blank]
[task 2021-07-27T17:21:48.488Z] 17:21:48 INFO - GECKO(1548) | [Parent 1548: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 19 (7f2c9ad6f000) [pid = 1548] [serial = 144] [outer = 0] [url = about:devtools-toolbox]
[task 2021-07-27T17:21:48.492Z] 17:21:48 INFO - GECKO(1548) | [Parent 1548: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f2c9a94f400 == 6 [pid = 1548] [id = 62] [url = chrome://devtools/content/storage/index.xhtml]
[task 2021-07-27T17:21:48.496Z] 17:21:48 INFO - GECKO(1548) | [Parent 1548: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f2c9a9bac00 == 5 [pid = 1548] [id = 61] [url = about:devtools-toolbox]
[task 2021-07-27T17:21:48.649Z] 17:21:48 INFO - GECKO(1548) | [Parent 1548: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 18 (7f2ca17863c0) [pid = 1548] [serial = 147] [outer = 0] [url = about:devtools-toolbox]
[task 2021-07-27T17:21:48.649Z] 17:21:48 INFO - GECKO(1548) | [Parent 1548: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 17 (7f2ca320d580) [pid = 1548] [serial = 150] [outer = 0] [url = chrome://devtools/content/storage/index.xhtml]
[task 2021-07-27T17:21:48.753Z] 17:21:48 INFO - GECKO(1548) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp_29hicl8.mozrunner/runtests_leaks_tab_pid4202.log
[task 2021-07-27T17:21:48.754Z] 17:21:48 INFO - GECKO(1548) | [4202, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:250
[task 2021-07-27T17:21:48.791Z] 17:21:48 INFO - GECKO(1548) | [Child 4202, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:619
[task 2021-07-27T17:21:48.838Z] 17:21:48 INFO - GECKO(1548) | [Child 1691: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7fe7a4969800) [pid = 1691] [serial = 11] [outer = 0] [url = about:blank]
[task 2021-07-27T17:21:48.880Z] 17:21:48 INFO - GECKO(1548) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-07-27T17:21:48.886Z] 17:21:48 INFO - GECKO(1548) | [Child 4153: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f9092b8f800 == 1 [pid = 4153] [id = 1] [url = ]
[task 2021-07-27T17:21:48.887Z] 17:21:48 INFO - GECKO(1548) | [Child 4153: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (7f90add64580) [pid = 4153] [serial = 4] [outer = 0] [url = ]
[task 2021-07-27T17:21:48.958Z] 17:21:48 INFO - GECKO(1548) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-07-27T17:21:48.961Z] 17:21:48 INFO - GECKO(1548) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-07-27T17:21:48.962Z] 17:21:48 INFO - GECKO(1548) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-07-27T17:21:49.203Z] 17:21:49 INFO - GECKO(1548) | [Child 4153: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f9093022800 == 0 [pid = 4153] [id = 0] [url = http://sub1.test1.example.org/browser/devtools/client/storage/test/storage-dfpi.html]
[task 2021-07-27T17:21:49.265Z] 17:21:49 INFO - GECKO(1548) | [Child 4202: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f75ba322800 == 1 [pid = 4202] [id = 0][task 2021-07-27T17:21:49.267Z] 17:21:49 INFO - GECKO(1548) | [Child 4202: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7f75d5064200) [pid = 4202] [serial = 1] [outer = 0]
[task 2021-07-27T17:21:49.270Z] 17:21:49 INFO - GECKO(1548) | [Child 4202: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7f75ba327c00) [pid = 4202] [serial = 2] [outer = 7f75d5064200]
[task 2021-07-27T17:21:49.356Z] 17:21:49 INFO - GECKO(1548) | [Child 4153: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (7f9093026c00) [pid = 4153] [serial = 2] [outer = 0] [url = about:blank]
[task 2021-07-27T17:21:49.358Z] 17:21:49 INFO - GECKO(1548) | [Child 4153: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7f90add64200) [pid = 4153] [serial = 1] [outer = 0] [url = http://sub1.test1.example.org/browser/devtools/client/storage/test/storage-dfpi.html]
[task 2021-07-27T17:21:49.360Z] 17:21:49 INFO - GECKO(1548) | [Child 4153: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7f909302d400) [pid = 4153] [serial = 3] [outer = 0] [url = http://sub1.test1.example.org/browser/devtools/client/storage/test/storage-dfpi.html]
[task 2021-07-27T17:21:49.426Z] 17:21:49 INFO - GECKO(1548) | [Child 4177: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f699e322800 == 0 [pid = 4177] [id = 0] [url = http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html]
[task 2021-07-27T17:21:49.468Z] 17:21:49 INFO - GECKO(1548) | [Child 4202, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp:348
[task 2021-07-27T17:21:49.468Z] 17:21:49 INFO - GECKO(1548) | [Child 4202, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp:348
[task 2021-07-27T17:21:49.471Z] 17:21:49 INFO - GECKO(1548) | [Child 4202, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp:348
[task 2021-07-27T17:21:49.476Z] 17:21:49 INFO - GECKO(1548) | [Child 4202: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (7f75ba32d000) [pid = 4202] [serial = 3] [outer = 7f75d5064200]
[task 2021-07-27T17:21:49.552Z] 17:21:49 INFO - GECKO(1548) | [Child 4202: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f75b9e8c400 == 2 [pid = 4202] [id = 1]
[task 2021-07-27T17:21:49.554Z] 17:21:49 INFO - GECKO(1548) | [Child 4202: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (7f75d5064740) [pid = 4202] [serial = 4] [outer = 0]
[task 2021-07-27T17:21:49.574Z] 17:21:49 INFO - GECKO(1548) | [Child 4153, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-07-27T17:21:49.622Z] 17:21:49 INFO - GECKO(1548) | [Child 4153, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4438
[task 2021-07-27T17:21:49.756Z] 17:21:49 INFO - GECKO(1548) | [Child 4177: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (7f699e327000) [pid = 4177] [serial = 2] [outer = 0] [url = about:blank]
[task 2021-07-27T17:21:49.756Z] 17:21:49 INFO - GECKO(1548) | [Child 4177: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7f69b90643c0) [pid = 4177] [serial = 1] [outer = 0] [url = http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html]
[task 2021-07-27T17:21:49.756Z] 17:21:49 INFO - GECKO(1548) | [Child 4177: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7f699e32d400) [pid = 4177] [serial = 3] [outer = 0] [url = http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html]
[task 2021-07-27T17:21:49.864Z] 17:21:49 INFO - GECKO(1548) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp_29hicl8.mozrunner/runtests_leaks_tab_pid4226.log
[task 2021-07-27T17:21:49.866Z] 17:21:49 INFO - GECKO(1548) | [4226, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:250
[task 2021-07-27T17:21:49.901Z] 17:21:49 INFO - GECKO(1548) | [Child 4226, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:619
[task 2021-07-27T17:21:49.993Z] 17:21:49 INFO - GECKO(1548) | [Child 4202, Main Thread] WARNING: NS_ENSURE_TRUE(currentInner) failed: file /builds/worker/checkouts/gecko/dom/base/WindowDestroyedEvent.cpp:113
[task 2021-07-27T17:21:50.044Z] 17:21:50 INFO - GECKO(1548) | [Child 4177, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-07-27T17:21:50.091Z] 17:21:50 INFO - GECKO(1548) | [Child 4177, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4438
[task 2021-07-27T17:21:50.141Z] 17:21:50 INFO - GECKO(1548) | [Child 4226: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7fa58cb22800 == 1 [pid = 4226] [id = 0]
[task 2021-07-27T17:21:50.143Z] 17:21:50 INFO - GECKO(1548) | [Child 4226: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7fa5a7864200) [pid = 4226] [serial = 1] [outer = 0]
[task 2021-07-27T17:21:50.146Z] 17:21:50 INFO - GECKO(1548) | [Child 4226: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7fa58cb27000) [pid = 4226] [serial = 2] [outer = 7fa5a7864200]
[task 2021-07-27T17:21:50.199Z] 17:21:50 INFO - GECKO(1548) | [Child 4226: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (7fa58cb2d400) [pid = 4226] [serial = 3] [outer = 7fa5a7864200]
[task 2021-07-27T17:21:50.524Z] 17:21:50 INFO - GECKO(1548) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp_29hicl8.mozrunner/runtests_leaks_tab_pid4250.log
[task 2021-07-27T17:21:50.525Z] 17:21:50 INFO - GECKO(1548) | [4250, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:250
[task 2021-07-27T17:21:50.561Z] 17:21:50 INFO - GECKO(1548) | [Child 4250, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:619
[task 2021-07-27T17:21:50.659Z] 17:21:50 INFO - GECKO(1548) | ###!!! [Parent][MessageChannel] Error: (msgtype=0x390076,name=PContent::Msg_DestroyBrowsingContextGroup) Closed channel: cannot send/recv
[task 2021-07-27T17:21:50.668Z] 17:21:50 INFO - GECKO(1548) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-07-27T17:21:50.669Z] 17:21:50 INFO - GECKO(1548) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-07-27T17:21:50.675Z] 17:21:50 INFO - GECKO(1548) | [Child 4202: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f75b9e8c400 == 1 [pid = 4202] [id = 1] [url = ]
[task 2021-07-27T17:21:50.675Z] 17:21:50 INFO - GECKO(1548) | [Child 4202: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (7f75d5064740) [pid = 4202] [serial = 4] [outer = 0] [url = ]
[task 2021-07-27T17:21:50.716Z] 17:21:50 INFO - GECKO(1548) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-07-27T17:21:50.803Z] 17:21:50 INFO - GECKO(1548) | [Child 4226: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7fa58cb22800 == 0 [pid = 4226] [id = 0] [url = http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html]
[task 2021-07-27T17:21:50.904Z] 17:21:50 INFO - GECKO(1548) | [Child 4202: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f75ba322800 == 0 [pid = 4202] [id = 0] [url = http://sub1.test1.example.org/browser/devtools/client/storage/test/storage-dfpi.html]
[task 2021-07-27T17:21:50.946Z] 17:21:50 INFO - GECKO(1548) | [Child 4226: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (7fa5a7864200) [pid = 4226] [serial = 1] [outer = 0] [url = http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html]
[task 2021-07-27T17:21:50.946Z] 17:21:50 INFO - GECKO(1548) | [Child 4226: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7fa58cb27000) [pid = 4226] [serial = 2] [outer = 0] [url = about:blank]
[task 2021-07-27T17:21:50.946Z] 17:21:50 INFO - GECKO(1548) | [Child 4226: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7fa58cb2d400) [pid = 4226] [serial = 3] [outer = 0] [url = http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html]
[task 2021-07-27T17:21:50.946Z] 17:21:50 INFO - GECKO(1548) | [Child 4226, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-07-27T17:21:50.959Z] 17:21:50 INFO - GECKO(1548) | [Child 4250: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f849da22800 == 1 [pid = 4250] [id = 0][task 2021-07-27T17:21:50.959Z] 17:21:50 INFO - GECKO(1548) | [Child 4250: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7f84b8764200) [pid = 4250] [serial = 1] [outer = 0]
[task 2021-07-27T17:21:50.964Z] 17:21:50 INFO - GECKO(1548) | [Child 4250: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7f849da27000) [pid = 4250] [serial = 2] [outer = 7f84b8764200]
[task 2021-07-27T17:21:50.988Z] 17:21:50 INFO - GECKO(1548) | [Child 4226, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4438
[task 2021-07-27T17:21:51.014Z] 17:21:51 INFO - GECKO(1548) | [Child 4202: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (7f75d5064200) [pid = 4202] [serial = 1] [outer = 0] [url = http://sub1.test1.example.org/browser/devtools/client/storage/test/storage-dfpi.html]
[task 2021-07-27T17:21:51.020Z] 17:21:51 INFO - GECKO(1548) | [Child 4202: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7f75ba327c00) [pid = 4202] [serial = 2] [outer = 0] [url = about:blank]
[task 2021-07-27T17:21:51.022Z] 17:21:51 INFO - GECKO(1548) | [Child 4202: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7f75ba32d000) [pid = 4202] [serial = 3] [outer = 0] [url = http://sub1.test1.example.org/browser/devtools/client/storage/test/storage-dfpi.html]
[task 2021-07-27T17:21:51.023Z] 17:21:51 INFO - GECKO(1548) | [Child 4202, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-07-27T17:21:51.097Z] 17:21:51 INFO - GECKO(1548) | [Child 4202, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4438
[task 2021-07-27T17:21:51.179Z] 17:21:51 INFO - GECKO(1548) | [Child 4250, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp:348
[task 2021-07-27T17:21:51.182Z] 17:21:51 INFO - GECKO(1548) | [Child 4250, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp:348
[task 2021-07-27T17:21:51.184Z] 17:21:51 INFO - GECKO(1548) | [Child 4250, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp:348
[task 2021-07-27T17:21:51.194Z] 17:21:51 INFO - GECKO(1548) | [Child 4250: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (7f849da2d000) [pid = 4250] [serial = 3] [outer = 7f84b8764200]
[task 2021-07-27T17:21:51.248Z] 17:21:51 INFO - GECKO(1548) | [Child 4250: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f849d58b000 == 2 [pid = 4250] [id = 1]
[task 2021-07-27T17:21:51.250Z] 17:21:51 INFO - GECKO(1548) | [Child 4250: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (7f84b8764740) [pid = 4250] [serial = 4] [outer = 0]
[task 2021-07-27T17:21:51.379Z] 17:21:51 INFO - GECKO(1548) | [Child 4250, Main Thread] WARNING: NS_ENSURE_TRUE(currentInner) failed: file /builds/worker/checkouts/gecko/dom/base/WindowDestroyedEvent.cpp:113
[task 2021-07-27T17:21:51.397Z] 17:21:51 INFO - GECKO(1548) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp_29hicl8.mozrunner/runtests_leaks_tab_pid4274.log
[task 2021-07-27T17:21:51.398Z] 17:21:51 INFO - GECKO(1548) | [4274, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:250
[task 2021-07-27T17:21:51.441Z] 17:21:51 INFO - GECKO(1548) | [Child 4274, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:619
[task 2021-07-27T17:21:51.636Z] 17:21:51 INFO - GECKO(1548) | [Child 4274: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f03e3522800 == 1 [pid = 4274] [id = 0]
[task 2021-07-27T17:21:51.637Z] 17:21:51 INFO - GECKO(1548) | [Child 4274: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7f03fe2643c0) [pid = 4274] [serial = 1] [outer = 0]
[task 2021-07-27T17:21:51.639Z] 17:21:51 INFO - GECKO(1548) | [Child 4274: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7f03e3527000) [pid = 4274] [serial = 2] [outer = 7f03fe2643c0]
[task 2021-07-27T17:21:51.680Z] 17:21:51 INFO - GECKO(1548) | [Child 4274: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (7f03e352d400) [pid = 4274] [serial = 3] [outer = 7f03fe2643c0]
[task 2021-07-27T17:21:51.866Z] 17:21:51 INFO - GECKO(1548) | [Parent 1548: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f2c9a8a9400 == 6 [pid = 1548] [id = 63]
[task 2021-07-27T17:21:51.868Z] 17:21:51 INFO - GECKO(1548) | [Parent 1548: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 18 (7f2ca5341040) [pid = 1548] [serial = 157] [outer = 0]
[task 2021-07-27T17:21:51.874Z] 17:21:51 INFO - GECKO(1548) | [Parent 1548: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 19 (7f2c9ad2a000) [pid = 1548] [serial = 158] [outer = 7f2ca5341040]
[task 2021-07-27T17:21:51.897Z] 17:21:51 INFO - GECKO(1548) | [Child 4250, Main Thread] WARNING: Scrolled rect smaller than scrollport?: file /builds/worker/checkouts/gecko/layout/generic/nsGfxScrollFrame.cpp:7051
[task 2021-07-27T17:21:51.925Z] 17:21:51 INFO - GECKO(1548) | [Parent 1548: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 20 (7f2c9ad6f800) [pid = 1548] [serial = 159] [outer = 7f2ca5341040]
[task 2021-07-27T17:21:52.095Z] 17:21:52 INFO - GECKO(1548) | [Parent 1548, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/dom/base/nsContentUtils.cpp:4018
[task 2021-07-27T17:21:52.894Z] 17:21:52 INFO - GECKO(1548) | [Parent 1548: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f2c9a8a7800 == 7 [pid = 1548] [id = 64]
[task 2021-07-27T17:21:52.894Z] 17:21:52 INFO - GECKO(1548) | [Parent 1548: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 21 (7f2c9a9b3e40) [pid = 1548] [serial = 160] [outer = 0]
[task 2021-07-27T17:21:52.896Z] 17:21:52 INFO - GECKO(1548) | [Parent 1548: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 22 (7f2c9ac0f400) [pid = 1548] [serial = 161] [outer = 7f2c9a9b3e40]
[task 2021-07-27T17:21:52.912Z] 17:21:52 INFO - GECKO(1548) | [Parent 1548, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/chrome/nsChromeRegistry.cpp:180
[task 2021-07-27T17:21:52.913Z] 17:21:52 INFO - GECKO(1548) | [Parent 1548, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/dom/security/nsCSPService.cpp:191
[task 2021-07-27T17:21:53.377Z] 17:21:53 INFO - GECKO(1548) | [Parent 1548, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/dom/base/nsContentUtils.cpp:4018
[task 2021-07-27T17:21:53.627Z] 17:21:53 INFO - GECKO(1548) | console.warn: "Failed to enumerate CacheStorage for host http://sub1.test1.example.com: SecurityError: The operation is insecure."
[task 2021-07-27T17:21:54.957Z] 17:21:54 INFO - GECKO(1548) | [Parent 1548: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 21 (7f2c9ad65800) [pid = 1548] [serial = 153] [outer = 0] [url = about:blank]
[task 2021-07-27T17:21:54.959Z] 17:21:54 INFO - GECKO(1548) | [Parent 1548: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 20 (7f2c9ad2c800) [pid = 1548] [serial = 149] [outer = 0] [url = about:devtools-toolbox]
[task 2021-07-27T17:21:54.960Z] 17:21:54 INFO - GECKO(1548) | [Parent 1548: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 19 (7f2c9ad2a800) [pid = 1548] [serial = 151] [outer = 0] [url = about:blank]
[task 2021-07-27T17:21:55.432Z] 17:21:55 INFO - TEST-INFO | started process screentopng
[task 2021-07-27T17:21:55.840Z] 17:21:55 INFO - TEST-INFO | screentopng: exit 0
[task 2021-07-27T17:21:55.841Z] 17:21:55 INFO - <snipped 13 output lines - if you need more context, please use SimpleTest.requestCompleteLog() in your test>
[task 2021-07-27T17:21:55.841Z] 17:21:55 INFO - Buffered messages logged at 17:21:39[task 2021-07-27T17:21:55.843Z] 17:21:55 INFO - Console message: [JavaScript Warning: "Partitioned cookie or storage access was provided to “http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html” because it is loaded in the third-party context and dynamic state partitioning is enabled."]
[task 2021-07-27T17:21:55.844Z] 17:21:55 INFO - Console message: [JavaScript Warning: "Partitioned cookie or storage access was provided to “http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html” because it is loaded in the third-party context and dynamic state partitioning is enabled."]
[task 2021-07-27T17:21:55.853Z] 17:21:55 INFO - Console message: [JavaScript Warning: "Partitioned cookie or storage access was provided to “http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html” because it is loaded in the third-party context and dynamic state partitioning is enabled."]
[task 2021-07-27T17:21:55.853Z] 17:21:55 INFO - Console message: [JavaScript Warning: "Partitioned cookie or storage access was provided to “http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html” because it is loaded in the third-party context and dynamic state partitioning is enabled."]
[task 2021-07-27T17:21:55.853Z] 17:21:55 INFO - Console message: [JavaScript Warning: "Partitioned cookie or storage access was provided to “http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html” because it is loaded in the third-party context and dynamic state partitioning is enabled."]
[task 2021-07-27T17:21:55.854Z] 17:21:55 INFO - Making sure that the toolbox's frame is focused
[task 2021-07-27T17:21:55.854Z] 17:21:55 INFO - TEST-PASS | devtools/client/storage/test/browser_storage_dfpi.js | check origin: http://sub1.test1.example.org -
[task 2021-07-27T17:21:55.855Z] 17:21:55 INFO - TEST-PASS | devtools/client/storage/test/browser_storage_dfpi.js | check origin: moz-extension://c4f9251b-6595-446c-98a6-cb98b7a8add6^userContextId=4294967295 -
[task 2021-07-27T17:21:55.856Z] 17:21:55 INFO - TEST-PASS | devtools/client/storage/test/browser_storage_dfpi.js | http://sub1.test1.example.org is added -
[task 2021-07-27T17:21:55.856Z] 17:21:55 INFO - Leaving test bound
[task 2021-07-27T17:21:55.857Z] 17:21:55 INFO - Entering test bound test_partitioned_cookies
[task 2021-07-27T17:21:55.857Z] 17:21:55 INFO - Buffered messages logged at 17:21:41
[task 2021-07-27T17:21:55.857Z] 17:21:55 INFO - Open the test url in a new tab and add storage entries *before* opening the storage panel.
[task 2021-07-27T17:21:55.857Z] 17:21:55 INFO - Buffered messages logged at 17:21:42
[task 2021-07-27T17:21:55.858Z] 17:21:55 INFO - Console message: [JavaScript Warning: "Cookie “contextA” will be soon rejected because it has the “SameSite” attribute set to “None” or an invalid value, without the “secure” attribute. To know more about the “SameSite“ attribute, read https://developer.mozilla.org/docs/Web/HTTP/Headers/Set-Cookie/SameSite" {file: "chrome://mochitests/content/browser/devtools/client/storage/test/browser_storage_dfpi.js" line: 64}]
[task 2021-07-27T17:21:55.865Z] 17:21:55 INFO - Console message: [JavaScript Warning: "Cookie “contextA” will be soon rejected because it has the “SameSite” attribute set to “None” or an invalid value, without the “secure” attribute. To know more about the “SameSite“ attribute, read https://developer.mozilla.org/docs/Web/HTTP/Headers/Set-Cookie/SameSite" {file: "chrome://mochitests/content/browser/devtools/client/storage/test/browser_storage_dfpi.js" line: 64}]
[task 2021-07-27T17:21:55.866Z] 17:21:55 INFO - Adding a new tab with URL: http://sub1.test1.example.org/browser/devtools/client/storage/test/storage-dfpi.html
[task 2021-07-27T17:21:55.866Z] 17:21:55 INFO - Buffered messages logged at 17:21:43
[task 2021-07-27T17:21:55.867Z] 17:21:55 INFO - Console message: [JavaScript Warning: "Partitioned cookie or storage access was provided to “http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html” because it is loaded in the third-party context and dynamic state partitioning is enabled."]
[task 2021-07-27T17:21:55.868Z] 17:21:55 INFO - Buffered messages logged at 17:21:44
[task 2021-07-27T17:21:55.869Z] 17:21:55 INFO - Tab added and finished loading
[task 2021-07-27T17:21:55.870Z] 17:21:55 INFO - Found a window: complete
[task 2021-07-27T17:21:55.870Z] 17:21:55 INFO - Found a window: complete
[task 2021-07-27T17:21:55.871Z] 17:21:55 INFO - Opening the storage inspector
[task 2021-07-27T17:21:55.872Z] 17:21:55 INFO - Opening the toolbox
[task 2021-07-27T17:21:55.873Z] 17:21:55 INFO - Buffered messages logged at 17:21:45
[task 2021-07-27T17:21:55.876Z] 17:21:55 INFO - Console message: Invalid chrome URI (need path): chrome://devtools/
[task 2021-07-27T17:21:55.877Z] 17:21:55 INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 511}]
[task 2021-07-27T17:21:55.878Z] 17:21:55 INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 511}]
[task 2021-07-27T17:21:55.879Z] 17:21:55 INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 75}]
[task 2021-07-27T17:21:55.879Z] 17:21:55 INFO - Buffered messages logged at 17:21:46
[task 2021-07-27T17:21:55.881Z] 17:21:55 INFO - Console message: [JavaScript Warning: "Partitioned cookie or storage access was provided to “http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html” because it is loaded in the third-party context and dynamic state partitioning is enabled."]
[task 2021-07-27T17:21:55.882Z] 17:21:55 INFO - Console message: [JavaScript Warning: "Partitioned cookie or storage access was provided to “http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html” because it is loaded in the third-party context and dynamic state partitioning is enabled."]
[task 2021-07-27T17:21:55.883Z] 17:21:55 INFO - Console message: [JavaScript Warning: "Partitioned cookie or storage access was provided to “http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html” because it is loaded in the third-party context and dynamic state partitioning is enabled."]
[task 2021-07-27T17:21:55.885Z] 17:21:55 INFO - Console message: [JavaScript Warning: "Partitioned cookie or storage access was provided to “http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html” because it is loaded in the third-party context and dynamic state partitioning is enabled."]
[task 2021-07-27T17:21:55.886Z] 17:21:55 INFO - Console message: [JavaScript Warning: "Partitioned cookie or storage access was provided to “http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html” because it is loaded in the third-party context and dynamic state partitioning is enabled."]
[task 2021-07-27T17:21:55.887Z] 17:21:55 INFO - Console message: [JavaScript Warning: "Partitioned cookie or storage access was provided to “http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html” because it is loaded in the third-party context and dynamic state partitioning is enabled."]
[task 2021-07-27T17:21:55.888Z] 17:21:55 INFO - Making sure that the toolbox's frame is focused
[task 2021-07-27T17:21:55.888Z] 17:21:55 INFO - check that both hosts appear in the storage tree[task 2021-07-27T17:21:55.889Z] 17:21:55 INFO - TEST-PASS | devtools/client/storage/test/browser_storage_dfpi.js | cookies > http://sub1.test1.example.org is in the tree -
[task 2021-07-27T17:21:55.890Z] 17:21:55 INFO - TEST-PASS | devtools/client/storage/test/browser_storage_dfpi.js | cookies > http://sub1.test1.example.com is in the tree -
[task 2021-07-27T17:21:55.891Z] 17:21:55 INFO - check that items for both first and third party host have the initial storage entries
[task 2021-07-27T17:21:55.892Z] 17:21:55 INFO - "cookies,http://sub1.test1.example.org" is already selected, returning.
[task 2021-07-27T17:21:55.892Z] 17:21:55 INFO - found first
[task 2021-07-27T17:21:55.893Z] 17:21:55 INFO - TEST-PASS | devtools/client/storage/test/browser_storage_dfpi.js | Table row has an entry for: contextA with value: first -
[task 2021-07-27T17:21:55.894Z] 17:21:55 INFO - Selecting "cookies,http://sub1.test1.example.com".
[task 2021-07-27T17:21:55.895Z] 17:21:55 INFO - found third
[task 2021-07-27T17:21:55.896Z] 17:21:55 INFO - TEST-PASS | devtools/client/storage/test/browser_storage_dfpi.js | Table row has an entry for: contextA with value: third -
[task 2021-07-27T17:21:55.896Z] 17:21:55 INFO - Add more entries while the storage panel is open
[task 2021-07-27T17:21:55.898Z] 17:21:55 INFO - Console message: [JavaScript Warning: "Cookie “contextB” will be soon rejected because it has the “SameSite” attribute set to “None” or an invalid value, without the “secure” attribute. To know more about the “SameSite“ attribute, read https://developer.mozilla.org/docs/Web/HTTP/Headers/Set-Cookie/SameSite" {file: "chrome://mochitests/content/browser/devtools/client/storage/test/browser_storage_dfpi.js" line: 64}]
[task 2021-07-27T17:21:55.899Z] 17:21:55 INFO - Console message: [JavaScript Warning: "Cookie “contextB” will be soon rejected because it has the “SameSite” attribute set to “None” or an invalid value, without the “secure” attribute. To know more about the “SameSite“ attribute, read https://developer.mozilla.org/docs/Web/HTTP/Headers/Set-Cookie/SameSite" {file: "chrome://mochitests/content/browser/devtools/client/storage/test/browser_storage_dfpi.js" line: 64}]
[task 2021-07-27T17:21:55.900Z] 17:21:55 INFO - Console message: [JavaScript Warning: "Partitioned cookie or storage access was provided to “http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html” because it is loaded in the third-party context and dynamic state partitioning is enabled."]
[task 2021-07-27T17:21:55.901Z] 17:21:55 INFO - Buffered messages logged at 17:21:47
[task 2021-07-27T17:21:55.902Z] 17:21:55 INFO - check that both hosts appear in the storage tree
[task 2021-07-27T17:21:55.903Z] 17:21:55 INFO - TEST-PASS | devtools/client/storage/test/browser_storage_dfpi.js | cookies > http://sub1.test1.example.org is in the tree -
[task 2021-07-27T17:21:55.904Z] 17:21:55 INFO - TEST-PASS | devtools/client/storage/test/browser_storage_dfpi.js | cookies > http://sub1.test1.example.com is in the tree -
[task 2021-07-27T17:21:55.905Z] 17:21:55 INFO - check that items for both first and third party host have the updated storage entries
[task 2021-07-27T17:21:55.905Z] 17:21:55 INFO - Selecting "cookies,http://sub1.test1.example.org".
[task 2021-07-27T17:21:55.906Z] 17:21:55 INFO - found first
[task 2021-07-27T17:21:55.907Z] 17:21:55 INFO - TEST-PASS | devtools/client/storage/test/browser_storage_dfpi.js | Table row has an entry for: contextA with value: first -
[task 2021-07-27T17:21:55.908Z] 17:21:55 INFO - found first
[task 2021-07-27T17:21:55.909Z] 17:21:55 INFO - TEST-PASS | devtools/client/storage/test/browser_storage_dfpi.js | Table row has an entry for: contextB with value: first -
[task 2021-07-27T17:21:55.910Z] 17:21:55 INFO - Selecting "cookies,http://sub1.test1.example.com".
[task 2021-07-27T17:21:55.910Z] 17:21:55 INFO - found third
[task 2021-07-27T17:21:55.911Z] 17:21:55 INFO - TEST-PASS | devtools/client/storage/test/browser_storage_dfpi.js | Table row has an entry for: contextA with value: third -
[task 2021-07-27T17:21:55.912Z] 17:21:55 INFO - found third
[task 2021-07-27T17:21:55.913Z] 17:21:55 INFO - TEST-PASS | devtools/client/storage/test/browser_storage_dfpi.js | Table row has an entry for: contextB with value: third -
[task 2021-07-27T17:21:55.914Z] 17:21:55 INFO - Leaving test bound test_partitioned_cookies
[task 2021-07-27T17:21:55.914Z] 17:21:55 INFO - Entering test bound test_partitioned_localStorage
[task 2021-07-27T17:21:55.915Z] 17:21:55 INFO - Buffered messages logged at 17:21:48
[task 2021-07-27T17:21:55.916Z] 17:21:55 INFO - Open the test url in a new tab and add storage entries *before* opening the storage panel.
[task 2021-07-27T17:21:55.917Z] 17:21:55 INFO - Buffered messages logged at 17:21:50[task 2021-07-27T17:21:55.918Z] 17:21:55 INFO - Adding a new tab with URL: http://sub1.test1.example.org/browser/devtools/client/storage/test/storage-dfpi.html
[task 2021-07-27T17:21:55.919Z] 17:21:55 INFO - Console message: [JavaScript Warning: "Partitioned cookie or storage access was provided to “http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html” because it is loaded in the third-party context and dynamic state partitioning is enabled."]
[task 2021-07-27T17:21:55.920Z] 17:21:55 INFO - Console message: [JavaScript Warning: "Partitioned cookie or storage access was provided to “http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html” because it is loaded in the third-party context and dynamic state partitioning is enabled."]
[task 2021-07-27T17:21:55.921Z] 17:21:55 INFO - Console message: [JavaScript Warning: "Partitioned cookie or storage access was provided to “http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html” because it is loaded in the third-party context and dynamic state partitioning is enabled."]
[task 2021-07-27T17:21:55.922Z] 17:21:55 INFO - Console message: [JavaScript Warning: "Partitioned cookie or storage access was provided to “http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html” because it is loaded in the third-party context and dynamic state partitioning is enabled."]
[task 2021-07-27T17:21:55.923Z] 17:21:55 INFO - Buffered messages logged at 17:21:51
[task 2021-07-27T17:21:55.923Z] 17:21:55 INFO - Tab added and finished loading
[task 2021-07-27T17:21:55.924Z] 17:21:55 INFO - Found a window: complete
[task 2021-07-27T17:21:55.925Z] 17:21:55 INFO - Found a window: complete
[task 2021-07-27T17:21:55.926Z] 17:21:55 INFO - Opening the storage inspector
[task 2021-07-27T17:21:55.926Z] 17:21:55 INFO - Opening the toolbox
[task 2021-07-27T17:21:55.927Z] 17:21:55 INFO - Buffered messages logged at 17:21:53
[task 2021-07-27T17:21:55.928Z] 17:21:55 INFO - Console message: Invalid chrome URI (need path): chrome://devtools/
[task 2021-07-27T17:21:55.929Z] 17:21:55 INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 511}]
[task 2021-07-27T17:21:55.930Z] 17:21:55 INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 511}]
[task 2021-07-27T17:21:55.931Z] 17:21:55 INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 75}]
[task 2021-07-27T17:21:55.932Z] 17:21:55 INFO - Console message: [JavaScript Warning: "Partitioned cookie or storage access was provided to “http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html” because it is loaded in the third-party context and dynamic state partitioning is enabled."]
[task 2021-07-27T17:21:55.933Z] 17:21:55 INFO - Console message: [JavaScript Warning: "Partitioned cookie or storage access was provided to “http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html” because it is loaded in the third-party context and dynamic state partitioning is enabled."]
[task 2021-07-27T17:21:55.934Z] 17:21:55 INFO - Console message: [JavaScript Warning: "Partitioned cookie or storage access was provided to “http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html” because it is loaded in the third-party context and dynamic state partitioning is enabled."]
[task 2021-07-27T17:21:55.935Z] 17:21:55 INFO - Console message: [JavaScript Warning: "Partitioned cookie or storage access was provided to “http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html” because it is loaded in the third-party context and dynamic state partitioning is enabled."]
[task 2021-07-27T17:21:55.936Z] 17:21:55 INFO - Console message: [JavaScript Warning: "Partitioned cookie or storage access was provided to “http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html” because it is loaded in the third-party context and dynamic state partitioning is enabled."]
[task 2021-07-27T17:21:55.937Z] 17:21:55 INFO - Console message: [JavaScript Warning: "Partitioned cookie or storage access was provided to “http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html” because it is loaded in the third-party context and dynamic state partitioning is enabled."]
[task 2021-07-27T17:21:55.938Z] 17:21:55 INFO - Buffered messages logged at 17:21:54
[task 2021-07-27T17:21:55.939Z] 17:21:55 INFO - Making sure that the toolbox's frame is focused
[task 2021-07-27T17:21:55.939Z] 17:21:55 INFO - check that both hosts appear in the storage tree
[task 2021-07-27T17:21:55.940Z] 17:21:55 INFO - TEST-PASS | devtools/client/storage/test/browser_storage_dfpi.js | localStorage > http://sub1.test1.example.org is in the tree -
[task 2021-07-27T17:21:55.941Z] 17:21:55 INFO - TEST-PASS | devtools/client/storage/test/browser_storage_dfpi.js | localStorage > http://sub1.test1.example.com is in the tree -
[task 2021-07-27T17:21:55.942Z] 17:21:55 INFO - check that items for both first and third party host have the initial storage entries
[task 2021-07-27T17:21:55.943Z] 17:21:55 INFO - Selecting "localStorage,http://sub1.test1.example.org".
[task 2021-07-27T17:21:55.944Z] 17:21:55 INFO - TEST-PASS | devtools/client/storage/test/browser_storage_dfpi.js | Table row has an entry for: contextA with value: first -
[task 2021-07-27T17:21:55.945Z] 17:21:55 INFO - Selecting "localStorage,http://sub1.test1.example.com".
[task 2021-07-27T17:21:55.945Z] 17:21:55 INFO - TEST-PASS | devtools/client/storage/test/browser_storage_dfpi.js | Table row has an entry for: contextA with value: third -
[task 2021-07-27T17:21:55.946Z] 17:21:55 INFO - Add more entries while the storage panel is open
[task 2021-07-27T17:21:55.947Z] 17:21:55 INFO - Console message: [JavaScript Warning: "Partitioned cookie or storage access was provided to “http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html” because it is loaded in the third-party context and dynamic state partitioning is enabled."]
[task 2021-07-27T17:21:55.948Z] 17:21:55 INFO - Console message: [JavaScript Warning: "Partitioned cookie or storage access was provided to “http://sub1.test1.example.com/browser/devtools/client/storage/test/storage-blank.html” because it is loaded in the third-party context and dynamic state partitioning is enabled."]
[task 2021-07-27T17:21:55.948Z] 17:21:55 INFO - Buffered messages logged at 17:21:55
[task 2021-07-27T17:21:55.949Z] 17:21:55 INFO - check that both hosts appear in the storage tree
[task 2021-07-27T17:21:55.949Z] 17:21:55 INFO - TEST-PASS | devtools/client/storage/test/browser_storage_dfpi.js | localStorage > http://sub1.test1.example.org is in the tree -
[task 2021-07-27T17:21:55.950Z] 17:21:55 INFO - TEST-PASS | devtools/client/storage/test/browser_storage_dfpi.js | localStorage > http://sub1.test1.example.com is in the tree -
[task 2021-07-27T17:21:55.950Z] 17:21:55 INFO - check that items for both first and third party host have the updated storage entries
[task 2021-07-27T17:21:55.951Z] 17:21:55 INFO - Selecting "localStorage,http://sub1.test1.example.org".
[task 2021-07-27T17:21:55.951Z] 17:21:55 INFO - Buffered messages finished
[task 2021-07-27T17:21:55.953Z] 17:21:55 INFO - TEST-UNEXPECTED-FAIL | devtools/client/storage/test/browser_storage_dfpi.js | Table row has an entry for: contextA with value: first -
Description
•