Filed by: csabou [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=414441893&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/BlJKSSfHQCelS2YOsNiR0g/runs/0/artifacts/public/logs/live_backing.log
[task 2023-05-03T04:15:34.293Z] 04:15:34 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-tabs-pretty-print.js | Pretty printed tab has the pretty-print icon -
[task 2023-05-03T04:15:34.294Z] 04:15:34 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-tabs-pretty-print.js | original tab does not have the pretty-print icon -
[task 2023-05-03T04:15:34.295Z] 04:15:34 INFO - Waiting for state change: selected source
[task 2023-05-03T04:15:34.296Z] 04:15:34 INFO - Finished waiting for state change: selected source
[task 2023-05-03T04:15:34.298Z] 04:15:34 INFO - Navigating to "https://example.com/browser/devtools/client/debugger/test/mochitest/examples/doc-minified.html"
[task 2023-05-03T04:15:34.299Z] 04:15:34 INFO - Waiting for page to be loaded…
[task 2023-05-03T04:15:34.299Z] 04:15:34 INFO - Buffered messages finished
[task 2023-05-03T04:15:34.301Z] 04:15:34 INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/test/mochitest/browser_dbg-tabs-pretty-print.js | Test timed out -
[task 2023-05-03T04:15:36.253Z] 04:15:36 INFO - Removing tab.
[task 2023-05-03T04:15:36.255Z] 04:15:36 INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2023-05-03T04:15:36.299Z] 04:15:36 INFO - Got event: 'TabClose' on [object XULElement].
[task 2023-05-03T04:15:36.319Z] 04:15:36 INFO - GECKO(1593) | [Parent 1593, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:163
[task 2023-05-03T04:15:36.321Z] 04:15:36 INFO - GECKO(1593) | [Parent 1593, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:163
[task 2023-05-03T04:15:36.329Z] 04:15:36 INFO - Tab removed and finished closing
[task 2023-05-03T04:15:36.358Z] 04:15:36 INFO - GECKO(1593) | JavaScript error: , line 0: NotFoundError: No such JSWindowActor 'DevToolsFrame'
[task 2023-05-03T04:15:36.361Z] 04:15:36 INFO - GECKO(1593) | [Parent 1593: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7fe98c369800 == 6 [pid = 1593] [id = 444] [url = chrome://devtools/content/debugger/index.html]
[task 2023-05-03T04:15:36.380Z] 04:15:36 INFO - Console message: [JavaScript Error: "NotFoundError: No such JSWindowActor 'DevToolsFrame'"]
[task 2023-05-03T04:15:36.388Z] 04:15:36 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-tabs-pretty-print.js | The main process DevToolsServer has no pending connection when the test ends -
task 2023-05-03T04:21:38.143Z] 04:21:38 INFO - TEST-START | Shutdown
[task 2023-05-03T04:21:38.144Z] 04:21:38 INFO - Browser Chrome Test Summary
[task 2023-05-03T04:21:38.145Z] 04:21:38 INFO - Passed: 3559
[task 2023-05-03T04:21:38.147Z] 04:21:38 INFO - Failed: 1
[task 2023-05-03T04:21:38.147Z] 04:21:38 INFO - Todo: 0
[task 2023-05-03T04:21:38.147Z] 04:21:38 INFO - Mode: e10s
[task 2023-05-03T04:21:38.148Z] 04:21:38 INFO - *** End BrowserChrome Test Results ***
[task 2023-05-03T04:21:38.212Z] 04:21:38 INFO - GECKO(1593) | [Child 1690, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-05-03T04:21:38.213Z] 04:21:38 INFO - GECKO(1593) | [Child 1690, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-05-03T04:21:38.216Z] 04:21:38 INFO - GECKO(1593) | [Child 1690, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-05-03T04:21:38.217Z] 04:21:38 INFO - GECKO(1593) | [Child 1690, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-05-03T04:21:38.222Z] 04:21:38 INFO - GECKO(1593) | [Child 1690: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f8615b21800 == 4 [pid = 1690] [id = 4] [url = moz-extension://dfa44963-5008-42ce-a928-06502664636b/_generated_background_page.html]
[task 2023-05-03T04:21:38.222Z] 04:21:38 INFO - GECKO(1593) | [Child 1690: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f8615b20800 == 3 [pid = 1690] [id = 3] [url = moz-extension://9dcb2af8-02f5-4b7b-ac20-815ef616cfec/_generated_background_page.html]
[task 2023-05-03T04:21:38.222Z] 04:21:38 INFO - GECKO(1593) | [Child 1690: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f8615b1f400 == 2 [pid = 1690] [id = 2] [url = moz-extension://b5fe8882-0e89-4cc5-97c3-fd87673b3a72/_generated_background_page.html]
[task 2023-05-03T04:21:38.243Z] 04:21:38 INFO - GECKO(1593) | [Child 1690, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-05-03T04:21:38.244Z] 04:21:38 INFO - GECKO(1593) | [Child 1690, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-05-03T04:21:38.247Z] 04:21:38 INFO - GECKO(1593) | [Child 1690: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f8615b1c400 == 1 [pid = 1690] [id = 0] [url = moz-extension://3aaad922-d7bd-4cac-8832-953aa3f0147c/_generated_background_page.html]
[task 2023-05-03T04:21:38.269Z] 04:21:38 INFO - GECKO(1593) | [Child 1690, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-05-03T04:21:38.292Z] 04:21:38 INFO - GECKO(1593) | [WARN webrender::device::gl] Missing optimized shader source for gpu_cache_update
[task 2023-05-03T04:21:38.420Z] 04:21:38 INFO - GECKO(1593) | 1683087698419 Marionette TRACE Received observer notification quit-application
[task 2023-05-03T04:21:38.420Z] 04:21:38 INFO - GECKO(1593) | 1683087698419 Marionette INFO Stopped listening on port 2828
[task 2023-05-03T04:21:38.423Z] 04:21:38 INFO - GECKO(1593) | 1683087698422 Marionette DEBUG Marionette stopped listening
[task 2023-05-03T04:21:38.462Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:371
[task 2023-05-03T04:21:38.495Z] 04:21:38 INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-05-03T04:21:38.496Z] 04:21:38 INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/test/mochitest/browser_dbg-tabs-pretty-print.js | Uncaught exception received from previously timed out test bound - at resource://testing-common/BrowserTestUtils.sys.mjs:527 - Error: The window unloaded while we were waiting for the browser to load - this should never happen.
[task 2023-05-03T04:21:38.496Z] 04:21:38 INFO - Stack trace:
[task 2023-05-03T04:21:38.496Z] 04:21:38 INFO - listener@resource://testing-common/BrowserTestUtils.sys.mjs:527:15
[task 2023-05-03T04:21:38.497Z] 04:21:38 INFO - EventListener.handleEvent*browserLoaded/<@resource://testing-common/BrowserTestUtils.sys.mjs:542:27
[task 2023-05-03T04:21:38.497Z] 04:21:38 INFO - browserLoaded@resource://testing-common/BrowserTestUtils.sys.mjs:492:12
[task 2023-05-03T04:21:38.498Z] 04:21:38 INFO - navigateTo@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:561:44
[task 2023-05-03T04:21:38.498Z] 04:21:38 INFO - async*reloadBrowser@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:518:10
[task 2023-05-03T04:21:38.499Z] 04:21:38 INFO - reload@chrome://mochitests/content/browser/devtools/client/debugger/test/mochitest/shared-head.js:937:9
[task 2023-05-03T04:21:38.499Z] 04:21:38 INFO - @chrome://mochitests/content/browser/devtools/client/debugger/test/mochitest/browser_dbg-tabs-pretty-print.js:29:9
[task 2023-05-03T04:21:38.499Z] 04:21:38 INFO - async*handleTask@chrome://mochikit/content/browser-test.js:1133:26
[task 2023-05-03T04:21:38.500Z] 04:21:38 INFO - _runTaskBasedTest@chrome://mochikit/content/browser-test.js:1205:18
[task 2023-05-03T04:21:38.500Z] 04:21:38 INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1347:14
[task 2023-05-03T04:21:38.500Z] 04:21:38 INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1122:14
[task 2023-05-03T04:21:38.501Z] 04:21:38 INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1056:13
[task 2023-05-03T04:21:38.536Z] 04:21:38 INFO - GECKO(1593) | [WARN webrender::device::gl] Missing optimized shader source for gpu_cache_update
[task 2023-05-03T04:21:38.611Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:371
[task 2023-05-03T04:21:38.617Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, 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-03T04:21:38.621Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, 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-03T04:21:38.626Z] 04:21:38 INFO - GECKO(1593) | JavaScript error: chrome://mochikit/content/browser-test.js, line 1196: TypeError: can't access property "shift", currentScope.__tasks is null
[task 2023-05-03T04:21:38.631Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, 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-03T04:21:38.639Z] 04:21:38 INFO - GECKO(1593) | [Child 1690: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f8615263400 == 0 [pid = 1690] [id = 5] [url = moz-extension://0a6679ac-6a6c-4c69-a86e-73cfc298db4f/_generated_background_page.html]
[task 2023-05-03T04:21:38.652Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-05-03T04:21:38.652Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-05-03T04:21:38.652Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-05-03T04:21:38.652Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-05-03T04:21:38.652Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-05-03T04:21:38.653Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-05-03T04:21:38.654Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-05-03T04:21:38.654Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-05-03T04:21:38.655Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-05-03T04:21:38.656Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-05-03T04:21:38.656Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-05-03T04:21:38.657Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-05-03T04:21:38.658Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-05-03T04:21:38.658Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-05-03T04:21:38.659Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-05-03T04:21:38.660Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-05-03T04:21:38.660Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-05-03T04:21:38.662Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-05-03T04:21:38.662Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.
[task 2023-05-03T04:21:38.663Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-05-03T04:21:38.665Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-05-03T04:21:38.666Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-05-03T04:21:38.667Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-05-03T04:21:38.670Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-05-03T04:21:38.670Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-05-03T04:21:38.671Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-05-03T04:21:38.672Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-05-03T04:21:38.673Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-05-03T04:21:38.674Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-05-03T04:21:38.677Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:558
[task 2023-05-03T04:21:38.678Z] 04:21:38 INFO - GECKO(1593) | [Parent 1593, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-05-03T04:21:38.784Z] 04:21:38 INFO - GECKO(1593) | [Child 1815: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7fd37ec31400 == 0 [pid = 1815] [id = 89] [url = about:blank]
[task 2023-05-03T04:21:38.814Z] 04:21:38 INFO - GECKO(1593) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp5czqcceo.mozrunner/runtests_leaks.log
[task 2023-05-03T04:21:38.815Z] 04:21:38 INFO - GECKO(1593) | [22510, 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-03T04:21:38.815Z] 04:21:38 INFO - GECKO(1593) | *** You are running in background task mode. ***
[task 2023-05-03T04:21:38.815Z] 04:21:38 INFO - GECKO(1593) | *** You are running in headless mode.
Description
•