1877547 - Intermittent MOZ_ASSERT(!mOwningThread) (destroying a still-owned lock!) [@ mozilla::OffTheBooksMutex::~OffTheBooksMutex] | single tracking bug

Status: RESOLVED INCOMPLETE

(Core :: DOM: Workers, defect)

Description

[Treeherder Bug Filer]

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

---

[task 2024-01-30T20:50:53.517Z] 20:50:53     INFO -  TEST-START | dom/xhr/tests/test_bug1752863.html
[task 2024-01-30T20:51:13.684Z] 20:51:13     INFO -  wait for org.mozilla.geckoview.test_runner complete; top activity=com.android.launcher3
[task 2024-01-30T20:51:13.684Z] 20:51:13     INFO -  runtestsremote.py | Application ran for: 0:02:45.178422
[task 2024-01-30T20:51:13.751Z] 20:51:13     INFO -  mozcrash Copy/paste: /builds/worker/fetches/minidump-stackwalk/minidump-stackwalk --symbols-url=https://symbols.mozilla.org/ --cyborg=/tmp/tmpcp11xhn3/7751a57e-7333-9825-1c6b-b828b3bf9659.trace /tmp/tmp2imvfmub/7751a57e-7333-9825-1c6b-b828b3bf9659.dmp /builds/worker/workspace/build/symbols
[task 2024-01-30T20:51:17.460Z] 20:51:17     INFO -  mozcrash Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/7751a57e-7333-9825-1c6b-b828b3bf9659.dmp
[task 2024-01-30T20:51:17.460Z] 20:51:17     INFO -  mozcrash Saved app info as /builds/worker/workspace/build/blobber_upload_dir/7751a57e-7333-9825-1c6b-b828b3bf9659.extra
[task 2024-01-30T20:51:17.461Z] 20:51:17  WARNING -  PROCESS-CRASH | MOZ_ASSERT(!mOwningThread) (destroying a still-owned lock!) [@ mozilla::OffTheBooksMutex::~OffTheBooksMutex] | dom/xhr/tests/mochitest.toml
[task 2024-01-30T20:51:17.461Z] 20:51:17     INFO -  Process type: content
[task 2024-01-30T20:51:17.461Z] 20:51:17     INFO -  Process pid: 8385
[task 2024-01-30T20:51:17.461Z] 20:51:17     INFO -  Mozilla crash reason: MOZ_ASSERT(!mOwningThread) (destroying a still-owned lock!)
[task 2024-01-30T20:51:17.461Z] 20:51:17     INFO -  Crash dump filename: /tmp/tmp2imvfmub/7751a57e-7333-9825-1c6b-b828b3bf9659.dmp
[task 2024-01-30T20:51:17.461Z] 20:51:17     INFO -  Operating system: Android
[task 2024-01-30T20:51:17.461Z] 20:51:17     INFO -                    0.0.0 Linux 3.10.0+ #260 SMP PREEMPT Fri May 19 12:48:14 PDT 2017 x86_64
[task 2024-01-30T20:51:17.461Z] 20:51:17     INFO -  CPU: amd64
[task 2024-01-30T20:51:17.461Z] 20:51:17     INFO -       family 6 model 6 stepping 3
[task 2024-01-30T20:51:17.461Z] 20:51:17     INFO -       4 CPUs
[task 2024-01-30T20:51:17.461Z] 20:51:17     INFO -  Crash reason:  SIGSEGV / SEGV_MAPERR
[task 2024-01-30T20:51:17.461Z] 20:51:17     INFO -  Crash address: 0x0000000000000000
[task 2024-01-30T20:51:17.461Z] 20:51:17     INFO -  Crashing instruction: `mov dword [0x0], 0x3a`
[task 2024-01-30T20:51:17.461Z] 20:51:17     INFO -  Memory accessed by instruction:
[task 2024-01-30T20:51:17.461Z] 20:51:17     INFO -    0. Address: 0x0000000000000000
[task 2024-01-30T20:51:17.461Z] 20:51:17     INFO -       Size: 4
[task 2024-01-30T20:51:17.461Z] 20:51:17     INFO -  Crashing address may be the result of a flipped bit:
[task 2024-01-30T20:51:17.461Z] 20:51:17     INFO -    0. Valid address: 0x0000000020000000 (0.250)
[task 2024-01-30T20:51:17.461Z] 20:51:17     INFO -    1. Valid address: 0x0000000040000000 (0.250)
[task 2024-01-30T20:51:17.461Z] 20:51:17     INFO -  Process uptime: not available
[task 2024-01-30T20:51:17.462Z] 20:51:17     INFO -  Thread 33 DOM Worker (crashed)
[task 2024-01-30T20:51:17.462Z] 20:51:17     INFO -   0  libxul.so!mozilla::OffTheBooksMutex::~OffTheBooksMutex() [Mutex.h:06b0ce1a9748e18deb48466efed01c1f5a8ff984 : 58 + 0x0]
[task 2024-01-30T20:51:17.462Z] 20:51:17     INFO -       rax = 0x00007f8ac22c0d8c    rdx = 0x00007f8ab8ffc040
[task 2024-01-30T20:51:17.462Z] 20:51:17     INFO -       rcx = 0x00007f8ae5df9c20    rbx = 0x00007f8aba1a69d0
[task 2024-01-30T20:51:17.462Z] 20:51:17     INFO -       rsi = 0x0000000000000001    rdi = 0x00007f8ab8ffc0c0
[task 2024-01-30T20:51:17.462Z] 20:51:17     INFO -       rbp = 0x00007f8ab8ffc470    rsp = 0x00007f8ab8ffc460
[task 2024-01-30T20:51:17.462Z] 20:51:17     INFO -        r8 = 0x00007f8ae5cbe75c     r9 = 0x00007f8ab8ffbd50
[task 2024-01-30T20:51:17.462Z] 20:51:17     INFO -       r10 = 0x00000000ffffff80    r11 = 0x0000000000000246
[task 2024-01-30T20:51:17.462Z] 20:51:17     INFO -       r12 = 0x00007f8ac214ebcf    r13 = 0x0000000000000004
[task 2024-01-30T20:51:17.462Z] 20:51:17     INFO -       r14 = 0x00007f8ab9380b98    r15 = 0x00007f8ab8ffc5a0
[task 2024-01-30T20:51:17.462Z] 20:51:17     INFO -       rip = 0x00007f8ac5be1368
[task 2024-01-30T20:51:17.462Z] 20:51:17     INFO -      Found by: given as instruction pointer in context
[task 2024-01-30T20:51:17.462Z] 20:51:17     INFO -   1  libxul.so!mozilla::dom::StrongWorkerRef::~StrongWorkerRef() [WorkerRef.cpp:06b0ce1a9748e18deb48466efed01c1f5a8ff984 : 193 + 0xd]
[task 2024-01-30T20:51:17.462Z] 20:51:17     INFO -       rbx = 0x00007f8aba1a69d0    rbp = 0x00007f8ab8ffc490
[task 2024-01-30T20:51:17.462Z] 20:51:17     INFO -       rsp = 0x00007f8ab8ffc480    r12 = 0x00007f8ac214ebcf
[task 2024-01-30T20:51:17.462Z] 20:51:17     INFO -       r13 = 0x0000000000000004    r14 = 0x00007f8ab9380b98
[task 2024-01-30T20:51:17.462Z] 20:51:17     INFO -       r15 = 0x00007f8ab8ffc5a0    rip = 0x00007f8ac8996372
[task 2024-01-30T20:51:17.462Z] 20:51:17     INFO -      Found by: call frame info
[task 2024-01-30T20:51:17.463Z] 20:51:17     INFO -   2  libxul.so!mozilla::dom::WorkerRef::Release() [WorkerRef.h:06b0ce1a9748e18deb48466efed01c1f5a8ff984 : 123 + 0x8]
[task 2024-01-30T20:51:17.463Z] 20:51:17     INFO -       rbx = 0x00007f8aba1a69d0    rbp = 0x00007f8ab8ffc4b0
[task 2024-01-30T20:51:17.463Z] 20:51:17     INFO -       rsp = 0x00007f8ab8ffc4a0    r12 = 0x00007f8ac214ebcf
[task 2024-01-30T20:51:17.463Z] 20:51:17     INFO -       r13 = 0x0000000000000004    r14 = 0x00007f8ab9380b98
[task 2024-01-30T20:51:17.463Z] 20:51:17     INFO -       r15 = 0x00007f8ab8ffc5a0    rip = 0x00007f8ac6ac1251
[task 2024-01-30T20:51:17.463Z] 20:51:17     INFO -      Found by: call frame info
[task 2024-01-30T20:51:17.463Z] 20:51:17     INFO -   3  libxul.so!RefPtr<mozilla::dom::StrongWorkerRef>::operator=(std::nullptr_t) [RefPtr.h:06b0ce1a9748e18deb48466efed01c1f5a8ff984 : 180]
[task 2024-01-30T20:51:17.463Z] 20:51:17     INFO -      Found by: inlining
[task 2024-01-30T20:51:17.463Z] 20:51:17     INFO -   4  libxul.so!mozilla::dom::XMLHttpRequestWorker::Unpin() [XMLHttpRequestWorker.cpp:06b0ce1a9748e18deb48466efed01c1f5a8ff984 : 1635 + 0x9]
[task 2024-01-30T20:51:17.463Z] 20:51:17     INFO -       rbx = 0x00007f8ab9380b00    rbp = 0x00007f8ab8ffc4d0
[task 2024-01-30T20:51:17.463Z] 20:51:17     INFO -       rsp = 0x00007f8ab8ffc4c0    r12 = 0x00007f8ac214ebcf
[task 2024-01-30T20:51:17.463Z] 20:51:17     INFO -       r13 = 0x0000000000000004    r14 = 0x00007f8ab9380b98
[task 2024-01-30T20:51:17.463Z] 20:51:17     INFO -       r15 = 0x00007f8ab8ffc5a0    rip = 0x00007f8ac8b6d17b
[task 2024-01-30T20:51:17.463Z] 20:51:17     INFO -      Found by: call frame info
[task 2024-01-30T20:51:17.463Z] 20:51:17     INFO -   5  libxul.so!mozilla::dom::XMLHttpRequestWorker::ReleaseProxy(mozilla::dom::XMLHttpRequestWorker::ReleaseType) [XMLHttpRequestWorker.cpp:06b0ce1a9748e18deb48466efed01c1f5a8ff984 : 1462 + 0x7]
[task 2024-01-30T20:51:17.464Z] 20:51:17     INFO -       rbx = 0x00007f8ab9380ba8    rbp = 0x00007f8ab8ffc540
[task 2024-01-30T20:51:17.464Z] 20:51:17     INFO -       rsp = 0x00007f8ab8ffc4e0    r12 = 0x00007f8ac214ebcf
[task 2024-01-30T20:51:17.464Z] 20:51:17     INFO -       r13 = 0x0000000000000004    r14 = 0x00007f8ab9380b00
[task 2024-01-30T20:51:17.464Z] 20:51:17     INFO -       r15 = 0x00007f8ab8ffc5a0    rip = 0x00007f8ac8b6cb4a
[task 2024-01-30T20:51:17.464Z] 20:51:17     INFO -      Found by: call frame info
[task 2024-01-30T20:51:17.464Z] 20:51:17     INFO -   6  libxul.so!fu2::abi_400::detail::type_erasure::tables::vtable<fu2::abi_400::detail::property<false, false, void ()> >::invoke<(unsigned long)0, fu2::abi_400::detail::type_erasure::data_accessor*, unsigned long const&>(fu2::abi_400::detail::type_erasure::data_accessor*, unsigned long const&) const [function2.hpp:06b0ce1a9748e18deb48466efed01c1f5a8ff984 : 1046]
[task 2024-01-30T20:51:17.464Z] 20:51:17     INFO -      Found by: inlining
[task 2024-01-30T20:51:17.464Z] 20:51:17     INFO -   7  libxul.so!fu2::abi_400::detail::type_erasure::erasure<true, fu2::abi_400::detail::config<true, false, fu2::capacity_fixed<(unsigned long)16, (unsigned long)8> >, fu2::abi_400::detail::property<false, false, void ()> >::invoke<(unsigned long)0, fu2::abi_400::detail::type_erasure::erasure<true, fu2::abi_400::detail::config<true, false, fu2::capacity_fixed<(unsigned long)16, (unsigned long)8> >, fu2::abi_400::detail::property<false, false, void ()> >&, >(fu2::abi_400::detail::type_erasure::erasure<true, fu2::abi_400::detail::config<true, false, fu2::capacity_fixed<(unsigned long)16, (unsigned long)8> >, fu2::abi_400::detail::property<false, false, void ()> >&, &&) [function2.hpp:06b0ce1a9748e18deb48466efed01c1f5a8ff984 : 1268]
[task 2024-01-30T20:51:17.464Z] 20:51:17     INFO -      Found by: inlining
[task 2024-01-30T20:51:17.465Z] 20:51:17     INFO -   8  libxul.so!fu2::abi_400::detail::type_erasure::invocation_table::operator_impl<(unsigned long)0, fu2::abi_400::detail::function<fu2::abi_400::detail::config<true, false, fu2::capacity_fixed<(unsigned long)16, (unsigned long)8> >, fu2::abi_400::detail::property<false, false, void ()> >, void ()>::operator()() [function2.hpp:06b0ce1a9748e18deb48466efed01c1f5a8ff984 : 826]
[task 2024-01-30T20:51:17.465Z] 20:51:17     INFO -      Found by: inlining
[task 2024-01-30T20:51:17.465Z] 20:51:17     INFO -   9  libxul.so!mozilla::dom::WorkerRef::Notify() [WorkerRef.cpp:06b0ce1a9748e18deb48466efed01c1f5a8ff984 : 105 + 0x5]
[task 2024-01-30T20:51:17.465Z] 20:51:17     INFO -       rbx = 0x00007f8ab8ffc550    rbp = 0x00007f8ab8ffc580
[task 2024-01-30T20:51:17.465Z] 20:51:17     INFO -       rsp = 0x00007f8ab8ffc550    r12 = 0x00007f8ac214ebcf
[task 2024-01-30T20:51:17.465Z] 20:51:17     INFO -       r13 = 0x0000000000000004    r14 = 0x0000000000000003
[task 2024-01-30T20:51:17.465Z] 20:51:17     INFO -       r15 = 0x00007f8ab8ffc5a0    rip = 0x00007f8ac8995f22
[task 2024-01-30T20:51:17.465Z] 20:51:17     INFO -      Found by: call frame info
[task 2024-01-30T20:51:17.465Z] 20:51:17     INFO -  10  libxul.so!mozilla::dom::WorkerPrivate::NotifyWorkerRefs(mozilla::dom::WorkerStatus) [WorkerPrivate.cpp:06b0ce1a9748e18deb48466efed01c1f5a8ff984 : 4244 + 0x8]
[task 2024-01-30T20:51:17.465Z] 20:51:17     INFO -       rbx = 0x00007f8aba1a69d0    rbp = 0x00007f8ab8ffc950
[task 2024-01-30T20:51:17.465Z] 20:51:17     INFO -       rsp = 0x00007f8ab8ffc590    r12 = 0x00007f8ac214ebcf
[task 2024-01-30T20:51:17.465Z] 20:51:17     INFO -       r13 = 0x0000000000000004    r14 = 0x0000000000000003
[task 2024-01-30T20:51:17.465Z] 20:51:17     INFO -       r15 = 0x00007f8ab8ffc5a0    rip = 0x00007f8ac898b728
[task 2024-01-30T20:51:17.465Z] 20:51:17     INFO -      Found by: call frame info
[task 2024-01-30T20:51:17.466Z] 20:51:17     INFO -  11  libxul.so!mozilla::dom::WorkerPrivate::NotifyInternal(mozilla::dom::WorkerStatus) [WorkerPrivate.cpp:06b0ce1a9748e18deb48466efed01c1f5a8ff984 : 5034 + 0x7]
[task 2024-01-30T20:51:17.466Z] 20:51:17     INFO -       rbx = 0x00007f8ab936bd00    rbp = 0x00007f8ab8ffc9a0
[task 2024-01-30T20:51:17.466Z] 20:51:17     INFO -       rsp = 0x00007f8ab8ffc960    r12 = 0x0000000000000000
[task 2024-01-30T20:51:17.466Z] 20:51:17     INFO -       r13 = 0x00007f8ab933c8d0    r14 = 0x0000000000000003
[task 2024-01-30T20:51:17.466Z] 20:51:17     INFO -       r15 = 0x00007f8ab933c8d0    rip = 0x00007f8ac898cbf8
[task 2024-01-30T20:51:17.466Z] 20:51:17     INFO -      Found by: call frame info
[task 2024-01-30T20:51:17.466Z] 20:51:17     INFO -  12  libxul.so!mozilla::dom::WorkerRunnable::Run() [WorkerRunnable.cpp:06b0ce1a9748e18deb48466efed01c1f5a8ff984 : 389 + 0x13]
[task 2024-01-30T20:51:17.466Z] 20:51:17     INFO -       rbx = 0x00007f8ab937d150    rbp = 0x00007f8ab8ffcb20
[task 2024-01-30T20:51:17.466Z] 20:51:17     INFO -       rsp = 0x00007f8ab8ffc9b0    r12 = 0x00007f8ab8ffc900
[task 2024-01-30T20:51:17.466Z] 20:51:17     INFO -       r13 = 0x0000000000000001    r14 = 0x00007f8ab8ffca00
[task 2024-01-30T20:51:17.466Z] 20:51:17     INFO -       r15 = 0x00007f8adf735500    rip = 0x00007f8ac89972a6
[task 2024-01-30T20:51:17.466Z] 20:51:17     INFO -      Found by: call frame info
[task 2024-01-30T20:51:17.466Z] 20:51:17     INFO -  13  libxul.so!mozilla::dom::WorkerPrivate::ProcessAllControlRunnablesLocked() [WorkerPrivate.cpp:06b0ce1a9748e18deb48466efed01c1f5a8ff984 : 4014 + 0xc]
[task 2024-01-30T20:51:17.466Z] 20:51:17     INFO -       rbx = 0x00007f8ab936c168    rbp = 0x00007f8ab8ffcbe0
[task 2024-01-30T20:51:17.466Z] 20:51:17     INFO -       rsp = 0x00007f8ab8ffcb30    r12 = 0xaaaaaaaaaaaaaaaa
[task 2024-01-30T20:51:17.466Z] 20:51:17     INFO -       r13 = 0x00007f8ab933c8d0    r14 = 0x00007f8ab936bd68
[task 2024-01-30T20:51:17.466Z] 20:51:17     INFO -       r15 = 0x0000000000000000    rip = 0x00007f8ac898c939
[task 2024-01-30T20:51:17.466Z] 20:51:17     INFO -      Found by: call frame info
[task 2024-01-30T20:51:17.467Z] 20:51:17     INFO -  14  libxul.so!mozilla::dom::WorkerPrivate::RunCurrentSyncLoop() [WorkerPrivate.cpp:06b0ce1a9748e18deb48466efed01c1f5a8ff984 : 4557 + 0x7]
[task 2024-01-30T20:51:17.467Z] 20:51:17     INFO -       rbx = 0x00007f8ab936bd00    rbp = 0x00007f8ab8ffcc70
[task 2024-01-30T20:51:17.467Z] 20:51:17     INFO -       rsp = 0x00007f8ab8ffcbf0    r12 = 0x00007f8ab933c8d0
[task 2024-01-30T20:51:17.467Z] 20:51:17     INFO -       r13 = 0x00007f8ab936c168    r14 = 0x0000000000000000
[task 2024-01-30T20:51:17.467Z] 20:51:17     INFO -       r15 = 0x00007f8ab93449e0    rip = 0x00007f8ac89900e9
[task 2024-01-30T20:51:17.467Z] 20:51:17     INFO -      Found by: call frame info
[task 2024-01-30T20:51:17.467Z] 20:51:17     INFO -  15  libxul.so!mozilla::dom::AutoSyncLoopHolder::Run() [WorkerPrivate.h:06b0ce1a9748e18deb48466efed01c1f5a8ff984 : 1654 + 0x4]
[task 2024-01-30T20:51:17.467Z] 20:51:17     INFO -       rbx = 0x00007f8ab8ffcce0    rbp = 0x00007f8ab8ffcd60
[task 2024-01-30T20:51:17.467Z] 20:51:17     INFO -       rsp = 0x00007f8ab8ffcc80    r12 = 0x00007f8ab9380ba8
[task 2024-01-30T20:51:17.467Z] 20:51:17     INFO -       r13 = 0x00007f8ab9380bb8    r14 = 0x00007f8ab8ffcdc0
[task 2024-01-30T20:51:17.467Z] 20:51:17     INFO -       r15 = 0x00007f8ab8ffcc88    rip = 0x00007f8ac7d35625
[task 2024-01-30T20:51:17.467Z] 20:51:17     INFO -      Found by: call frame info
[task 2024-01-30T20:51:17.467Z] 20:51:17     INFO -  16  libxul.so!mozilla::dom::XMLHttpRequestWorker::SendInternal(mozilla::dom::BodyExtractorBase const*, mozilla::ErrorResult&) [XMLHttpRequestWorker.cpp:06b0ce1a9748e18deb48466efed01c1f5a8ff984 : 1726 + 0xc]
[task 2024-01-30T20:51:17.467Z] 20:51:17     INFO -       rbx = 0x00007f8ab8ffcfc0    rbp = 0x00007f8ab8ffcf50
[task 2024-01-30T20:51:17.467Z] 20:51:17     INFO -       rsp = 0x00007f8ab8ffcd70    r12 = 0x00007f8ab9380ba8
[task 2024-01-30T20:51:17.467Z] 20:51:17     INFO -       r13 = 0x00007f8ab9380bb8    r14 = 0x00007f8ab9380b00
[task 2024-01-30T20:51:17.468Z] 20:51:17     INFO -       r15 = 0x00007f8ab8ffcf01    rip = 0x00007f8ac8b6dfe7
[task 2024-01-30T20:51:17.468Z] 20:51:17     INFO -      Found by: call frame info
[task 2024-01-30T20:51:17.468Z] 20:51:17     INFO -  17  libxul.so!mozilla::dom::XMLHttpRequestWorker::Send(mozilla::dom::Nullable<mozilla::dom::DocumentOrBlobOrArrayBufferViewOrArrayBufferOrFormDataOrURLSearchParamsOrUSVString> const&, mozilla::ErrorResult&) [BodyExtractor.h:06b0ce1a9748e18deb48466efed01c1f5a8ff984 : 0 + 0x15]
[task 2024-01-30T20:51:17.468Z] 20:51:17     INFO -       rbx = 0x00007f8ab9380b00    rbp = 0x00007f8ab8ffcfa0
[task 2024-01-30T20:51:17.468Z] 20:51:17     INFO -       rsp = 0x00007f8ab8ffcf60    r12 = 0x00007f8ab9380bb8
[task 2024-01-30T20:51:17.468Z] 20:51:17     INFO -       r13 = 0x00000000000003e1    r14 = 0x00007f8ab8ffcfc0
[task 2024-01-30T20:51:17.468Z] 20:51:17     INFO -       r15 = 0x00007f8ab8ffd000    rip = 0x00007f8ac8b6efa1
[task 2024-01-30T20:51:17.468Z] 20:51:17     INFO -      Found by: call frame info
[task 2024-01-30T20:51:17.468Z] 20:51:17     INFO -  18  libxul.so!mozilla::dom::XMLHttpRequest_Binding::send(JSContext*, JS::Handle<JSObject*>, void*, JSJitMethodCallArgs const&) [XMLHttpRequestBinding.cpp: : 1679 + 0x15]
[task 2024-01-30T20:51:17.468Z] 20:51:17     INFO -       rbx = 0x00007f8ab8ffd130    rbp = 0x00007f8ab8ffd0d0
[task 2024-01-30T20:51:17.468Z] 20:51:17     INFO -       rsp = 0x00007f8ab8ffcfb0    r12 = 0x00007f8ab8ffd100
[task 2024-01-30T20:51:17.468Z] 20:51:17     INFO -       r13 = 0x00000000000003e1    r14 = 0x00007f8ab9380b00
[task 2024-01-30T20:51:17.468Z] 20:51:17     INFO -       r15 = 0x00007f8ab8ffcfc0    rip = 0x00007f8ac763b8b1
[task 2024-01-30T20:51:17.468Z] 20:51:17     INFO -      Found by: call frame info
[task 2024-01-30T20:51:17.469Z] 20:51:17     INFO -  19  libxul.so!mozilla::dom::binding_detail::GenericMethod<mozilla::dom::binding_detail::NormalThisPolicy, mozilla::dom::binding_detail::ThrowExceptions>(JSContext*, unsigned int, JS::Value*) [BindingUtils.cpp:06b0ce1a9748e18deb48466efed01c1f5a8ff984 : 3258 + 0x1]
[task 2024-01-30T20:51:17.469Z] 20:51:17     INFO -       rbx = 0x00007f8accb3d470    rbp = 0x00007f8ab8ffd180
[task 2024-01-30T20:51:17.469Z] 20:51:17     INFO -       rsp = 0x00007f8ab8ffd0e0    r12 = 0x00007f8ab8ffd100
[task 2024-01-30T20:51:17.469Z] 20:51:17     INFO -       r13 = 0x00000000000003e1    r14 = 0x00000000000003e1
[task 2024-01-30T20:51:17.469Z] 20:51:17     INFO -       r15 = 0x00007f8ab8ffd128    rip = 0x00007f8ac790d5e1
[task 2024-01-30T20:51:17.469Z] 20:51:17     INFO -      Found by: call frame info
[task 2024-01-30T20:51:17.469Z] 20:51:17     INFO -  20  libxul.so!CallJSNative(JSContext*, bool (*)(JSContext*, unsigned int, JS::Value*), js::CallReason, JS::CallArgs const&) [Interpreter.cpp:06b0ce1a9748e18deb48466efed01c1f5a8ff984 : 480 + 0x5]
[task 2024-01-30T20:51:17.469Z] 20:51:17     INFO -       rbx = 0x00007f8adf735500    rbp = 0x00007f8ab8ffd1d0
[task 2024-01-30T20:51:17.469Z] 20:51:17     INFO -       rsp = 0x00007f8ab8ffd190    r12 = 0x00007f8ab9438400
[task 2024-01-30T20:51:17.469Z] 20:51:17     INFO -       r13 = 0x0000109e8bf5f4a0    r14 = 0x00007f8ab8ffd690
[task 2024-01-30T20:51:17.469Z] 20:51:17     INFO -       r15 = 0x00007f8ac790d496    rip = 0x00007f8aca07b90e
[task 2024-01-30T20:51:17.469Z] 20:51:17     INFO -      Found by: call frame info
[task 2024-01-30T20:51:17.469Z] 20:51:17     INFO -  21  libxul.so!js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) [Interpreter.cpp:06b0ce1a9748e18deb48466efed01c1f5a8ff984 : 574 + 0x13]
[task 2024-01-30T20:51:17.469Z] 20:51:17     INFO -       rbx = 0x00007f8adf735500    rbp = 0x00007f8ab8ffd270
[task 2024-01-30T20:51:17.469Z] 20:51:17     INFO -       rsp = 0x00007f8ab8ffd1e0    r12 = 0x0000109e8bf5f4a0
[task 2024-01-30T20:51:17.469Z] 20:51:17     INFO -       r13 = 0xffff800000000000    r14 = 0x00007f8ab8ffd690
[task 2024-01-30T20:51:17.470Z] 20:51:17     INFO -       r15 = 0x00007f8ac790d496    rip = 0x00007f8aca07b129
[task 2024-01-30T20:51:17.470Z] 20:51:17     INFO -      Found by: call frame info
[task 2024-01-30T20:51:17.470Z] 20:51:17     INFO -  22  libxul.so!js::CallFromStack(JSContext*, JS::CallArgs const&, js::CallReason) [Interpreter.cpp:06b0ce1a9748e18deb48466efed01c1f5a8ff984 : 646]
[task 2024-01-30T20:51:17.470Z] 20:51:17     INFO -      Found by: inlining
[task 2024-01-30T20:51:17.470Z] 20:51:17     INFO -  23  libxul.so!js::Interpret(JSContext*, js::RunState&) [Interpreter.cpp:06b0ce1a9748e18deb48466efed01c1f5a8ff984 : 3061 + 0x4]
[task 2024-01-30T20:51:17.470Z] 20:51:17     INFO -       rbx = 0x0000000000000000    rbp = 0x00007f8ab8ffd700
[task 2024-01-30T20:51:17.470Z] 20:51:17     INFO -       rsp = 0x00007f8ab8ffd280    r12 = 0xffff800000000000
[task 2024-01-30T20:51:17.470Z] 20:51:17     INFO -       r13 = 0x0000000000000003    r14 = 0x0000109e8bf5f4a0
[task 2024-01-30T20:51:17.470Z] 20:51:17     INFO -       r15 = 0x00007f8ab8ffd4b0    rip = 0x00007f8aca08c94d
[task 2024-01-30T20:51:17.470Z] 20:51:17     INFO -      Found by: call frame info
[task 2024-01-30T20:51:17.470Z] 20:51:17     INFO -  24  libxul.so!js::RunScript(JSContext*, js::RunState&) [Interpreter.cpp:06b0ce1a9748e18deb48466efed01c1f5a8ff984 : 452 + 0xa]
[task 2024-01-30T20:51:17.470Z] 20:51:17     INFO -       rbx = 0x00007f8adf735500    rbp = 0x00007f8ab8ffd760
[task 2024-01-30T20:51:17.470Z] 20:51:17     INFO -       rsp = 0x00007f8ab8ffd710    r12 = 0x0000000000000000
[task 2024-01-30T20:51:17.470Z] 20:51:17     INFO -       r13 = 0x00007f8adf78a7c1    r14 = 0x00007f8ab8ffd7a0
[task 2024-01-30T20:51:17.470Z] 20:51:17     INFO -       r15 = 0x0000007f6fddf506    rip = 0x00007f8aca07a76d
[task 2024-01-30T20:51:17.470Z] 20:51:17     INFO -      Found by: call frame info
[task 2024-01-30T20:51:17.471Z] 20:51:17     INFO -  25  libxul.so!js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) [Interpreter.cpp:06b0ce1a9748e18deb48466efed01c1f5a8ff984 : 606 + 0x7]
[task 2024-01-30T20:51:17.471Z] 20:51:17     INFO -       rbx = 0x00007f8adf735500    rbp = 0x00007f8ab8ffd800
[task 2024-01-30T20:51:17.471Z] 20:51:17     INFO -       rsp = 0x00007f8ab8ffd770    r12 = 0x0000000000000000
[task 2024-01-30T20:51:17.471Z] 20:51:17     INFO -       r13 = 0x00002cfc1c80c348    r14 = 0x00007f8ab8ffd888
[task 2024-01-30T20:51:17.471Z] 20:51:17     INFO -       r15 = 0x00007f8ab9438400    rip = 0x00007f8aca07b049
[task 2024-01-30T20:51:17.471Z] 20:51:17     INFO -      Found by: call frame info
[task 2024-01-30T20:51:17.471Z] 20:51:17     INFO -  26  libxul.so!js::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, js::AnyInvokeArgs const&, JS::MutableHandle<JS::Value>, js::CallReason) [Interpreter.cpp:06b0ce1a9748e18deb48466efed01c1f5a8ff984 : 673 + 0xd]
[task 2024-01-30T20:51:17.471Z] 20:51:17     INFO -       rbx = 0x0000109e8bf07030    rbp = 0x00007f8ab8ffd860
<...>
[task 2024-01-30T20:51:17.482Z] 20:51:17     INFO -      Found by: stack scanning
[task 2024-01-30T20:51:17.482Z] 20:51:17     INFO -  60  libc.so + 0x1ca65
[task 2024-01-30T20:51:17.483Z] 20:51:17     INFO -       rsp = 0x00007f8ab8fff450    rip = 0x00007f8ae9861a66
[task 2024-01-30T20:51:17.483Z] 20:51:17     INFO -      Found by: stack scanning
[task 2024-01-30T20:51:17.483Z] 20:51:17     INFO -  61  libmozglue.so!getdelim [getline_interposer.cpp:06b0ce1a9748e18deb48466efed01c1f5a8ff984 : 105 + 0x5]
[task 2024-01-30T20:51:17.483Z] 20:51:17     INFO -       rsp = 0x00007f8ab8fff4b8    rip = 0x00007f8ae5d24e3a
[task 2024-01-30T20:51:17.483Z] 20:51:17     INFO -      Found by: stack scanning
[task 2024-01-30T20:51:17.483Z] 20:51:17     INFO -  62  0x7f8ab93905cf
[task 2024-01-30T20:51:17.483Z] 20:51:17     INFO -       rbp = 0x00007f8ae5d24e3a    rsp = 0x00007f8ab8fff4c0
[task 2024-01-30T20:51:17.483Z] 20:51:17     INFO -       rip = 0x00007f8ab93905d0
[task 2024-01-30T20:51:17.483Z] 20:51:17     INFO -      Found by: call frame info
[task 2024-01-30T20:51:17.483Z] 20:51:17     INFO -  Thread 0 st_runner:tab35

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 5
• mozilla-central: 1

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 5
  • debug: 5
• macosx1015-64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1877547&startday=2024-01-29&endday=2024-02-04&tree=all

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1
• autoland: 5

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 5
  • debug: 5
• windows11-64-2009-qr: 1
  • debug: 1

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

Comment 3

[Valentin Gosu [:valentin] (he/him) {{ AFK in July }}]

I can't figure out by looking at the code exactly which mutex is still locked.

Comment 4

[Andrew Sutherland [:asuth] (he/him)]

Not looking at this exact code, but having looked at a related idiom yesterday with :smaug, I would say there there is potentially always an interesting race here between stopping a sync loop (which is targeted at the nested event queue) and the ThreadSafeWorkerRef which is a control runnable and therefore can end up running before a sync loop runnable.

Like if we have:

1. dispatch stop sync loop runnable at the nested event queue
2. release ThreadSafeWorkerRef

The apparent sequence of events on the worker can be 2 running followed by 1. Which could be something that was assumed impossible by the code author, and could be related to the mutex usage.

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 6
• mozilla-central: 1

Platform and build breakdown:

• macosx1015-64-qr: 3
  • debug: 3
• android-em-7-0-x86_64-qr: 4
  • debug: 4

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

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 3
• mozilla-central: 2

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 4
  • debug: 4
• linux1804-64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1877547&startday=2024-02-19&endday=2024-02-25&tree=all

Comment 8

[Intermittent Failures Robot]

2 failures in 4052 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:

• android-em-7-0-x86_64-qr: 2
  • debug: 2

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

Comment 9

[Jens Stutte [:jstutte]]

(In reply to Valentin Gosu [:valentin] (he/him) from comment #3)

I can't figure out by looking at the code exactly which mutex is still locked.

FWIW, the mutex in question is only active in DEBUG and indeed all the intermittent failures captured here are from StrongWorkerRef on DEBUG builds.

However, the crashes from the wild are all unrelated and might deserve a prefix to be categorized better. Volume is not super-high, but maybe worth a second look.

Comment 10

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

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.