1833295 - Intermittent Assertion failure: this->flags() == 0, at /builds/worker/checkouts/gecko/js/src/gc/Cell.h:837

Status: NEW

(Core :: JavaScript Engine, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=415893157&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/PMZcM941Q8-3Tl8eegxFhw/runs/0/artifacts/public/logs/live_backing.log

---

[task 2023-05-16T02:03:19.498Z] 02:03:19     INFO - TEST-START | tools/profiler/tests/browser/browser_test_feature_jsallocations.js
[task 2023-05-16T02:03:19.522Z] 02:03:19     INFO - GECKO(15135) | [Parent 15135, 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-16T02:03:19.562Z] 02:03:19     INFO - GECKO(15135) | [Child 15202, ProfilerChild] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:345
[task 2023-05-16T02:03:19.567Z] 02:03:19     INFO - GECKO(15135) | [Utility 15287, ProfilerChild] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:345
[task 2023-05-16T02:03:19.571Z] 02:03:19     INFO - GECKO(15135) | [Child 15229, ProfilerChild] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:345
[task 2023-05-16T02:03:19.573Z] 02:03:19     INFO - GECKO(15135) | [Child 15341, ProfilerChild] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:345
[task 2023-05-16T02:03:19.575Z] 02:03:19     INFO - GECKO(15135) | [Child 15294, ProfilerChild] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:345
[task 2023-05-16T02:03:19.583Z] 02:03:19     INFO - GECKO(15135) | [Child 15406, ProfilerChild] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:345
[task 2023-05-16T02:03:19.584Z] 02:03:19     INFO - GECKO(15135) | [Child 15413, ProfilerChild] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:345
[task 2023-05-16T02:03:19.585Z] 02:03:19     INFO - GECKO(15135) | [Child 15474, ProfilerChild] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:345
[task 2023-05-16T02:03:19.660Z] 02:03:19     INFO - GECKO(15135) | [Child 15406: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f05b9a8b800 == 1 [pid = 15406] [id = 0]
[task 2023-05-16T02:03:19.661Z] 02:03:19     INFO - GECKO(15135) | [Child 15406: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7f05bea20470) [pid = 15406] [serial = 1] [outer = 0]
[task 2023-05-16T02:03:19.668Z] 02:03:19     INFO - GECKO(15135) | [Child 15406: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7f05b9a8c000) [pid = 15406] [serial = 2] [outer = 7f05bea20470]
[task 2023-05-16T02:03:19.706Z] 02:03:19     INFO - GECKO(15135) | [Child 15202: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (7f4cbb261800) [pid = 15202] [serial = 13] [outer = 0] [url = moz-extension://2e798aa9-d285-40bf-9a66-b2f32e0d235e/_generated_background_page.html]
[task 2023-05-16T02:03:19.985Z] 02:03:19     INFO - GECKO(15135) | [Child 15406: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (7f05b9a8e400) [pid = 15406] [serial = 3] [outer = 7f05bea20470]
[task 2023-05-16T02:03:20.713Z] 02:03:20     INFO - GECKO(15135) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp90xt3bg6.mozrunner/runtests_leaks_tab_pid15509.log
[task 2023-05-16T02:03:20.714Z] 02:03:20     INFO - GECKO(15135) | [15509, 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-16T02:03:20.714Z] 02:03:20     INFO - GECKO(15135) | [15509, 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-16T02:03:20.716Z] 02:03:20     INFO - GECKO(15135) | [15509, 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-16T02:03:20.731Z] 02:03:20     INFO - GECKO(15135) | [Child 15509, 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-16T02:03:20.763Z] 02:03:20     INFO - GECKO(15135) | [Child 15509, 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-16T02:03:20.778Z] 02:03:20     INFO - GECKO(15135) | [Child 15509, 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-16T02:03:20.780Z] 02:03:20     INFO - GECKO(15135) | [Child 15509, 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-16T02:03:20.814Z] 02:03:20     INFO - GECKO(15135) | [Child 15509, 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-16T02:03:21.302Z] 02:03:21     INFO - GECKO(15135) | Assertion failure: this->flags() == 0, at /builds/worker/checkouts/gecko/js/src/gc/Cell.h:837
[task 2023-05-16T02:03:21.348Z] 02:03:21     INFO -  Initializing stack-fixing for the first stack frame, this may take a while...
[task 2023-05-16T02:03:45.463Z] 02:03:45     INFO - GECKO(15135) | #01: JSObject::nonCCWRealm() const [js/src/vm/JSObject.h:407]
[task 2023-05-16T02:03:45.465Z] 02:03:45     INFO - GECKO(15135) | #02: js::jit::JitcodeGlobalEntry::lookupRealmID(JSRuntime*, void*) const [js/src/jit/JitcodeMap.cpp:470]
[task 2023-05-16T02:03:45.466Z] 02:03:45     INFO - GECKO(15135) | #03: std::_Function_handler<void (void*), JITFrameInfo::AddInfoForRange(unsigned long, unsigned long, JSContext*, std::function<void (std::function<void (void*)> const&)> const&)::$_0>::_M_invoke(std::_Any_data const&, void*&&) [/builds/worker/fetches/sysroot-x86_64-linux-gnu/usr/include/c++/7/bits/std_function.h:316]
[task 2023-05-16T02:03:45.468Z] 02:03:45     INFO - GECKO(15135) | #04: ProfileBuffer::AddJITInfoForRange(unsigned long, mozilla::baseprofiler::BaseProfilerThreadId, JSContext*, JITFrameInfo&, mozilla::ProgressLogger) const::$_0::operator()(std::function<void (void*)> const&) const::{lambda(mozilla::ProfileChunkedBuffer::Reader*)#1}::operator()(mozilla::ProfileChunkedBuffer::Reader*) const::{lambda(mozilla::ProfileChunkedBuffer::Reader*)#1}::operator()(mozilla::ProfileChunkedBuffer::Reader*) const [tools/profiler/core/ProfileBufferEntry.cpp:1577]
[task 2023-05-16T02:03:45.469Z] 02:03:45     INFO - GECKO(15135) | #05: std::_Function_handler<void (std::function<void (void*)> const&), ProfileBuffer::AddJITInfoForRange(unsigned long, mozilla::baseprofiler::BaseProfilerThreadId, JSContext*, JITFrameInfo&, mozilla::ProgressLogger) const::$_0>::_M_invoke(std::_Any_data const&, std::function<void (void*)> const&) [/builds/worker/fetches/sysroot-x86_64-linux-gnu/usr/include/c++/7/bits/std_function.h:316]
[task 2023-05-16T02:03:45.470Z] 02:03:45     INFO - GECKO(15135) | #06: JITFrameInfo::AddInfoForRange(unsigned long, unsigned long, JSContext*, std::function<void (std::function<void (void*)> const&)> const&) [tools/profiler/core/ProfileBufferEntry.cpp:607]
[task 2023-05-16T02:03:45.471Z] 02:03:45     INFO - GECKO(15135) | #07: ProfileBuffer::AddJITInfoForRange(unsigned long, mozilla::baseprofiler::BaseProfilerThreadId, JSContext*, JITFrameInfo&, mozilla::ProgressLogger) const [tools/profiler/core/ProfileBufferEntry.cpp:1510]
[task 2023-05-16T02:03:45.472Z] 02:03:45     INFO - GECKO(15135) | #08: ProfiledThreadData::PrepareUniqueStacks(ProfileBuffer const&, JSContext*, mozilla::FailureLatch&, ProfilerCodeAddressService*, mozilla::ProgressLogger) [tools/profiler/core/ProfiledThreadData.cpp:117]
[task 2023-05-16T02:03:45.474Z] 02:03:45     INFO - GECKO(15135) | #09: ThreadStreamingContext::ThreadStreamingContext(ProfiledThreadData&, ProfileBuffer const&, JSContext*, mozilla::FailureLatch&, ProfilerCodeAddressService*, mozilla::ProgressLogger) [tools/profiler/core/ProfiledThreadData.cpp:389]
[task 2023-05-16T02:03:45.475Z] 02:03:45     INFO - GECKO(15135) | #10: mozilla::Vector<ThreadStreamingContext, (unsigned long)0, mozilla::MallocAllocPolicy>::infallibleEmplaceBack<ProfiledThreadData&, ProfileBuffer const&, JSContext*&, mozilla::FailureLatch&, ProfilerCodeAddressService*&, mozilla::ProgressLogger>(ProfiledThreadData&, ProfileBuffer const&, JSContext*&, mozilla::FailureLatch&, ProfilerCodeAddressService*&, mozilla::ProgressLogger) [mfbt/Vector.h:805]
[task 2023-05-16T02:03:45.476Z] 02:03:45     INFO - GECKO(15135) | #11: ProcessStreamingContext::AddThreadStreamingContext(ProfiledThreadData&, ProfileBuffer const&, JSContext*, ProfilerCodeAddressService*, mozilla::ProgressLogger) [tools/profiler/core/ProfiledThreadData.cpp:450]
[task 2023-05-16T02:03:45.478Z] 02:03:45     INFO - GECKO(15135) | #12: locked_profiler_stream_json_for_this_process(PSAutoLock const&, mozilla::baseprofiler::SpliceableJSONWriter&, double, PreRecordedMetaInformation const&, bool, ProfilerCodeAddressService*, mozilla::ProgressLogger) [tools/profiler/core/platform.cpp:3323]
[task 2023-05-16T02:03:45.479Z] 02:03:45     INFO - GECKO(15135) | #13: do_profiler_stream_json_for_this_process(mozilla::baseprofiler::SpliceableJSONWriter&, double, bool, ProfilerCodeAddressService*, mozilla::ProgressLogger) [tools/profiler/core/platform.cpp:3475]
[task 2023-05-16T02:03:45.480Z] 02:03:45     INFO - GECKO(15135) | #14: profiler_stream_json_for_this_process(mozilla::baseprofiler::SpliceableJSONWriter&, double, bool, ProfilerCodeAddressService*, mozilla::ProgressLogger) [tools/profiler/core/platform.cpp:3501]
[task 2023-05-16T02:03:45.481Z] 02:03:45     INFO - GECKO(15135) | #15: WriteProfileToJSONWriter(mozilla::baseprofiler::SpliceableChunkedJSONWriter&, double, bool, ProfilerCodeAddressService*, mozilla::ProgressLogger) [tools/profiler/core/platform.cpp:5386]
[task 2023-05-16T02:03:45.483Z] 02:03:45     INFO - GECKO(15135) | #16: profiler_get_profile_json(mozilla::baseprofiler::SpliceableChunkedJSONWriter&, double, bool, mozilla::ProgressLogger) [tools/profiler/core/platform.cpp:5445]
[task 2023-05-16T02:03:45.488Z] 02:03:45     INFO - GECKO(15135) | #17: mozilla::ProfilerChild::GatherProfileThreadFunction(void*) [tools/profiler/gecko/ProfilerChild.cpp:334]
[task 2023-05-16T02:03:45.500Z] 02:03:45     INFO - GECKO(15135) | #18: _pt_root [nsprpub/pr/src/pthreads/ptthread.c:204]
[task 2023-05-16T02:03:45.599Z] 02:03:45     INFO - GECKO(15135) | #19: set_alt_signal_stack_and_start(PthreadCreateParams*) [mozglue/interposers/pthread_create_interposer.cpp:81]
[task 2023-05-16T02:03:45.601Z] 02:03:45     INFO - GECKO(15135) | #20: ??? [/lib/x86_64-linux-gnu/libpthread.so.0 + 0x76db]
[task 2023-05-16T02:03:45.612Z] 02:03:45     INFO - GECKO(15135) | #21: clone [/lib/x86_64-linux-gnu/libc.so.6 + 0x121a3f]
[task 2023-05-16T02:03:45.612Z] 02:03:45     INFO - GECKO(15135) | #22: ??? (???:???)
[task 2023-05-16T02:03:45.612Z] 02:03:45     INFO - GECKO(15135) | [Parent 15135, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer F48FBF1455821.514A43C3AB25BD64: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:344
[task 2023-05-16T02:03:45.613Z] 02:03:45     INFO - GECKO(15135) | [Parent 15135, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer F48FBF1455821.514A43C3AB25BD64: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:344
[task 2023-05-16T02:03:45.613Z] 02:03:45     INFO - GECKO(15135) | [Parent 15135, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer F48FBF1455821.514A43C3AB25BD64: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:344
[task 2023-05-16T02:03:45.613Z] 02:03:45     INFO - GECKO(15135) | [Parent 15135, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer F48FBF1455821.514A43C3AB25BD64: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:344
[task 2023-05-16T02:03:45.613Z] 02:03:45     INFO - GECKO(15135) | [Parent 15135, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer F48FBF1455821.514A43C3AB25BD64: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:344
[task 2023-05-16T02:03:45.613Z] 02:03:45     INFO - GECKO(15135) | [Parent 15135, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer F48FBF1455821.514A43C3AB25BD64: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:344
[task 2023-05-16T02:03:45.613Z] 02:03:45     INFO - GECKO(15135) | [Parent 15135, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer F48FBF1455821.514A43C3AB25BD64: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:344
[task 2023-05-16T02:03:45.613Z] 02:03:45     INFO - GECKO(15135) | [Parent 15135, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer F48FBF1455821.514A43C3AB25BD64: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:344
[task 2023-05-16T02:03:45.614Z] 02:03:45     INFO - GECKO(15135) | [Parent 15135, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer F48FBF1455821.514A43C3AB25BD64: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:344
[task 2023-05-16T02:03:45.619Z] 02:03:45     INFO - GECKO(15135) | [Parent 15135, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer F48FBF1455821.514A43C3AB25BD64: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:344
[task 2023-05-16T02:03:45.620Z] 02:03:45     INFO - GECKO(15135) | [Parent 15135, Main Thread] WARNING: IPC message 'PBrowser::Msg_Destroy' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:504
[task 2023-05-16T02:03:45.621Z] 02:03:45     INFO - GECKO(15135) | [Parent 15135, Main Thread] WARNING: No build ID mismatch: file /builds/worker/checkouts/gecko/dom/base/nsFrameLoader.cpp:3812
[task 2023-05-16T02:03:45.621Z] 02:03:45     INFO - GECKO(15135) | [Parent 15135, Main Thread] WARNING: IPC message 'PBrowser::Msg_Destroy' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:504
[task 2023-05-16T02:03:45.622Z] 02:03:45     INFO - GECKO(15135) | [Parent 15135, Main Thread] WARNING: No build ID mismatch: file /builds/worker/checkouts/gecko/dom/base/nsFrameLoader.cpp:3812
[task 2023-05-16T02:03:45.622Z] 02:03:45     INFO - GECKO(15135) | [Parent 15135, Main Thread] WARNING: IPC message 'PBrowser::Msg_Destroy' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:504
[task 2023-05-16T02:03:45.623Z] 02:03:45     INFO - GECKO(15135) | [Parent 15135, Main Thread] WARNING: No build ID mismatch: file /builds/worker/checkouts/gecko/dom/base/nsFrameLoader.cpp:3812
[task 2023-05-16T02:03:45.624Z] 02:03:45     INFO - GECKO(15135) | [Parent 15135, Main Thread] WARNING: IPC message 'PBrowser::Msg_Destroy' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:504
[task 2023-05-16T02:03:45.625Z] 02:03:45     INFO - GECKO(15135) | [Parent 15135, Main Thread] WARNING: No build ID mismatch: file /builds/worker/checkouts/gecko/dom/base/nsFrameLoader.cpp:3812
[task 2023-05-16T02:03:45.625Z] 02:03:45     INFO - GECKO(15135) | [Parent 15135, Main Thread] WARNING: IPC Connection Error: [Parent][PContentParent] Send(msgname=PContent::Msg_AsyncMessage) Channel error: cannot send/recv: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1927
[task 2023-05-16T02:03:45.626Z] 02:03:45     INFO - GECKO(15135) | [Parent 15135, Main Thread] WARNING: IPC message 'PBrowser::Msg_Destroy' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:504
[task 2023-05-16T02:03:45.627Z] 02:03:45     INFO - GECKO(15135) | [Parent 15135, ProcessHangMon] WARNING: IPC message 'PProcessHangMonitor::Msg_RequestContentJSInterrupt' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:504
[task 2023-05-16T02:03:45.628Z] 02:03:45     INFO - GECKO(15135) | [Parent 15135, Main Thread] WARNING: No build ID mismatch: file /builds/worker/checkouts/gecko/dom/base/nsFrameLoader.cpp:3812
[task 2023-05-16T02:03:45.629Z] 02:03:45     INFO - GECKO(15135) | [Parent 15135, IPC I/O Parent] WARNING: process 15202 exited on signal 11: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_posix.cc:264
[task 2023-05-16T02:03:45.629Z] 02:03:45     INFO - GECKO(15135) | [Child 15294: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f2f8d337400 == 0 [pid = 15294] [id = 0] [url = about:blank]
[task 2023-05-16T02:03:45.630Z] 02:03:45     INFO - GECKO(15135) | [Parent 15135, 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-16T02:03:45.631Z] 02:03:45     INFO - GECKO(15135) | [Child 15229, ProfilerChild] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:345
[task 2023-05-16T02:03:45.631Z] 02:03:45     INFO - GECKO(15135) | [Utility 15287, ProfilerChild] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:345
[task 2023-05-16T02:03:45.632Z] 02:03:45     INFO - GECKO(15135) | [Child 15294, ProfilerChild] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:345
[task 2023-05-16T02:03:45.633Z] 02:03:45     INFO - GECKO(15135) | [Child 15341, ProfilerChild] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:345
[task 2023-05-16T02:03:45.634Z] 02:03:45     INFO - GECKO(15135) | [Child 15406, ProfilerChild] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:345
[task 2023-05-16T02:03:45.634Z] 02:03:45     INFO - GECKO(15135) | [Child 15474, ProfilerChild] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:345
[task 2023-05-16T02:03:45.635Z] 02:03:45     INFO - GECKO(15135) | [Child 15413, ProfilerChild] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:345
[task 2023-05-16T02:03:45.636Z] 02:03:45     INFO - GECKO(15135) | [Child 15509, ProfilerChild] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:345
[task 2023-05-16T02:03:45.637Z] 02:03:45     INFO - GECKO(15135) | [Child 15406: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (7f05b9a88000) [pid = 15406] [serial = 4] [outer = 7f05bea20470]
[task 2023-05-16T02:03:45.638Z] 02:03:45     INFO - GECKO(15135) | [Parent 15135, 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-16T02:03:45.639Z] 02:03:45     INFO - GECKO(15135) | [Parent 15135, 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-16T02:03:45.639Z] 02:03:45     INFO - GECKO(15135) | [Parent 15135, IPDL Background] WARNING: Not resolving response 'PIdleScheduler::Reply_RequestGC': actor is dead: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:792
[task 2023-05-16T02:03:45.640Z] 02:03:45     INFO - GECKO(15135) | [Child 15406, Main Thread] WARNING: IPC message 'PContent::Msg_CleanupPendingLoadState' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:504
[task 2023-05-16T02:03:45.641Z] 02:03:45     INFO - GECKO(15135) | [Child 15406: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (7f05b9a8c000) [pid = 15406] [serial = 2] [outer = 0] [url = about:blank]
[task 2023-05-16T02:03:45.641Z] 02:03:45     INFO - GECKO(15135) | [Child 15406: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f05b9a8b800 == 0 [pid = 15406] [id = 0] [url = http://example.com/browser/tools/profiler/tests/browser/do_work_500ms.html]
[task 2023-05-16T02:03:45.642Z] 02:03:45     INFO - GECKO(15135) | [Child 15406: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (7f05b9a88000) [pid = 15406] [serial = 4] [outer = 0] [url = http://example.com/browser/tools/profiler/tests/browser/do_work_500ms.html]
[task 2023-05-16T02:03:45.643Z] 02:03:45     INFO - GECKO(15135) | [Child 15406: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7f05b9a8e400) [pid = 15406] [serial = 3] [outer = 0] [url = http://example.com/browser/tools/profiler/tests/browser/do_work_500ms.html]
[task 2023-05-16T02:03:45.644Z] 02:03:45     INFO - GECKO(15135) | [Child 15406: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7f05bea20470) [pid = 15406] [serial = 1] [outer = 0] [url = http://example.com/browser/tools/profiler/tests/browser/do_work_500ms.html]
[task 2023-05-16T02:03:45.645Z] 02:03:45     INFO - GECKO(15135) | [Child 15406, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3426
[task 2023-05-16T02:03:45.645Z] 02:03:45     INFO - GECKO(15135) | [Child 15406, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-05-16T02:03:45.646Z] 02:03:45     INFO - GECKO(15135) | [Child 15406, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4687
[task 2023-05-16T02:03:45.647Z] 02:03:45     INFO - GECKO(15135) | [WARN  rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2023-05-16T02:03:45.647Z] 02:03:45     INFO - GECKO(15135) | console.error: (new Error("Polling for changes failed: Unexpected content-type \"text/plain;charset=US-ASCII\".", "resource://services-settings/remote-settings.sys.mjs", 324))
[task 2023-05-16T02:03:45.648Z] 02:03:45     INFO - GECKO(15135) | MEMORY STAT | vsize 3206MB | residentFast 493MB | heapAllocated 215MB
[task 2023-05-16T02:03:45.649Z] 02:03:45     INFO - TEST-OK | tools/profiler/tests/browser/browser_test_feature_jsallocations.js | took 15112ms

Comment 1

[Jon Coppeard (:jonco)]

It looks like the profiler is looking up JIT code addresses during compacting GC.

(It's the WebExtensions process specifically. Better stack backtraces are here: https://treeherder.mozilla.org/logviewer?job_id=415893157&repo=autoland&lineNumber=38703-39109 )

I thought this would be prevented by the use AutoSuppressProfilerSampling during compacting:

https://searchfox.org/mozilla-central/source/js/src/gc/Compacting.cpp#74-78

However looking at the profiler code around JITFrameInfo::AddInfoForRange I'm not this will have any effect:

https://searchfox.org/mozilla-central/source/tools/profiler/core/ProfileBufferEntry.cpp#582

Do we need more synchronisation with the profiler for this or am I missing how this is supposed to work?

Comment 2

[Intermittent Failures Robot]

1 failures in 3578 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-qr: 1
  • debug: 1

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

Comment 3

[Iain Ireland [:iain]]

Taking a quick look at this: it looks like it's specifically this line that is the problem. We want the realmID for a frame, which calls lookupRealmID, which in this particular backtrace dispatches to BaselineEntry::lookupRealmID. The realmID is stored on the realm, but to get to the realm from the script, we load script_->sourceObject()->shape()->base()->realm(). Reading the shape reads the header pointer, which expects flags_ to be 0, but that's not necessarily the case if we're in the middle of a GC.

It seems generally awkward that we're walking such a long chain of pointers from off-thread. If that's the only thing we need to access, maybe it would be better to store the realmID in the entry directly? It's a bit awkward because BaselineEntry isn't the only kind of entry. BaselineInterpreterEntry::lookupRealmID claims to be unreachable, DummyEntry doesn't store any data, and IonICEntry forwards to IonEntry, but IonEntry looks a little nontrivial to sort out. On the other hand, we don't inline cross-realm calls, so it seems like every script in an IonEntry should have the same realmID anyway? So maybe we could just add a field to BaselineEntry and IonEntry, and cache this information instead of looking it up.

Bug 1468789 looks relevant - particularly comment 11:

Then, at sampling time, when we encounter a JS frame in the stack, we can get the global from the JSScript (I hope this can be done off-thread?) and look up the global in the stored hashmap.

Nazım, do you have any thoughts about this? It looks like you wrote this code.

Comment 4

[Intermittent Failures Robot]

1 failures in 3309 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1833295&startday=2023-07-10&endday=2023-07-16&tree=all

Comment 5

[Intermittent Failures Robot]

1 failures in 3076 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-qr: 1
  • debug: 1

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

Comment 6

[Intermittent Failures Robot]

1 failures in 3337 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• macosx1015-64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1833295&startday=2023-09-11&endday=2023-09-17&tree=all

Comment 7

[Intermittent Failures Robot]

1 failures in 3356 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1833295&startday=2023-10-30&endday=2023-11-05&tree=all

Comment 8

[Intermittent Failures Robot]

1 failures in 4108 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-qr: 1
  • debug: 1

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

Comment 9

[Intermittent Failures Robot]

1 failures in 3459 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1833295&startday=2024-08-05&endday=2024-08-11&tree=all

Comment 10

[Intermittent Failures Robot]

1 failures in 2154 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1833295&startday=2024-08-12&endday=2024-08-18&tree=all

Comment 11

[Intermittent Failures Robot]

1 failures in 3879 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1833295&startday=2024-08-26&endday=2024-09-01&tree=all

Comment 12

[Intermittent Failures Robot]

1 failures in 4448 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1833295&startday=2024-09-09&endday=2024-09-15&tree=all

Comment 13

[Intermittent Failures Robot]

2 failures in 4860 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1
• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-qr: 2
  • debug: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1833295&startday=2024-09-16&endday=2024-09-22&tree=all

Comment 14

[Intermittent Failures Robot]

1 failures in 4889 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1833295&startday=2024-09-23&endday=2024-09-29&tree=all

Comment 15

[Intermittent Failures Robot]

2 failures in 3574 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1
• autoland: 1

Platform and build breakdown:

• linux1804-64-qr: 2
  • debug: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1833295&startday=2024-09-30&endday=2024-10-06&tree=all

Comment 16

[Intermittent Failures Robot]

7 failures in 4579 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 7

Platform and build breakdown:

• linux1804-64-qr: 7
  • debug: 7

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1833295&startday=2024-10-14&endday=2024-10-20&tree=all

Comment 17

[Intermittent Failures Robot]

Summary

7 failures in 3774 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 7

Platform and build breakdown:

• linux1804-64-qr: 7
  • debug: 7

Table

	1proc	1proc-s	1proc-swr	a11y-checks	a11y-checks-s	a11y-checks-swr	a11y-checks-swr-s	condprof-s	fis	fis-hv	fis-hv-s	fis-s	headless	headless-s	http2	http3	mda-gpu	mda-gpu-s	msix	msix-s	no_variant	nofis	nofis-aab	nofis-s	nofis-ship	nofis-ship-s	nofis-spi	nofis-swr	nofis-swr-s	nogpu	nogpu-s	s	spi	spi-nw	spi-nw-1proc	spi-nw-nofis	spi-nw-nofis-s	spi-nw-s	spi-s	swr	swr-s	vt	vt-s	wmfme	wr-dc2-o	xorig	xorig-s
linux1804-64-qr/debug																																		7

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1833295&startday=2024-12-16&endday=2024-12-22&tree=all

Comment 18

[Intermittent Failures Robot]

Summary

1 failures in 1319 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-qr: 1
  • debug: 1

Table

	spi-nw
linux1804-64-qr/debug	1

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1833295&startday=2024-12-30&endday=2025-01-05&tree=all

Comment 19

[Intermittent Failures Robot]

Summary

3 failures in 3682 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 3

Platform and build breakdown:

• linux1804-64-qr: 3
  • debug: 3

Table

	spi-nw
linux1804-64-qr/debug	3

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1833295&startday=2025-01-06&endday=2025-01-12&tree=all

Comment 20

[Intermittent Failures Robot]

Summary

4 failures in 4508 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 4

Platform and build breakdown:

• linux1804-64-qr: 4
  • debug: 4

Table

	spi-nw
linux1804-64-qr/-/debug	4

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1833295&startday=2025-01-13&endday=2025-01-19&tree=all

Comment 21

[Intermittent Failures Robot]

Summary

2 failures in 4035 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 2

Platform and build breakdown:

• linux1804-64-qr: 2
  • debug: 2

Table

	spi-nw
linux1804-64-qr--/debug	2

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1833295&startday=2025-01-20&endday=2025-01-26&tree=all

Comment 22

[Intermittent Failures Robot]

Summary

3 failures in 4566 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 3

Platform and build breakdown:

• linux1804-64-qr: 3
  • debug: 3

Table

	spi-nw
linux1804-64-qr--/debug	3

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1833295&startday=2025-01-27&endday=2025-02-02&tree=all

Comment 23

[Intermittent Failures Robot]

Summary

6 failures in 4309 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 5
• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-qr: 5
  • debug: 5
• macosx1015-64-qr: 1
  • debug: 1

Table

	spi-nw
linux1804-64-qr--/debug	5
macosx1015-64-qr--/debug	1

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1833295&startday=2025-02-03&endday=2025-02-09&tree=all