Filed by: nfay [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=415043876&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/BqD1Py08R3uGK3tciHt0sQ/runs/0/artifacts/public/logs/live_backing.log
[task 2023-05-09T10:48:20.997Z] 10:48:20 INFO - TEST-START | devtools/client/debugger/test/mochitest/browser_dbg-scroll-run-to-completion.js
[task 2023-05-09T10:48:21.295Z] 10:48:21 INFO - GECKO(1601) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp65w510og.mozrunner/runtests_leaks_tab_pid15241.log
[task 2023-05-09T10:48:21.297Z] 10:48:21 INFO - GECKO(1601) | [15241, 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-05-09T10:48:21.301Z] 10:48:21 INFO - GECKO(1601) | [Child 15241, 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-05-09T10:48:21.334Z] 10:48:21 INFO - GECKO(1601) | [Parent 1601, Main Thread] WARNING: IPC Connection Error: [Parent][PProfilerParent] RunMessage(msgname=PProfiler::Reply_Stop) Channel closing: too late to send/recv, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1927
[task 2023-05-09T10:48:21.343Z] 10:48:21 INFO - GECKO(1601) | [Child 15241, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:655
[task 2023-05-09T10:48:21.360Z] 10:48:21 INFO - GECKO(1601) | [Child 15241, 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-05-09T10:48:21.361Z] 10:48:21 INFO - GECKO(1601) | [Child 15241, 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-05-09T10:48:21.424Z] 10:48:21 INFO - GECKO(1601) | [Parent 1601, 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-05-09T10:48:21.427Z] 10:48:21 INFO - GECKO(1601) | [Parent 1601, Compositor] WARNING: IPC Connection Error: [Parent][PImageBridgeParent] RunMessage(msgname=PImageBridge::Msg_WillClose) Channel closing: too late to send/recv, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1927
[task 2023-05-09T10:48:21.434Z] 10:48:21 INFO - GECKO(1601) | [Parent 1601, 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-05-09T10:48:21.556Z] 10:48:21 INFO - GECKO(1601) | [Child 15192, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3426
[task 2023-05-09T10:48:21.556Z] 10:48:21 INFO - GECKO(1601) | [Child 15189, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3426
[task 2023-05-09T10:48:21.564Z] 10:48:21 INFO - GECKO(1601) | [Child 15192, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-05-09T10:48:21.570Z] 10:48:21 INFO - GECKO(1601) | [Child 15189, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-05-09T10:48:21.609Z] 10:48:21 INFO - GECKO(1601) | [Child 15192, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4688
[task 2023-05-09T10:48:21.616Z] 10:48:21 INFO - GECKO(1601) | [Child 15189, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4688
[task 2023-05-09T10:48:21.640Z] 10:48:21 INFO - GECKO(1601) | [Child 15196, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3426
[task 2023-05-09T10:48:21.645Z] 10:48:21 INFO - GECKO(1601) | [Child 15196, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-05-09T10:48:21.653Z] 10:48:21 INFO - GECKO(1601) | [Child 15196, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4688
[task 2023-05-09T10:48:21.770Z] 10:48:21 INFO - GECKO(1601) | [Child 15241: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7fca16133400 == 1 [pid = 15241] [id = 0]
[task 2023-05-09T10:48:21.772Z] 10:48:21 INFO - GECKO(1601) | [Child 15241: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7fca1b28e090) [pid = 15241] [serial = 1] [outer = 0]
[task 2023-05-09T10:48:21.775Z] 10:48:21 INFO - GECKO(1601) | [Child 15241: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7fca16133c00) [pid = 15241] [serial = 2] [outer = 7fca1b28e090]
[task 2023-05-09T10:48:21.823Z] 10:48:21 INFO - GECKO(1601) | [Child 1845: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (7f1bac282470) [pid = 1845] [serial = 136] [outer = 0] [url = about:blank]
[task 2023-05-09T10:48:21.859Z] 10:48:21 INFO - GECKO(1601) | [Child 15241, 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-05-09T10:48:22.004Z] 10:48:22 INFO - GECKO(1601) | [Child 15241: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (7fca16136800) [pid = 15241] [serial = 3] [outer = 7fca1b28e090]
[task 2023-05-09T10:48:22.084Z] 10:48:22 INFO - GECKO(1601) | [Child 15241, 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:385
[task 2023-05-09T10:48:22.087Z] 10:48:22 INFO - GECKO(1601) | [Child 15241, 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:385
[task 2023-05-09T10:48:22.980Z] 10:48:22 INFO - GECKO(1601) | [Parent 1601: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7fcb3deca800 == 5 [pid = 1601] [id = 373] [url = about:devtools-toolbox]
[task 2023-05-09T10:48:22.984Z] 10:48:22 INFO - GECKO(1601) | [Parent 1601: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 38 (7fcb32149560) [pid = 1601] [serial = 916] [outer = 0] [url = chrome://devtools/content/debugger/index.html]
[task 2023-05-09T10:48:22.986Z] 10:48:22 INFO - GECKO(1601) | [Parent 1601: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 37 (7fcb307c9660) [pid = 1601] [serial = 913] [outer = 0] [url = about:devtools-toolbox]
[task 2023-05-09T10:48:24.144Z] 10:48:24 INFO - GECKO(1601) | [Parent 1601: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 36 (7fcb3dec8c00) [pid = 1601] [serial = 915] [outer = 0] [url = about:devtools-toolbox]
[task 2023-05-09T10:48:24.144Z] 10:48:24 INFO - GECKO(1601) | [Parent 1601: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 35 (7fcb481c3000) [pid = 1601] [serial = 917] [outer = 0] [url = about:blank]
[task 2023-05-09T10:48:24.146Z] 10:48:24 INFO - GECKO(1601) | [Parent 1601: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 34 (7fcb3fa92800) [pid = 1601] [serial = 919] [outer = 0] [url = about:blank]
[task 2023-05-09T10:48:25.813Z] 10:48:25 INFO - GECKO(1601) | [Child 1845: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (7f1ba7133c00) [pid = 1845] [serial = 137] [outer = 0] [url = about:blank]
[task 2023-05-09T10:48:29.223Z] 10:48:29 INFO - GECKO(1601) | [Child 1736: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7fd135e35800 == 0 [pid = 1736] [id = 70] [url = about:blank]
[task 2023-05-09T10:48:30.264Z] 10:48:30 INFO - GECKO(1601) | [Parent 1601, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/checkouts/gecko/dom/events/DOMEventTargetHelper.cpp:302
[task 2023-05-09T10:48:30.267Z] 10:48:30 INFO - GECKO(1601) | [Parent 1601, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/checkouts/gecko/dom/events/DOMEventTargetHelper.cpp:302
[task 2023-05-09T10:48:30.269Z] 10:48:30 INFO - GECKO(1601) | [Parent 1601, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/checkouts/gecko/dom/events/DOMEventTargetHelper.cpp:302
[task 2023-05-09T10:48:30.271Z] 10:48:30 INFO - GECKO(1601) | [Parent 1601, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/checkouts/gecko/dom/events/DOMEventTargetHelper.cpp:302
[task 2023-05-09T10:48:30.277Z] 10:48:30 INFO - GECKO(1601) | [Parent 1601: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 33 (7fcb307c9280) [pid = 1601] [serial = 918] [outer = 0] [url = about:devtools-toolbox]
[task 2023-05-09T10:48:30.278Z] 10:48:30 INFO - GECKO(1601) | [Parent 1601: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 32 (7fcb32131370) [pid = 1601] [serial = 921] [outer = 0] [url = chrome://devtools/content/debugger/index.html]
[task 2023-05-09T10:48:33.384Z] 10:48:33 INFO - GECKO(1601) | [Child 1736: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7fd13bd64090) [pid = 1736] [serial = 147] [outer = 0] [url = about:blank]
[task 2023-05-09T10:48:34.322Z] 10:48:34 INFO - GECKO(1601) | [Parent 1601: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 31 (7fcb3deca000) [pid = 1601] [serial = 920] [outer = 0] [url = about:devtools-toolbox]
[task 2023-05-09T10:48:34.324Z] 10:48:34 INFO - GECKO(1601) | [Parent 1601: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 30 (7fcb3fa96000) [pid = 1601] [serial = 922] [outer = 0] [url = about:blank]
[task 2023-05-09T10:48:36.541Z] 10:48:36 INFO - GECKO(1601) | [Child 15241: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (7fca16133c00) [pid = 15241] [serial = 2] [outer = 0] [url = about:blank]
[task 2023-05-09T10:48:37.458Z] 10:48:37 INFO - GECKO(1601) | [Child 1736: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7fd135e35c00) [pid = 1736] [serial = 148] [outer = 0] [url = about:blank]
[task 2023-05-09T10:49:51.082Z] 10:49:51 INFO - TEST-INFO | started process screentopng
[task 2023-05-09T10:49:51.448Z] 10:49:51 INFO - TEST-INFO | screentopng: exit 0
[task 2023-05-09T10:49:51.450Z] 10:49:51 INFO - Buffered messages logged at 10:48:21
[task 2023-05-09T10:49:51.450Z] 10:49:51 INFO - Entering test bound
[task 2023-05-09T10:49:51.450Z] 10:49:51 INFO - Adding a new tab with URL: https://example.com/browser/devtools/client/debugger/test/mochitest/examples/doc-scroll-run-to-completion.html
[task 2023-05-09T10:49:51.450Z] 10:49:51 INFO - Buffered messages finished
[task 2023-05-09T10:49:51.450Z] 10:49:51 INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/test/mochitest/browser_dbg-scroll-run-to-completion.js | Test timed out -
[task 2023-05-09T10:49:51.451Z] 10:49:51 INFO - Removing tab.
[task 2023-05-09T10:49:51.451Z] 10:49:51 INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2023-05-09T10:49:51.453Z] 10:49:51 INFO - Got event: 'TabClose' on [object XULElement].
[task 2023-05-09T10:49:51.453Z] 10:49:51 INFO - Tab removed and finished closing
[task 2023-05-09T10:49:51.454Z] 10:49:51 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-scroll-run-to-completion.js | The main process DevToolsServer has no pending connection when the test ends -
[task 2023-05-09T10:49:51.466Z] 10:49:51 INFO - GECKO(1601) | [Parent 1601, IPDL Background] WARNING: IPC Connection Error: [Parent][PBackgroundParent] RunMessage(msgname=PRemoteWorkerService::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-05-09T10:49:51.520Z] 10:49:51 INFO - GECKO(1601) | [Child 15241: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7fca16133400 == 0 [pid = 15241] [id = 0] [url = https://example.com/browser/devtools/client/debugger/test/mochitest/examples/doc-scroll-run-to-completion.html]
[task 2023-05-09T10:49:51.556Z] 10:49:51 INFO - GECKO(1601) | [Child 15241: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7fca1b28e090) [pid = 15241] [serial = 1] [outer = 0] [url = about:neterror?e=connectionFailure&u=https%3A//example.com/browser/devtools/client/debugger/test/mochitest/examples/doc-scroll-run-to-completion.html&c=UTF-8&d=Firefox%20can%E2%80%99t%20establish%20a%20connection%20to%20the%20server%20at%20example.com.]
[task 2023-05-09T10:49:51.558Z] 10:49:51 INFO - GECKO(1601) | [Child 15241: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7fca16136800) [pid = 15241] [serial = 3] [outer = 0] [url = about:neterror?e=connectionFailure&u=https%3A//example.com/browser/devtools/client/debugger/test/mochitest/examples/doc-scroll-run-to-completion.html&c=UTF-8&d=Firefox%20can%E2%80%99t%20establish%20a%20connection%20to%20the%20server%20at%20example.com.]
[task 2023-05-09T10:49:51.559Z] 10:49:51 INFO - GECKO(1601) | [Child 15241, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3426
[task 2023-05-09T10:49:51.562Z] 10:49:51 INFO - GECKO(1601) | [Child 15241, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-05-09T10:49:51.570Z] 10:49:51 INFO - GECKO(1601) | [Child 15241, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4688
[task 2023-05-09T10:49:51.715Z] 10:49:51 INFO - GECKO(1601) | MEMORY STAT | vsize 4374MB | residentFast 828MB | heapAllocated 408MB
[task 2023-05-09T10:49:51.717Z] 10:49:51 INFO - TEST-OK | devtools/client/debugger/test/mochitest/browser_dbg-scroll-run-to-completion.js | took 90720ms
[task 2023-05-09T10:49:51.732Z] 10:49:51 INFO - GECKO(1601) | [Child 1736: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7fd135e35800 == 1 [pid = 1736] [id = 71]
[task 2023-05-09T10:49:51.733Z] 10:49:51 INFO - GECKO(1601) | [Child 1736: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7fd13bd64090) [pid = 1736] [serial = 149] [outer = 0]
[task 2023-05-09T10:49:51.734Z] 10:49:51 INFO - GECKO(1601) | [Child 1736: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7fd135e35c00) [pid = 1736] [serial = 150] [outer = 7fd13bd64090]
[task 2023-05-09T10:49:51.790Z] 10:49:51 INFO - checking window state
[task 2023-05-09T10:49:51.859Z] 10:49:51 INFO - TEST-START | devtools/client/debugger/test/mochitest/browser_dbg-search-file-paused.js
Description
•