1823801 - Frequent toolkit/components/pictureinpicture/tests/browser_urlbar_toggle.js | single tracking bug

Status: RESOLVED FIXED

(Toolkit :: Picture-in-Picture, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=409678545&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/KGABeobZQeutb6VNX67B7g/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/KGABeobZQeutb6VNX67B7g/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

---

[task 2023-03-21T21:22:06.438Z] 21:22:06     INFO - TEST-START | toolkit/components/pictureinpicture/tests/browser_urlbar_toggle.js
[task 2023-03-21T21:22:06.474Z] 21:22:06     INFO - GECKO(15442) | [Child 15626: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f3bfd138000 == 1 [pid = 15626] [id = 0]
[task 2023-03-21T21:22:06.474Z] 21:22:06     INFO - GECKO(15442) | [Child 15626: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7f3c0235f470) [pid = 15626] [serial = 1] [outer = 0]
[task 2023-03-21T21:22:06.474Z] 21:22:06     INFO - GECKO(15442) | [Child 15626: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7f3bfd138800) [pid = 15626] [serial = 2] [outer = 7f3c0235f470]
[task 2023-03-21T21:22:06.494Z] 21:22:06     INFO - GECKO(15442) | [Child 15541: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f6a2fb25800 == 1 [pid = 15541] [id = 1] [url = about:blank]
[task 2023-03-21T21:22:06.690Z] 21:22:06     INFO - GECKO(15442) | [Child 15541: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (7f6a2fb23c00) [pid = 15541] [serial = 4] [outer = 0] [url = about:blank]
[task 2023-03-21T21:22:06.690Z] 21:22:06     INFO - GECKO(15442) | [Child 15541: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (7f6a2fb1a800) [pid = 15541] [serial = 3] [outer = 0] [url = about:blank]
[task 2023-03-21T21:22:06.690Z] 21:22:06     INFO - GECKO(15442) | [Child 15541: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (7f6a2fb19800) [pid = 15541] [serial = 2] [outer = 0] [url = about:blank]
[task 2023-03-21T21:22:06.745Z] 21:22:06     INFO - GECKO(15442) | [Child 15626: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (7f3bfd13ac00) [pid = 15626] [serial = 3] [outer = 7f3c0235f470]
[task 2023-03-21T21:22:06.784Z] 21:22:06     INFO - GECKO(15442) | [Child 15541: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (7f6a34e81090) [pid = 15541] [serial = 5] [outer = 0] [url = about:blank]
[task 2023-03-21T21:22:06.931Z] 21:22:06     INFO - GECKO(15442) | [Child 15626, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, NS_ERROR_INVALID_ARG) failed with result 0x80520012 (NS_ERROR_FILE_NOT_FOUND): file /builds/worker/checkouts/gecko/intl/l10n/L10nRegistry.cpp:384
[task 2023-03-21T21:22:06.985Z] 21:22:06     INFO - GECKO(15442) | [Child 15626: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f3bfd142000 == 2 [pid = 15626] [id = 1]
[task 2023-03-21T21:22:06.988Z] 21:22:06     INFO - GECKO(15442) | [Child 15626: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (7f3bfc482f10) [pid = 15626] [serial = 4] [outer = 0]
[task 2023-03-21T21:22:07.006Z] 21:22:07     INFO - GECKO(15442) | [Child 15626: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (7f3bfd142c00) [pid = 15626] [serial = 5] [outer = 7f3bfc482f10]
[task 2023-03-21T21:22:07.492Z] 21:22:07     INFO - GECKO(15442) | [RDD 15737, MediaPDecoder #1] WARNING: Unable to retrieve duration from map: file /builds/worker/checkouts/gecko/dom/media/platforms/ffmpeg/FFmpegVideoDecoder.cpp:1074
[task 2023-03-21T21:22:07.632Z] 21:22:07     INFO - GECKO(15442) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpaa07tdvy.mozrunner/runtests_leaks_tab_pid15785.log
[task 2023-03-21T21:22:07.632Z] 21:22:07     INFO - GECKO(15442) | [15785, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:345
[task 2023-03-21T21:22:07.676Z] 21:22:07     INFO - GECKO(15442) | [Child 15785, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-03-21T21:22:07.772Z] 21:22:07     INFO - GECKO(15442) | [Child 15785, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:715
[task 2023-03-21T21:22:07.798Z] 21:22:07     INFO - GECKO(15442) | [Child 15785, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-03-21T21:22:07.798Z] 21:22:07     INFO - GECKO(15442) | [Child 15785, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-03-21T21:22:07.834Z] 21:22:07     INFO - GECKO(15442) | [Child 15785, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-03-21T21:22:08.294Z] 21:22:08     INFO - GECKO(15442) | [Parent 15442: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 13 (7f76879c8800) [pid = 15442] [serial = 4] [outer = 0] [url = about:blank]
[task 2023-03-21T21:22:08.296Z] 21:22:08     INFO - GECKO(15442) | [Parent 15442: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f7653aa6000 == 5 [pid = 15442] [id = 5] [url = chrome://global/content/pictureinpicture/player.xhtml]
[task 2023-03-21T21:22:08.302Z] 21:22:08     INFO - GECKO(15442) | [Parent 15442: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 12 (7f7665145800) [pid = 15442] [serial = 9] [outer = 0] [url = about:blank]
[task 2023-03-21T21:22:09.100Z] 21:22:09     INFO - GECKO(15442) | [Child 15514: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (7f5ece53f400) [pid = 15514] [serial = 12] [outer = 0] [url = moz-extension://7e49ed36-e280-4106-a3cc-7c6212dec29c/_generated_background_page.html]
[task 2023-03-21T21:22:09.766Z] 21:22:09     INFO - GECKO(15442) | [Child 15600: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f69c6a3c800 == 0 [pid = 15600] [id = 0] [url = about:blank]
[task 2023-03-21T21:22:09.777Z] 21:22:09     INFO - GECKO(15442) | [Child 15600: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7f69cbcb1470) [pid = 15600] [serial = 1] [outer = 0] [url = about:blank]
[task 2023-03-21T21:22:10.362Z] 21:22:10     INFO - GECKO(15442) | [Child 15649: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (7f2072f25c00) [pid = 15649] [serial = 2] [outer = 0] [url = about:blank]
[task 2023-03-21T21:22:10.764Z] 21:22:10     INFO - GECKO(15442) | [Child 15541: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (7f6a2fb24c00) [pid = 15541] [serial = 7] [outer = 0] [url = about:blank]
[task 2023-03-21T21:22:10.765Z] 21:22:10     INFO - GECKO(15442) | [Child 15541: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (7f6a2fb25c00) [pid = 15541] [serial = 6] [outer = 0] [url = about:blank]
[task 2023-03-21T21:22:12.837Z] 21:22:12     INFO - GECKO(15442) | [Parent 15442: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (7f7653b42c30) [pid = 15442] [serial = 13] [outer = 0] [url = chrome://global/content/pictureinpicture/player.xhtml]
[task 2023-03-21T21:22:13.855Z] 21:22:13     INFO - GECKO(15442) | [Child 15600: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7f69c6a3d000) [pid = 15600] [serial = 2] [outer = 0] [url = about:blank]
[task 2023-03-21T21:22:16.813Z] 21:22:16     INFO - GECKO(15442) | [Parent 15442: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (7f7653aa6800) [pid = 15442] [serial = 14] [outer = 0] [url = about:blank]
[task 2023-03-21T21:22:18.930Z] 21:22:18     INFO - GECKO(15442) | [Child 15626: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (7f3bfd138800) [pid = 15626] [serial = 2] [outer = 0] [url = about:blank]
[task 2023-03-21T21:22:19.440Z] 21:22:19     INFO - GECKO(15442) | [WARN  rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2023-03-21T21:22:19.475Z] 21:22:19     INFO - GECKO(15442) | console.error: (new Error("Polling for changes failed: Unexpected content-type \"text/plain;charset=US-ASCII\".", "resource://services-settings/remote-settings.sys.mjs", 325))
[task 2023-03-21T21:22:26.502Z] 21:22:26     INFO - GECKO(15442) | ALSA lib seq_hw.c:466:(snd_seq_hw_open) open /dev/snd/seq failed: No such file or directory
[task 2023-03-21T21:23:36.515Z] 21:23:36     INFO - TEST-INFO | started process screentopng
[task 2023-03-21T21:23:36.935Z] 21:23:36     INFO - TEST-INFO | screentopng: exit 0
[task 2023-03-21T21:23:36.936Z] 21:23:36     INFO - Buffered messages logged at 21:22:06
[task 2023-03-21T21:23:36.937Z] 21:23:36     INFO - Entering test bound test_urlbar_toggle_multiple_contexts
[task 2023-03-21T21:23:36.938Z] 21:23:36     INFO - Buffered messages logged at 21:22:07
[task 2023-03-21T21:23:36.938Z] 21:23:36     INFO - Waiting for videos to be ready
[task 2023-03-21T21:23:36.939Z] 21:23:36     INFO - Waiting for videos to be ready
[task 2023-03-21T21:23:36.940Z] 21:23:36     INFO - Waiting for 'canplaythrough' for ''
[task 2023-03-21T21:23:36.940Z] 21:23:36     INFO - Buffered messages finished
[task 2023-03-21T21:23:36.941Z] 21:23:36     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/pictureinpicture/tests/browser_urlbar_toggle.js | Test timed out - 
[task 2023-03-21T21:23:36.942Z] 21:23:36     INFO - GECKO(15442) | MEMORY STAT | vsize 3489MB | residentFast 546MB | heapAllocated 251MB
[task 2023-03-21T21:23:36.943Z] 21:23:36     INFO - TEST-OK | toolkit/components/pictureinpicture/tests/browser_urlbar_toggle.js | took 90086ms
[task 2023-03-21T21:23:36.943Z] 21:23:36     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-03-21T21:23:36.951Z] 21:23:36     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/pictureinpicture/tests/browser_urlbar_toggle.js | Found a tab after previous test timed out: http://example.com/browser/toolkit/components/pictureinpicture/tests/test-page-multiple-contexts.html - 
[task 2023-03-21T21:23:36.952Z] 21:23:36     INFO - GECKO(15442) | [Child 15600: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f69c6a3c400 == 1 [pid = 15600] [id = 1]
[task 2023-03-21T21:23:36.952Z] 21:23:36     INFO - GECKO(15442) | [Child 15600: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7f69cbcb1470) [pid = 15600] [serial = 3] [outer = 0]
[task 2023-03-21T21:23:36.952Z] 21:23:36     INFO - GECKO(15442) | [Child 15600: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7f69c6a3d000) [pid = 15600] [serial = 4] [outer = 7f69cbcb1470]
[task 2023-03-21T21:23:36.957Z] 21:23:36     INFO - checking window state
[task 2023-03-21T21:23:36.958Z] 21:23:36     INFO - GECKO(15442) | [Child 15626: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f3bfd142000 == 1 [pid = 15626] [id = 1] [url = http://example.com/browser/toolkit/components/pictureinpicture/tests/test-video.mp4]
[task 2023-03-21T21:23:36.964Z] 21:23:36     INFO - GECKO(15442) | [Parent 15442: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (7f7653a9cc00) [pid = 15442] [serial = 15] [outer = 7f76674bd280]
[task 2023-03-21T21:23:37.013Z] 21:23:37     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-03-21T21:23:37.013Z] 21:23:37     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/pictureinpicture/tests/browser_urlbar_toggle.js | Uncaught exception received from previously timed out test bound test_urlbar_toggle_multiple_contexts - AbortError: Actor 'SpecialPowers' destroyed before query 'Spawn' was resolved
[task 2023-03-21T21:23:37.216Z] 21:23:37     INFO - GECKO(15442) | [Child 15541: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f6a2fb27400 == 0 [pid = 15541] [id = 2] [url = about:blank]
[task 2023-03-21T21:23:37.287Z] 21:23:37     INFO - GECKO(15442) | [Child 15649: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f2072f25400 == 0 [pid = 15649] [id = 0] [url = about:newtab]
[task 2023-03-21T21:23:37.382Z] 21:23:37     INFO - GECKO(15442) | [Child 15649: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7f207815f090) [pid = 15649] [serial = 1] [outer = 0] [url = about:newtab]
[task 2023-03-21T21:23:37.384Z] 21:23:37     INFO - GECKO(15442) | [Child 15649: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7f2072f28400) [pid = 15649] [serial = 3] [outer = 0] [url = about:newtab]
[task 2023-03-21T21:23:38.022Z] 21:23:38     INFO - Console message: BrowserTestUtils.withNewTab: Tab was already closed before removeTab would have been called
[task 2023-03-21T21:23:38.044Z] 21:23:38     INFO - GECKO(15442) | [Child 15541: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7f6a2fb27800) [pid = 15541] [serial = 9] [outer = 0] [url = about:blank]
[task 2023-03-21T21:23:38.045Z] 21:23:38     INFO - GECKO(15442) | [Child 15541: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7f6a34e81e20) [pid = 15541] [serial = 8] [outer = 0] [url = about:blank]
[task 2023-03-21T21:23:38.080Z] 21:23:38     INFO - GECKO(15442) | JavaScript error: chrome://mochikit/content/browser-test.js, line 1102: TypeError: can't access property "shift", currentScope.__tasks is null
[task 2023-03-21T21:23:38.104Z] 21:23:38     INFO - GECKO(15442) | [Parent 15442, Compositor] WARNING: IPC Connection Error: [Parent][PCompositorManagerParent] RunMessage(msgname=PCompositorBridge::Msg___delete__) Channel closing: too late to send/recv, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1927
[task 2023-03-21T21:23:38.118Z] 21:23:38     INFO - GECKO(15442) | [Parent 15442, Compositor] WARNING: IPC Connection Error: [Parent][PCompositorManagerParent] RunMessage(msgname=PCompositorBridge::Msg___delete__) Channel closing: too late to send/recv, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1927
[task 2023-03-21T21:23:38.125Z] 21:23:38     INFO - GECKO(15442) | [Parent 15442, Compositor] WARNING: IPC Connection Error: [Parent][PCompositorManagerParent] RunMessage(msgname=PCompositorBridge::Msg___delete__) Channel closing: too late to send/recv, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1927
[task 2023-03-21T21:23:38.170Z] 21:23:38     INFO - GECKO(15442) | [Child 15698, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3396
[task 2023-03-21T21:23:38.180Z] 21:23:38     INFO - GECKO(15442) | [Child 15712, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3396
[task 2023-03-21T21:23:38.183Z] 21:23:38     INFO - GECKO(15442) | [Child 15785, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3396
[task 2023-03-21T21:23:38.188Z] 21:23:38     INFO - GECKO(15442) | [Child 15785, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-03-21T21:23:38.190Z] 21:23:38     INFO - GECKO(15442) | [Child 15698, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-03-21T21:23:38.192Z] 21:23:38     INFO - GECKO(15442) | [Child 15712, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-03-21T21:23:38.202Z] 21:23:38     INFO - GECKO(15442) | [Child 15785, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4689
[task 2023-03-21T21:23:38.204Z] 21:23:38     INFO - GECKO(15442) | [Child 15712, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4689
[task 2023-03-21T21:23:38.220Z] 21:23:38     INFO - GECKO(15442) | [Child 15698, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4689
[task 2023-03-21T21:23:38.244Z] 21:23:38     INFO - GECKO(15442) | [Child 15626: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f3bfd138000 == 0 [pid = 15626] [id = 0] [url = http://example.com/browser/toolkit/components/pictureinpicture/tests/test-page-multiple-contexts.html]
[task 2023-03-21T21:23:38.351Z] 21:23:38     INFO - GECKO(15442) | [Child 15626: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (7f3bfc482f10) [pid = 15626] [serial = 4] [outer = 0] [url = http://example.com/browser/toolkit/components/pictureinpicture/tests/test-video.mp4]
[task 2023-03-21T21:23:38.353Z] 21:23:38     INFO - GECKO(15442) | [Child 15626: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (7f3c0235f470) [pid = 15626] [serial = 1] [outer = 0] [url = http://example.com/browser/toolkit/components/pictureinpicture/tests/test-page-multiple-contexts.html]
[task 2023-03-21T21:23:38.355Z] 21:23:38     INFO - GECKO(15442) | [Child 15626: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7f3bfd13ac00) [pid = 15626] [serial = 3] [outer = 0] [url = http://example.com/browser/toolkit/components/pictureinpicture/tests/test-page-multiple-contexts.html]
[task 2023-03-21T21:23:38.355Z] 21:23:38     INFO - GECKO(15442) | [Child 15626: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7f3bfd142c00) [pid = 15626] [serial = 5] [outer = 0] [url = about:blank]
[task 2023-03-21T21:23:38.396Z] 21:23:38     INFO - GECKO(15442) | [Parent 15442, Compositor] WARNING: IPC Connection Error: [Parent][PCompositorManagerParent] RunMessage(msgname=PCompositorBridge::Msg___delete__) Channel closing: too late to send/recv, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1927
[task 2023-03-21T21:23:38.450Z] 21:23:38     INFO - GECKO(15442) | [Child 15626, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3396
[task 2023-03-21T21:23:38.455Z] 21:23:38     INFO - GECKO(15442) | [Child 15626, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-03-21T21:23:38.476Z] 21:23:38     INFO - GECKO(15442) | [Child 15626, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4689
[task 2023-03-21T21:23:39.118Z] 21:23:39     INFO - GECKO(15442) | Completed ShutdownLeaks collections in process 15541
[task 2023-03-21T21:23:39.207Z] 21:23:39     INFO - GECKO(15442) | Completed ShutdownLeaks collections in process 15600
[task 2023-03-21T21:23:39.295Z] 21:23:39     INFO - GECKO(15442) | Completed ShutdownLeaks collections in process 15649
[task 2023-03-21T21:23:39.308Z] 21:23:39     INFO - GECKO(15442) | [Parent 15442: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (7f76675a9c00) [pid = 15442] [serial = 7] [outer = 0] [url = about:blank]
[task 2023-03-21T21:23:39.648Z] 21:23:39     INFO - Console message: [JavaScript Error: "TypeError: can't access property "shift", currentScope.__tasks is null" {file: "chrome://mochikit/content/browser-test.js" line: 1102}]
[task 2023-03-21T21:23:39.648Z] 21:23:39     INFO - _runTaskBasedTest@chrome://mochikit/content/browser-test.js:1114:3
[task 2023-03-21T21:23:39.648Z] 21:23:39     INFO - async*Tester_execTest@chrome://mochikit/content/browser-test.js:1253:14
[task 2023-03-21T21:23:39.650Z] 21:23:39     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1022:14
[task 2023-03-21T21:23:39.650Z] 21:23:39     INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1053:13
[task 2023-03-21T21:23:39.650Z] 21:23:39     INFO - 
[task 2023-03-21T21:23:39.653Z] 21:23:39     INFO - GECKO(15442) | [Parent 15442: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (7f7653aa2000) [pid = 15442] [serial = 16] [outer = 7f76674bd280]
[task 2023-03-21T21:23:39.743Z] 21:23:39     INFO - GECKO(15442) | Completed ShutdownLeaks collections in process 15514
[task 2023-03-21T21:23:41.435Z] 21:23:41     INFO - GECKO(15442) | [Parent 15442: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (7f7653a9cc00) [pid = 15442] [serial = 15] [outer = 0] [url = about:blank]
[task 2023-03-21T21:23:41.770Z] 21:23:41     INFO - GECKO(15442) | Completed ShutdownLeaks collections in process 15442
[task 2023-03-21T21:23:41.772Z] 21:23:41     INFO - TEST-START | Shutdown

Comment 1

[BugBot [:suhaib / :marco/ :calixte]]

:niklas, since you are the author of the regressor, bug 1811318, could you take a look?

For more information, please visit auto_nag documentation.

Comment 2

[Intermittent Failures Robot]

26 failures in 743 pushes (0.035 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 26

Platform and build breakdown:

• macosx1015-64-qr: 5
  • debug: 1
  • opt: 4
• linux1804-64-qr: 8
  • debug: 8
• windows11-64-2009-qr: 2
  • debug: 2
• linux1804-64-tsan-qr: 2
  • opt: 2
• linux1804-64-asan-qr: 7
  • opt: 7
• windows11-32-2009-qr: 1
  • debug: 1
• macosx1015-64-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1823801&startday=2023-03-21&endday=2023-03-21&tree=all

Comment 3

[Natalia Csoregi [:nataliaCs]]

Started to increase frequency: range

Comment 4

[Natalia Csoregi [:nataliaCs]]

Backed out Bug 1811318 for causing high frequency failures.

• backout details

Comment 5

[Natalia Csoregi [:nataliaCs]]

No more failures after the backout.
Closing as fixed based on Comment 4.

Comment 6

[Intermittent Failures Robot]

79 failures in 821 pushes (0.096 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 79

Platform and build breakdown:

• linux1804-64-qr: 56
  • debug: 56
• macosx1015-64-qr: 2
  • opt: 2
• linux1804-64-tsan-qr: 3
  • opt: 3
• linux1804-64-asan-qr: 14
  • opt: 14
• windows11-64-2009-asan-qr: 3
  • opt: 3
• macosx1015-64-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1823801&startday=2023-03-22&endday=2023-03-22&tree=all

Comment 7

[BugBot [:suhaib / :marco/ :calixte]]

Set release status flags based on info from the regressing bug 1811318

Comment 8

[Intermittent Failures Robot]

112 failures in 4161 pushes (0.027 failures/push) were associated with this bug in the last 7 days.

This is the #5 most frequent failure this week.

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

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

Repository breakdown:

• autoland: 112

Platform and build breakdown:

• macosx1015-64-qr: 11
  • debug: 1
  • opt: 10
• linux1804-64-qr: 67
  • debug: 67
• windows11-64-2009-qr: 2
  • debug: 2
• linux1804-64-tsan-qr: 5
  • opt: 5
• linux1804-64-asan-qr: 21
  • opt: 21
• windows11-32-2009-qr: 1
  • debug: 1
• macosx1015-64-shippable-qr: 2
  • opt: 2
• windows11-64-2009-asan-qr: 3
  • opt: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1823801&startday=2023-03-20&endday=2023-03-26&tree=all