Filed by: tszentpeteri [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=460852862&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/KuPG3gz9Q46PDubWhfz75g/runs/0/artifacts/public/logs/live_backing.log
[task 2024-06-03T17:17:54.407Z] 17:17:54 INFO - TEST-START | tools/profiler/tests/browser/browser_test_marker_network_redirect.js
[task 2024-06-03T17:17:54.452Z] 17:17:54 INFO - GECKO(19240) | [Child 19604: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7fa40b25ac00 == 0 [pid = 19604] [id = 0] [url = about:blank]
[task 2024-06-03T17:17:54.523Z] 17:17:54 INFO - GECKO(19240) | [Child 19604: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (7fa40aaeb020) [pid = 19604] [serial = 1] [outer = 0] [url = about:blank]
[task 2024-06-03T17:17:54.531Z] 17:17:54 INFO - GECKO(19240) | [Child 19728: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f8c4bac3000 == 1 [pid = 19728] [id = 0]
[task 2024-06-03T17:17:54.533Z] 17:17:54 INFO - GECKO(19240) | [Child 19728: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7f8c4baa7020) [pid = 19728] [serial = 1] [outer = 0]
[task 2024-06-03T17:17:54.534Z] 17:17:54 INFO - GECKO(19240) | [Child 19728: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7f8c4bac3800) [pid = 19728] [serial = 2] [outer = 7f8c4baa7020]
[task 2024-06-03T17:17:54.691Z] 17:17:54 INFO - GECKO(19240) | [Parent 19240, Main Thread] WARNING: NS_ENSURE_TRUE(mCacheEntry) failed: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpChannel.cpp:4996
[task 2024-06-03T17:17:54.715Z] 17:17:54 INFO - GECKO(19240) | [Parent 19240, Main Thread] WARNING: BounceTrackingState::OnDocumentStartRequest failed.: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/netwerk/ipc/DocumentLoadListener.cpp:2608
[task 2024-06-03T17:17:54.726Z] 17:17:54 INFO - GECKO(19240) | [Child 19728: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (7f8c4bac5c00) [pid = 19728] [serial = 3] [outer = 7f8c4baa7020]
[task 2024-06-03T17:17:56.713Z] 17:17:56 INFO - GECKO(19240) | [Child 19799: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f690c26cc00 == 1 [pid = 19799] [id = 0]
[task 2024-06-03T17:17:56.713Z] 17:17:56 INFO - GECKO(19240) | [Child 19799: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7f691155a020) [pid = 19799] [serial = 1] [outer = 0]
[task 2024-06-03T17:17:56.714Z] 17:17:56 INFO - GECKO(19240) | [Child 19799: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7f690c26d400) [pid = 19799] [serial = 2] [outer = 7f691155a020]
[task 2024-06-03T17:17:56.883Z] 17:17:56 INFO - GECKO(19240) | [Parent 19240, Main Thread] WARNING: BounceTrackingState::OnDocumentStartRequest failed.: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/netwerk/ipc/DocumentLoadListener.cpp:2608
[task 2024-06-03T17:17:56.922Z] 17:17:56 INFO - GECKO(19240) | [Child 19799: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (7f690c26fc00) [pid = 19799] [serial = 3] [outer = 7f691155a020]
[task 2024-06-03T17:17:56.934Z] 17:17:56 INFO - GECKO(19240) | [Child 19728: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f8c4bac3000 == 0 [pid = 19728] [id = 0] [url = https://example.com/browser/tools/profiler/tests/browser/simple.html]
[task 2024-06-03T17:17:57.027Z] 17:17:57 INFO - GECKO(19240) | [Child 19728: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (7f8c4bac3800) [pid = 19728] [serial = 2] [outer = 0] [url = about:blank]
[task 2024-06-03T17:17:57.029Z] 17:17:57 INFO - GECKO(19240) | [Child 19728: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7f8c4baa7020) [pid = 19728] [serial = 1] [outer = 0] [url = https://example.com/browser/tools/profiler/tests/browser/simple.html]
[task 2024-06-03T17:17:57.033Z] 17:17:57 INFO - GECKO(19240) | [Child 19728: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7f8c4bac5c00) [pid = 19728] [serial = 3] [outer = 0] [url = https://example.com/browser/tools/profiler/tests/browser/simple.html]
[task 2024-06-03T17:17:57.099Z] 17:17:57 INFO - GECKO(19240) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp7234yo57.mozrunner/runtests_leaks_tab_pid19911.log
[task 2024-06-03T17:17:57.102Z] 17:17:57 INFO - GECKO(19240) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp7234yo57.mozrunner/runtests_leaks_tab_pid19909.log
[task 2024-06-03T17:17:57.198Z] 17:17:57 INFO - GECKO(19240) | [Child 19909, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:757
[task 2024-06-03T17:17:57.198Z] 17:17:57 INFO - GECKO(19240) | [Child 19911, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:757
[task 2024-06-03T17:17:57.384Z] 17:17:57 INFO - GECKO(19240) | [Parent 19240, Main Thread] WARNING: NS_ENSURE_TRUE(mCacheEntry) failed: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpChannel.cpp:4996
[task 2024-06-03T17:17:57.822Z] 17:17:57 INFO - GECKO(19240) | [Child 19460: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f04fd8c5400 == 1 [pid = 19460] [id = 1] [url = about:privatebrowsing]
[task 2024-06-03T17:17:57.855Z] 17:17:57 INFO - GECKO(19240) | [19240] Assertion failure: mJSSampling == ACTIVE || mJSSampling == ACTIVE_REQUESTED, at /builds/worker/workspace/obj-build/dist/include/mozilla/ProfilerThreadRegistrationData.h:505
[task 2024-06-03T17:17:57.857Z] 17:17:57 INFO - GECKO(19240) | #01: locked_profiler_stop(PSAutoLock const&) [tools/profiler/core/platform.cpp:6593]
[task 2024-06-03T17:17:57.858Z] 17:17:57 INFO - GECKO(19240) | #02: profiler_stop() [tools/profiler/core/platform.cpp:6655]
[task 2024-06-03T17:17:57.861Z] 17:17:57 INFO - GECKO(19240) | #03: nsProfiler::StopProfiler(JSContext*, mozilla::dom::Promise**) [tools/profiler/gecko/nsProfiler.cpp:181]
[task 2024-06-03T17:17:57.861Z] 17:17:57 INFO - GECKO(19240) | #04: ??? [/builds/worker/workspace/build/application/firefox/libxul.so + 0x3999d9a]
[task 2024-06-03T17:17:57.863Z] 17:17:57 INFO - GECKO(19240) | #05: CallMethodHelper::Call() [js/xpconnect/src/XPCWrappedNative.cpp:1174]
[task 2024-06-03T17:17:57.866Z] 17:17:57 INFO - GECKO(19240) | #06: XPCWrappedNative::CallMethod(XPCCallContext&, XPCWrappedNative::CallMode) [js/xpconnect/src/XPCWrappedNative.cpp:1120]
[task 2024-06-03T17:17:57.866Z] 17:17:57 INFO - GECKO(19240) | #07: XPC_WN_CallMethod(JSContext*, unsigned int, JS::Value*) [js/xpconnect/src/XPCWrappedNativeJSOps.cpp:966]
[task 2024-06-03T17:17:57.866Z] 17:17:57 INFO - GECKO(19240) | #08: CallJSNative(JSContext*, bool (*)(JSContext*, unsigned int, JS::Value*), js::CallReason, JS::CallArgs const&) [js/src/vm/Interpreter.cpp:481]
[task 2024-06-03T17:17:57.867Z] 17:17:57 INFO - GECKO(19240) | #09: js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) [js/src/vm/Interpreter.cpp:575]
[task 2024-06-03T17:17:57.867Z] 17:17:57 INFO - GECKO(19240) | #10: js::Interpret(JSContext*, js::RunState&) [js/src/vm/Interpreter.cpp:0]
[task 2024-06-03T17:17:57.868Z] 17:17:57 INFO - GECKO(19240) | #11: js::RunScript(JSContext*, js::RunState&) [js/src/vm/Interpreter.cpp:453]
[task 2024-06-03T17:17:57.868Z] 17:17:57 INFO - GECKO(19240) | #12: js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) [js/src/vm/Interpreter.cpp:607]
[task 2024-06-03T17:17:57.869Z] 17:17:57 INFO - GECKO(19240) | #13: js::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, js::AnyInvokeArgs const&, JS::MutableHandle<JS::Value>, js::CallReason) [js/src/vm/Interpreter.cpp:674]
[task 2024-06-03T17:17:57.870Z] 17:17:57 INFO - GECKO(19240) | #14: js::CallSelfHostedFunction(JSContext*, JS::Handle<js::PropertyName*>, JS::Handle<JS::Value>, js::AnyInvokeArgs const&, JS::MutableHandle<JS::Value>) [js/src/vm/SelfHosting.cpp:1593]
[task 2024-06-03T17:17:57.871Z] 17:17:57 INFO - GECKO(19240) | #15: js::jit::InterpretResume(JSContext*, JS::Handle<JSObject*>, JS::Value*, JS::MutableHandle<JS::Value>) [js/src/jit/VMFunctions.cpp:1141]
[task 2024-06-03T17:17:57.872Z] 17:17:57 INFO - GECKO(19240) | #16: ??? (???:???)
[task 2024-06-03T17:17:57.872Z] 17:17:57 INFO - GECKO(19240) | ExceptionHandler::GenerateDump attempting to generate:</tmp/tmp7234yo57.mozrunner/minidumps/1f465508-dfc3-de88-e513-2615a9d49164.dmp
[task 2024-06-03T17:17:57.879Z] 17:17:57 INFO - GECKO(19240) | ExceptionHandler::GenerateDump cloned child 19969
[task 2024-06-03T17:17:57.880Z] 17:17:57 INFO - GECKO(19240) | ExceptionHandler::SendContinueSignalToChild sent continue signal to child
[task 2024-06-03T17:17:57.882Z] 17:17:57 INFO - GECKO(19240) | ExceptionHandler::WaitForContinueSignal waiting for continue signal...
[task 2024-06-03T17:17:57.998Z] 17:17:57 INFO - GECKO(19240) | ExceptionHandler::GenerateDump minidump generation <succeeded
[task 2024-06-03T17:17:58.034Z] 17:17:58 INFO - GECKO(19240) | <[Child 19409, IPC I/O Child] WARNING: [E2678149BA9A4BDE.4AFF65EE88556FF5]: Dropping message '<null>'; no connection to unknown peer 1.1: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:365
[task 2024-06-03T17:17:58.036Z] 17:17:58 INFO - GECKO(19240) | [Child 19604, IPC I/O Child] WARNING: [13412D93AADFD62C.8592572E76ACA92E]: Dropping message '<null>'; no connection to unknown peer 1.1: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:365
[task 2024-06-03T17:17:58.037Z] 17:17:58 INFO - GECKO(19240) | Exiting due to channel error.
[task 2024-06-03T17:17:58.038Z] 17:17:58 INFO - GECKO(19240) | Exiting due to channel error.
[task 2024-06-03T17:17:58.041Z] 17:17:58 INFO - GECKO(19240) | [Child 19460, IPC I/O Child] WARNING: [5191370258E50AEC.7549CD5CF9C9BAD8]: Dropping message '<null>'; no connection to unknown peer 1.1: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:365
[task 2024-06-03T17:17:58.041Z] 17:17:58 INFO - GECKO(19240) | [Child 19460, RemoteLzyStream] WARNING: RemoteLazyInputStreamChild disconnected unexpectedly (1 streams remaining)! 7f04fd8d8b40 {66683055-9223-40b8-8b89-4e1b5841c3f6}: file /builds/worker/checkouts/gecko/dom/file/ipc/RemoteLazyInputStreamChild.cpp:51
[task 2024-06-03T17:17:58.041Z] 17:17:58 INFO - GECKO(19240) | Exiting due to channel error.
[task 2024-06-03T17:17:58.041Z] 17:17:58 INFO - GECKO(19240) | [Child 19460, RemoteLzyStream] WARNING: RemoteLazyInputStreamChild disconnected unexpectedly (1 streams remaining)! 7f04fd8d89b0 {e727eb11-fa34-489c-9626-9fdbc2a09430}: file /builds/worker/checkouts/gecko/dom/file/ipc/RemoteLazyInputStreamChild.cpp:51
[task 2024-06-03T17:17:58.045Z] 17:17:58 INFO - GECKO(19240) | [Child 19911, IPC I/O Child] WARNING: [5E14295604359A06.39A4A0FE4B60A2A1]: Dropping message '<null>'; no connection to unknown peer 1.1: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:365
[task 2024-06-03T17:17:58.045Z] 17:17:58 INFO - GECKO(19240) | [Child 19799, IPC I/O Child] WARNING: [49316246B99631AB.EA161DBFF6CE3BD0]: Dropping message '<null>'; no connection to unknown peer 1.1: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:365
[task 2024-06-03T17:17:58.045Z] 17:17:58 INFO - GECKO(19240) | Exiting due to channel error.
[task 2024-06-03T17:17:58.045Z] 17:17:58 INFO - GECKO(19240) | Exiting due to channel error.
[task 2024-06-03T17:17:58.045Z] 17:17:58 INFO - GECKO(19240) | [Child 19335, IPC I/O Child] WARNING: [E4C155D4744149A5.B9BED0F5E81B3680]: Dropping message '<null>'; no connection to unknown peer 1.1: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:365
[task 2024-06-03T17:17:58.046Z] 17:17:58 INFO - GECKO(19240) | Exiting due to channel error.
[task 2024-06-03T17:17:58.047Z] 17:17:58 INFO - GECKO(19240) | [Child 19909, IPC I/O Child] WARNING: [F9CD65FE67A66955.9AFA94A7760A1771]: Dropping message '<null>'; no connection to unknown peer 1.1: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:365
[task 2024-06-03T17:17:58.047Z] 17:17:58 INFO - GECKO(19240) | Exiting due to channel error.
[task 2024-06-03T17:17:58.048Z] 17:17:58 INFO - GECKO(19240) | [Child 19852, IPC I/O Child] WARNING: [EE11A167D7A56B93.ADA647DF628BD283]: Dropping message '<null>'; no connection to unknown peer 1.1: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:365
[task 2024-06-03T17:17:58.048Z] 17:17:58 INFO - GECKO(19240) | Exiting due to channel error.
[task 2024-06-03T17:17:58.049Z] 17:17:58 INFO - GECKO(19240) | [Child 19304, IPC I/O Child] WARNING: [92F4879B421BDAB6.3A4A3E6FD855AC75]: Dropping message '<null>'; no connection to unknown peer 1.1: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:365
[task 2024-06-03T17:17:58.049Z] 17:17:58 INFO - GECKO(19240) | Exiting due to channel error.
[task 2024-06-03T17:17:58.050Z] 17:17:58 INFO - GECKO(19240) | [Utility 19400, IPC I/O Child] WARNING: [13A70B8E74A2B746.39A930A1BDE32122]: Dropping message '<null>'; no connection to unknown peer 1.1: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:365
[task 2024-06-03T17:17:58.051Z] 17:17:58 INFO - GECKO(19240) | [Utility 19400, Main Thread] WARNING: Shutting down Utility process early due to a crash!: file /builds/worker/checkouts/gecko/ipc/glue/UtilityProcessChild.cpp:352
[task 2024-06-03T17:17:58.086Z] 17:17:58 INFO - TEST-INFO | Main app process: exit 11
[task 2024-06-03T17:17:58.086Z] 17:17:58 ERROR - TEST-UNEXPECTED-FAIL | ShutdownLeaks | process() called before end of test suite
[task 2024-06-03T17:17:58.086Z] 17:17:58 INFO - TEST-INFO | Confirming we saw 30 DOCSHELL created and 15 destroyed log strings.
[task 2024-06-03T17:17:58.086Z] 17:17:58 INFO - TEST-INFO | Confirming we saw 82 DOMWINDOW created and 41 destroyed log strings.
[task 2024-06-03T17:17:58.087Z] 17:17:58 INFO - Buffered messages logged at 17:17:54
[task 2024-06-03T17:17:58.097Z] 17:17:58 INFO - Entering test bound test_network_markers_service_worker_setup
[task 2024-06-03T17:17:58.098Z] 17:17:58 INFO - Leaving test bound test_network_markers_service_worker_setup
[task 2024-06-03T17:17:58.098Z] 17:17:58 INFO - Entering test bound test_network_markers_redirect_simple
[task 2024-06-03T17:17:58.098Z] 17:17:58 INFO - TEST-PASS | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The profiler is not currently active - true == true -
Description
•