Closed Bug 1765822 Opened 2 years ago Closed 2 years ago

High freq xpcshell <random_test> | application crashed [@ nsTimerImpl::InitCommon(mozilla::BaseTimeDuration<mozilla::TimeDurationValueCalculator> const&, unsigned int, mozilla::Variant<nsTimerImpl::UnknownCallback, nsCOMPtr<nsITimerCallba

Categories

(WebExtensions :: General, defect, P5)

Product:
WebExtensions
Component:
General
Type:
defect

Tracking

(firefox-esr91 unaffected, firefox99 unaffected, firefox100 unaffected, firefox101 fixed)

Status:
RESOLVED FIXED
Milestone:
101 Branch
Tracking Flags:
Tracking Status
firefox-esr91 --- unaffected
firefox99 --- unaffected
firefox100 --- unaffected
firefox101 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: jstutte)

References

(Regression)

Details

(Keywords: intermittent-failure, regression)

Attachments

(1 file)

Bug 1765822: Do not assert but just bail out on missing event target in nsTimerImpl::InitCommon. r?smaug
48 bytes, text/x-phabricator-request
Details | Review

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

[task 2022-04-21T15:29:48.465Z] 15:29:48     INFO -  TEST-START | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contexts_gc.js
[task 2022-04-21T15:29:49.970Z] 15:29:49  WARNING -  PROCESS-CRASH | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_brokenlinks.js | application crashed [@ nsTimerImpl::InitCommon(mozilla::BaseTimeDuration<mozilla::TimeDurationValueCalculator> const&, unsigned int, mozilla::Variant<nsTimerImpl::UnknownCallback, nsCOMPtr<nsITimerCallback>, nsCOMPtr<nsIObserver>, nsTimerImpl::FuncCallback, nsTimerImpl::ClosureCallback>&&, mozilla::detail::BaseAutoLock<mozilla::Mutex&> const&)]
[task 2022-04-21T15:29:49.970Z] 15:29:49     INFO -  Crash dump filename: /tmp/xpc-other-76oyol32/581201bc-924c-b389-ea63-1e28e6c76736.dmp
[task 2022-04-21T15:29:49.970Z] 15:29:49     INFO -  Operating system: Linux
[task 2022-04-21T15:29:49.970Z] 15:29:49     INFO -                    4.4.0-1014-aws #14taskcluster1-Ubuntu SMP Tue Apr 3 10:27:00 UTC 2018
[task 2022-04-21T15:29:49.970Z] 15:29:49     INFO -  CPU: amd64
[task 2022-04-21T15:29:49.970Z] 15:29:49     INFO -       family 6 model 85 stepping 4
[task 2022-04-21T15:29:49.971Z] 15:29:49     INFO -       2 CPUs
[task 2022-04-21T15:29:49.971Z] 15:29:49     INFO -  Linux Ubuntu 18.04 - bionic (Ubuntu 18.04.6 LTS)
[task 2022-04-21T15:29:49.971Z] 15:29:49     INFO -  Crash reason:  SIGSEGV / SEGV_MAPERR
[task 2022-04-21T15:29:49.971Z] 15:29:49     INFO -  Crash address: 0x0
[task 2022-04-21T15:29:49.971Z] 15:29:49     INFO -  Process uptime: not available
[task 2022-04-21T15:29:49.971Z] 15:29:49     INFO -  Thread 0 Web Content (crashed)
[task 2022-04-21T15:29:49.971Z] 15:29:49     INFO -   0  libxul.so!Abort(char const*) [nsDebugImpl.cpp:5ea7c27a36a06c21b66344008612ad738a5a61c6 : 450 + 0x17]
[task 2022-04-21T15:29:49.971Z] 15:29:49     INFO -       rax = 0x000055651af8dad8    rdx = 0x0000000000000000
[task 2022-04-21T15:29:49.971Z] 15:29:49     INFO -       rcx = 0x0000000000000b40    rbx = 0x00007ffe2446cb60
[task 2022-04-21T15:29:49.971Z] 15:29:49     INFO -       rsi = 0x00007f1fb629e8b0    rdi = 0x00007f1fb629d680
[task 2022-04-21T15:29:49.971Z] 15:29:49     INFO -       rbp = 0x00007ffe2446cb00    rsp = 0x00007ffe2446caf0
[task 2022-04-21T15:29:49.971Z] 15:29:49     INFO -        r8 = 0x00007f1fb629e8b0     r9 = 0x00007f1fc2b84340
[task 2022-04-21T15:29:49.971Z] 15:29:49     INFO -       r10 = 0x0000000000000000    r11 = 0x0000000000000000
[task 2022-04-21T15:29:49.971Z] 15:29:49     INFO -       r12 = 0x0000000000000001    r13 = 0x0000000000000000
[task 2022-04-21T15:29:49.971Z] 15:29:49     INFO -       r14 = 0x00007ffe2446cb60    r15 = 0x00007ffe2446ce70
[task 2022-04-21T15:29:49.971Z] 15:29:49     INFO -       rip = 0x00007f1fbb592cce
[task 2022-04-21T15:29:49.971Z] 15:29:49     INFO -      Found by: given as instruction pointer in context
[task 2022-04-21T15:29:49.972Z] 15:29:49     INFO -   1  libxul.so!NS_DebugBreak [nsDebugImpl.cpp:5ea7c27a36a06c21b66344008612ad738a5a61c6 : 399 + 0x22]
[task 2022-04-21T15:29:49.972Z] 15:29:49     INFO -       rbx = 0x00007ffe24470f12    rbp = 0x00007ffe2446d1a0
[task 2022-04-21T15:29:49.972Z] 15:29:49     INFO -       rsp = 0x00007ffe2446cb10    r12 = 0x0000000000000001
[task 2022-04-21T15:29:49.972Z] 15:29:49     INFO -       r13 = 0x0000000000000000    r14 = 0x00007ffe2446cb60
[task 2022-04-21T15:29:49.972Z] 15:29:49     INFO -       r15 = 0x00007ffe2446ce70    rip = 0x00007f1fbb592af7
[task 2022-04-21T15:29:49.972Z] 15:29:49     INFO -      Found by: call frame info
[task 2022-04-21T15:29:49.972Z] 15:29:49     INFO -   2  libxul.so!nsTimerImpl::InitCommon(mozilla::BaseTimeDuration<mozilla::TimeDurationValueCalculator> const&, unsigned int, mozilla::Variant<nsTimerImpl::UnknownCallback, nsCOMPtr<nsITimerCallback>, nsCOMPtr<nsIObserver>, nsTimerImpl::FuncCallback, nsTimerImpl::ClosureCallback>&&, mozilla::detail::BaseAutoLock<mozilla::Mutex&> const&) [nsTimerImpl.cpp:5ea7c27a36a06c21b66344008612ad738a5a61c6 : 397 + 0x24]
[task 2022-04-21T15:29:49.972Z] 15:29:49     INFO -       rbx = 0x00007ffe2446d240    rbp = 0x00007ffe2446d220
[task 2022-04-21T15:29:49.972Z] 15:29:49     INFO -       rsp = 0x00007ffe2446d1b0    r12 = 0x00007f1fa6f9a040
[task 2022-04-21T15:29:49.972Z] 15:29:49     INFO -       r13 = 0x00007ffe2446d238    r14 = 0x0000000000000005
[task 2022-04-21T15:29:49.972Z] 15:29:49     INFO -       r15 = 0x00007ffe2446d2b8    rip = 0x00007f1fbb69d6c0
[task 2022-04-21T15:29:49.972Z] 15:29:49     INFO -      Found by: call frame info
[task 2022-04-21T15:29:49.972Z] 15:29:49     INFO -   3  libxul.so!nsTimerImpl::InitHighResolutionWithNamedFuncCallback(void (*)(nsITimer*, void*), void*, mozilla::BaseTimeDuration<mozilla::TimeDurationValueCalculator> const&, unsigned int, char const*) [nsTimerImpl.cpp:5ea7c27a36a06c21b66344008612ad738a5a61c6 : 433 + 0x13]
[task 2022-04-21T15:29:49.972Z] 15:29:49     INFO -       rbx = 0x00007ffe2446d240    rbp = 0x00007ffe2446d2a0
[task 2022-04-21T15:29:49.972Z] 15:29:49     INFO -       rsp = 0x00007ffe2446d230    r12 = 0x00007f1fa6f9a040
[task 2022-04-21T15:29:49.973Z] 15:29:49     INFO -       r13 = 0x00007ffe2446d238    r14 = 0x0000000000000005
[task 2022-04-21T15:29:49.973Z] 15:29:49     INFO -       r15 = 0x00007ffe2446d2b8    rip = 0x00007f1fbb69d769
[task 2022-04-21T15:29:49.973Z] 15:29:49     INFO -      Found by: call frame info
[task 2022-04-21T15:29:49.973Z] 15:29:49     INFO -   4  libxul.so!nsTimer::InitWithNamedFuncCallback(void (*)(nsITimer*, void*), void*, unsigned int, unsigned int, char const*) [nsTimerImpl.h:5ea7c27a36a06c21b66344008612ad738a5a61c6 : 200 + 0x67]
[task 2022-04-21T15:29:49.973Z] 15:29:49     INFO -       rbx = 0x00000000000007d0    rbp = 0x00007ffe2446d2f0
[task 2022-04-21T15:29:49.973Z] 15:29:49     INFO -       rsp = 0x00007ffe2446d2b0    r12 = 0x0000000000000000
[task 2022-04-21T15:29:49.973Z] 15:29:49     INFO -       r13 = 0x00007f1fbfac14c9    r14 = 0x00007f1fa6f9a040
[task 2022-04-21T15:29:49.973Z] 15:29:49     INFO -       r15 = 0x0000000000000005    rip = 0x00007f1fbb69d004
[task 2022-04-21T15:29:49.973Z] 15:29:49     INFO -      Found by: call frame info
[task 2022-04-21T15:29:49.973Z] 15:29:49     INFO -   5  libxul.so!(anonymous namespace)::DoArmIPCTimerMainThread(mozilla::detail::BaseAutoLock<mozilla::StaticMutex&> const&) [TelemetryIPCAccumulator.cpp:5ea7c27a36a06c21b66344008612ad738a5a61c6 : 87 + 0x1e]
[task 2022-04-21T15:29:49.974Z] 15:29:49     INFO -       rbx = 0x00007f1fa6f25d80    rbp = 0x00007ffe2446d320
[task 2022-04-21T15:29:49.975Z] 15:29:49     INFO -       rsp = 0x00007ffe2446d300    r12 = 0x00007f1fa7017000
[task 2022-04-21T15:29:49.976Z] 15:29:49     INFO -       r13 = 0x00007f1fa7017700    r14 = 0x000000000000003d
[task 2022-04-21T15:29:49.976Z] 15:29:49     INFO -       r15 = 0x0000003200000000    rip = 0x00007f1fbfac1eb7
[task 2022-04-21T15:29:49.977Z] 15:29:49     INFO -      Found by: call frame info
[task 2022-04-21T15:29:49.978Z] 15:29:49     INFO -   6  libxul.so!(anonymous namespace)::ArmIPCTimer(mozilla::detail::BaseAutoLock<mozilla::StaticMutex&> const&) [TelemetryIPCAccumulator.cpp:5ea7c27a36a06c21b66344008612ad738a5a61c6 : 102 + 0x4]
[task 2022-04-21T15:29:49.979Z] 15:29:49     INFO -       rbx = 0x00007f1fac20b1c0    rbp = 0x00007ffe2446d360
[task 2022-04-21T15:29:49.979Z] 15:29:49     INFO -       rsp = 0x00007ffe2446d330    r12 = 0x00007f1fa7017000
[task 2022-04-21T15:29:49.980Z] 15:29:49     INFO -       r13 = 0x00007f1fa7017700    r14 = 0x000000000000003d
[task 2022-04-21T15:29:49.980Z] 15:29:49     INFO -       r15 = 0x0000003200000000    rip = 0x00007f1fbfac0d2f
[task 2022-04-21T15:29:49.981Z] 15:29:49     INFO -      Found by: call frame info
[task 2022-04-21T15:29:49.982Z] 15:29:49     INFO -   7  libxul.so!mozilla::TelemetryIPCAccumulator::AccumulateChildHistogram(mozilla::Telemetry::HistogramID, unsigned int) [TelemetryIPCAccumulator.cpp:5ea7c27a36a06c21b66344008612ad738a5a61c6 : 142 + 0x4]
[task 2022-04-21T15:29:49.983Z] 15:29:49     INFO -       rbx = 0x00007f1fac20b1c0    rbp = 0x00007ffe2446d390
[task 2022-04-21T15:29:49.984Z] 15:29:49     INFO -       rsp = 0x00007ffe2446d370    r12 = 0x00007f1fa7017000
[task 2022-04-21T15:29:49.984Z] 15:29:49     INFO -       r13 = 0x00007f1fa7017700    r14 = 0x000000000000003d
[task 2022-04-21T15:29:49.985Z] 15:29:49     INFO -       r15 = 0x0000003200000000    rip = 0x00007f1fbfac0bbe
[task 2022-04-21T15:29:49.986Z] 15:29:49     INFO -      Found by: call frame info
[task 2022-04-21T15:29:50.000Z] 15:29:50     INFO -   8  libxul.so!TelemetryHistogram::Accumulate(mozilla::Telemetry::HistogramID, unsigned int) [TelemetryHistogram.cpp:5ea7c27a36a06c21b66344008612ad738a5a61c6 : 2569 + 0x9]
[task 2022-04-21T15:29:50.001Z] 15:29:50     INFO -       rbx = 0x000000000000003d    rbp = 0x00007ffe2446d3c0
[task 2022-04-21T15:29:50.001Z] 15:29:50     INFO -       rsp = 0x00007ffe2446d3a0    r12 = 0x00007f1fa7017000
[task 2022-04-21T15:29:50.002Z] 15:29:50     INFO -       r13 = 0x00007f1fa7017700    r14 = 0x0000000000000032
[task 2022-04-21T15:29:50.003Z] 15:29:50     INFO -       r15 = 0x00007f1fc28f6358    rip = 0x00007f1fbfaa8481
[task 2022-04-21T15:29:50.004Z] 15:29:50     INFO -      Found by: call frame info
[task 2022-04-21T15:29:50.004Z] 15:29:50     INFO -   9  libxul.so!js::gcstats::Statistics::beginSlice(js::gcstats::ZoneGCStats const&, JS::GCOptions, js::SliceBudget const&, JS::GCReason, bool) [Statistics.cpp:5ea7c27a36a06c21b66344008612ad738a5a61c6 : 1169 + 0xe]
[task 2022-04-21T15:29:50.005Z] 15:29:50     INFO -       rbx = 0x00007f1fa7017768    rbp = 0x00007ffe2446d460
[task 2022-04-21T15:29:50.005Z] 15:29:50     INFO -       rsp = 0x00007ffe2446d3d0    r12 = 0x00007f1fa7017000
[task 2022-04-21T15:29:50.006Z] 15:29:50     INFO -       r13 = 0x00007f1fa7017700    r14 = 0x00007f1fa7017880
[task 2022-04-21T15:29:50.006Z] 15:29:50     INFO -       r15 = 0x00007f1fa7017840    rip = 0x00007f1fc04e8dac
[task 2022-04-21T15:29:50.006Z] 15:29:50     INFO -      Found by: call frame info
[task 2022-04-21T15:29:50.006Z] 15:29:50     INFO -  10  libxul.so!js::gc::GCRuntime::gcCycle(bool, js::SliceBudget const&, JS::GCReason) [GC.cpp:5ea7c27a36a06c21b66344008612ad738a5a61c6 : 3710 + 0x78]
[task 2022-04-21T15:29:50.007Z] 15:29:50     INFO -       rbx = 0x00007ffe2446d4b0    rbp = 0x00007ffe2446d520
[task 2022-04-21T15:29:50.007Z] 15:29:50     INFO -       rsp = 0x00007ffe2446d470    r12 = 0x00007f1fa703e100
[task 2022-04-21T15:29:50.007Z] 15:29:50     INFO -       r13 = 0x00007f1fa7017768    r14 = 0x0000000000000032
[task 2022-04-21T15:29:50.008Z] 15:29:50     INFO -       r15 = 0x0000000000000000    rip = 0x00007f1fc047201a
[task 2022-04-21T15:29:50.008Z] 15:29:50     INFO -      Found by: call frame info
[task 2022-04-21T15:29:50.008Z] 15:29:50     INFO -  11  libxul.so!js::gc::GCRuntime::collect(bool, js::SliceBudget const&, JS::GCReason) [GC.cpp:5ea7c27a36a06c21b66344008612ad738a5a61c6 : 3917 + 0x13]
[task 2022-04-21T15:29:50.008Z] 15:29:50     INFO -       rbx = 0x0000000000000001    rbp = 0x00007ffe2446d600
[task 2022-04-21T15:29:50.009Z] 15:29:50     INFO -       rsp = 0x00007ffe2446d530    r12 = 0x00007f1fa7017768
[task 2022-04-21T15:29:50.009Z] 15:29:50     INFO -       r13 = 0x0000000000000001    r14 = 0x0000000000000032
[task 2022-04-21T15:29:50.009Z] 15:29:50     INFO -       r15 = 0x0000000000000001    rip = 0x00007f1fc0473286
[task 2022-04-21T15:29:50.009Z] 15:29:50     INFO -      Found by: call frame info
[task 2022-04-21T15:29:50.010Z] 15:29:50     INFO -  12  libxul.so!js::gc::GCRuntime::gc(JS::GCOptions, JS::GCReason) [GC.cpp:5ea7c27a36a06c21b66344008612ad738a5a61c6 : 3994 + 0x37]
[task 2022-04-21T15:29:50.010Z] 15:29:50     INFO -       rbx = 0x00007f1fa7017768    rbp = 0x00007ffe2446d670
[task 2022-04-21T15:29:50.011Z] 15:29:50     INFO -       rsp = 0x00007ffe2446d610    r12 = 0x00007f1fac2733a0
[task 2022-04-21T15:29:50.012Z] 15:29:50     INFO -       r13 = 0x00007f1fac2733a0    r14 = 0x0000000000000032
[task 2022-04-21T15:29:50.012Z] 15:29:50     INFO -       r15 = 0x0000000000000002    rip = 0x00007f1fc0442b98
[task 2022-04-21T15:29:50.013Z] 15:29:50     INFO -      Found by: call frame info
[task 2022-04-21T15:29:50.017Z] 15:29:50     INFO -  13  libxul.so!JS::NonIncrementalGC(JSContext*, JS::GCOptions, JS::GCReason) [GCAPI.cpp:5ea7c27a36a06c21b66344008612ad738a5a61c6 : 281 + 0x1d]
[task 2022-04-21T15:29:50.022Z] 15:29:50     INFO -       rbx = 0x00007f1fa7029200    rbp = 0x00007ffe2446d6a0
[task 2022-04-21T15:29:50.026Z] 15:29:50     INFO -       rsp = 0x00007ffe2446d680    r12 = 0x00007f1fac2733a0
[task 2022-04-21T15:29:50.027Z] 15:29:50     INFO -       r13 = 0x00007f1fac2733a0    r14 = 0x0000000000000032
[task 2022-04-21T15:29:50.027Z] 15:29:50     INFO -       r15 = 0x0000000000000002    rip = 0x00007f1fc048fc6c
[task 2022-04-21T15:29:50.038Z] 15:29:50     INFO -      Found by: call frame info
[task 2022-04-21T15:29:50.038Z] 15:29:50     INFO -  14  libxul.so!nsCycleCollector::FixGrayBits(bool, TimeLog&) [nsCycleCollector.cpp:5ea7c27a36a06c21b66344008612ad738a5a61c6 : 3272 + 0xb]
[task 2022-04-21T15:29:50.039Z] 15:29:50     INFO -       rbx = 0x0000000000000003    rbp = 0x00007ffe2446d6d0
[task 2022-04-21T15:29:50.039Z] 15:29:50     INFO -       rsp = 0x00007ffe2446d6b0    r12 = 0x00007f1fac2733a0
[task 2022-04-21T15:29:50.040Z] 15:29:50     INFO -       r13 = 0x00007f1fac2733a0    r14 = 0x0000000000000001
[task 2022-04-21T15:29:50.041Z] 15:29:50     INFO -       r15 = 0x00007f1fac273458    rip = 0x00007f1fbb5c78aa
[task 2022-04-21T15:29:50.041Z] 15:29:50     INFO -      Found by: call frame info
[task 2022-04-21T15:29:50.043Z] 15:29:50     INFO -  15  libxul.so!nsCycleCollector::BeginCollection(mozilla::CCReason, ccIsManual, nsICycleCollectorListener*) [nsCycleCollector.cpp:5ea7c27a36a06c21b66344008612ad738a5a61c6 : 3580 + 0x9]
[task 2022-04-21T15:29:50.043Z] 15:29:50     INFO -       rbx = 0x0000000000000001    rbp = 0x00007ffe2446d730
[task 2022-04-21T15:29:50.044Z] 15:29:50     INFO -       rsp = 0x00007ffe2446d6e0    r12 = 0x0000000000000086
[task 2022-04-21T15:29:50.045Z] 15:29:50     INFO -       r13 = 0x00007f1fac2733a0    r14 = 0x0000000000000001
[task 2022-04-21T15:29:50.045Z] 15:29:50     INFO -       r15 = 0x00007f1fac273458    rip = 0x00007f1fbb5c823f
[task 2022-04-21T15:29:50.046Z] 15:29:50     INFO -      Found by: call frame info
[task 2022-04-21T15:29:50.047Z] 15:29:50     INFO -  16  libxul.so!nsCycleCollector::Collect(mozilla::CCReason, ccIsManual, js::SliceBudget&, nsICycleCollectorListener*, bool) [nsCycleCollector.cpp:5ea7c27a36a06c21b66344008612ad738a5a61c6 : 3412 + 0x11]
[task 2022-04-21T15:29:50.047Z] 15:29:50     INFO -       rbx = 0x0000000000000000    rbp = 0x00007ffe2446d7c0
[task 2022-04-21T15:29:50.048Z] 15:29:50     INFO -       rsp = 0x00007ffe2446d740    r12 = 0x00007ffe2446d7d0
[task 2022-04-21T15:29:50.050Z] 15:29:50     INFO -       r13 = 0x00007f1fac2733a0    r14 = 0x0000000000000001
[task 2022-04-21T15:29:50.051Z] 15:29:50     INFO -       r15 = 0x00007f1fb89750e4    rip = 0x00007f1fbb5c7e4b
[task 2022-04-21T15:29:50.051Z] 15:29:50     INFO -      Found by: call frame info
[task 2022-04-21T15:29:50.052Z] 15:29:50     INFO -  17  libxul.so!nsCycleCollector::ShutdownCollect() [nsCycleCollector.cpp:5ea7c27a36a06c21b66344008612ad738a5a61c6 : 3351 + 0x1a]
[task 2022-04-21T15:29:50.052Z] 15:29:50     INFO -       rbx = 0x00007f1fac2733a0    rbp = 0x00007ffe2446d840
[task 2022-04-21T15:29:50.053Z] 15:29:50     INFO -       rsp = 0x00007ffe2446d7d0    r12 = 0x00007f1fa6fc4000
[task 2022-04-21T15:29:50.054Z] 15:29:50     INFO -       r13 = 0x0000000000000000    r14 = 0x00007f1fac2733a0
[task 2022-04-21T15:29:50.054Z] 15:29:50     INFO -       r15 = 0x00007ffe2446d7d0    rip = 0x00007f1fbb5c7c8a
[task 2022-04-21T15:29:50.055Z] 15:29:50     INFO -      Found by: call frame info
[task 2022-04-21T15:29:50.056Z] 15:29:50     INFO -  18  libxul.so!nsCycleCollector::Shutdown(bool) [nsCycleCollector.cpp:5ea7c27a36a06c21b66344008612ad738a5a61c6 : 3646 + 0x7]
[task 2022-04-21T15:29:50.063Z] 15:29:50     INFO -       rbx = 0x00007f1fac2733a0    rbp = 0x00007ffe2446d860
[task 2022-04-21T15:29:50.064Z] 15:29:50     INFO -       rsp = 0x00007ffe2446d850    r12 = 0x0000000000000000
[task 2022-04-21T15:29:50.064Z] 15:29:50     INFO -       r13 = 0x00007ffe2446de28    r14 = 0x0000000000000001
[task 2022-04-21T15:29:50.065Z] 15:29:50     INFO -       r15 = 0x0000000000000001    rip = 0x00007f1fbb5c8bbc
[task 2022-04-21T15:29:50.065Z] 15:29:50     INFO -      Found by: call frame info
[task 2022-04-21T15:29:50.066Z] 15:29:50     INFO -  19  libxul.so!nsCycleCollector_shutdown(bool) [nsCycleCollector.cpp:5ea7c27a36a06c21b66344008612ad738a5a61c6 : 3962 + 0x10]
[task 2022-04-21T15:29:50.066Z] 15:29:50     INFO -       rbx = 0x00007f1fac2039c0    rbp = 0x00007ffe2446d8a0
[task 2022-04-21T15:29:50.074Z] 15:29:50     INFO -       rsp = 0x00007ffe2446d870    r12 = 0x0000000000000000
[task 2022-04-21T15:29:50.075Z] 15:29:50     INFO -       r13 = 0x00007ffe2446de28    r14 = 0x00007f1fc2b83a80
[task 2022-04-21T15:29:50.078Z] 15:29:50     INFO -       r15 = 0x0000000000000001    rip = 0x00007f1fbb5ca14c
[task 2022-04-21T15:29:50.079Z] 15:29:50     INFO -      Found by: call frame info
[task 2022-04-21T15:29:50.082Z] 15:29:50     INFO -  20  libxul.so!mozilla::ShutdownXPCOM(nsIServiceManager*) [XPCOMInit.cpp:5ea7c27a36a06c21b66344008612ad738a5a61c6 : 721 + 0x9]
[task 2022-04-21T15:29:50.082Z] 15:29:50     INFO -       rbx = 0x00007ffe2446d8b8    rbp = 0x00007ffe2446d950
[task 2022-04-21T15:29:50.083Z] 15:29:50     INFO -       rsp = 0x00007ffe2446d8b0    r12 = 0x0000000000000000
[task 2022-04-21T15:29:50.083Z] 15:29:50     INFO -       r13 = 0x00007ffe2446de28    r14 = 0x0000000000000000
[task 2022-04-21T15:29:50.084Z] 15:29:50     INFO -       r15 = 0x0000000000000000    rip = 0x00007f1fbb6c2483
[task 2022-04-21T15:29:50.085Z] 15:29:50     INFO -      Found by: call frame info
[task 2022-04-21T15:29:50.085Z] 15:29:50     INFO -  21  libxul.so!XRE_TermEmbedding() [nsEmbedFunctions.cpp:5ea7c27a36a06c21b66344008612ad738a5a61c6 : 226 + 0x6]
[task 2022-04-21T15:29:50.086Z] 15:29:50     INFO -       rbx = 0x00007f1fac26fca0    rbp = 0x00007ffe2446d970
[task 2022-04-21T15:29:50.087Z] 15:29:50     INFO -       rsp = 0x00007ffe2446d960    r12 = 0x00000000000004c9
[task 2022-04-21T15:29:50.087Z] 15:29:50     INFO -       r13 = 0x00007ffe2446de28    r14 = 0x0000000000000001
[task 2022-04-21T15:29:50.090Z] 15:29:50     INFO -       r15 = 0x00007ffe2446d9e8    rip = 0x00007f1fbfb7b43b
[task 2022-04-21T15:29:50.091Z] 15:29:50     INFO -      Found by: call frame info
[task 2022-04-21T15:29:50.092Z] 15:29:50     INFO -  22  libxul.so!mozilla::ipc::ScopedXREEmbed::Stop() [ScopedXREEmbed.cpp:5ea7c27a36a06c21b66344008612ad738a5a61c6 : 90 + 0x4]
[task 2022-04-21T15:29:50.092Z] 15:29:50     INFO -       rbx = 0x00007f1fac26fca0    rbp = 0x00007ffe2446d990
[task 2022-04-21T15:29:50.093Z] 15:29:50     INFO -       rsp = 0x00007ffe2446d980    r12 = 0x00000000000004c9
[task 2022-04-21T15:29:50.093Z] 15:29:50     INFO -       r13 = 0x00007ffe2446de28    r14 = 0x0000000000000001
[task 2022-04-21T15:29:50.096Z] 15:29:50     INFO -       r15 = 0x00007ffe2446d9e8    rip = 0x00007f1fbbdced38
[task 2022-04-21T15:29:50.104Z] 15:29:50     INFO -      Found by: call frame info
[task 2022-04-21T15:29:50.105Z] 15:29:50     INFO -  23  libxul.so!XRE_InitChildProcess(int, char**, XREChildData const*) [nsEmbedFunctions.cpp:5ea7c27a36a06c21b66344008612ad738a5a61c6 : 733 + 0x10]
[task 2022-04-21T15:29:50.105Z] 15:29:50     INFO -       rbx = 0x00007ffe2446d9b8    rbp = 0x00007ffe2446dc80
[task 2022-04-21T15:29:50.106Z] 15:29:50     INFO -       rsp = 0x00007ffe2446d9a0    r12 = 0x00000000000004c9
[task 2022-04-21T15:29:50.107Z] 15:29:50     INFO -       r13 = 0x00007ffe2446de28    r14 = 0x0000000000000001
[task 2022-04-21T15:29:50.118Z] 15:29:50     INFO -       r15 = 0x00007ffe2446d9e8    rip = 0x00007f1fbfb7b9e7
[task 2022-04-21T15:29:50.118Z] 15:29:50     INFO -      Found by: call frame info
[task 2022-04-21T15:29:50.119Z] 15:29:50     INFO -  24  plugin-container!content_process_main(mozilla::Bootstrap*, int, char**) [plugin-container.cpp:5ea7c27a36a06c21b66344008612ad738a5a61c6 : 57 + 0x10]
[task 2022-04-21T15:29:50.120Z] 15:29:50     INFO -       rbx = 0x0000000000000010    rbp = 0x00007ffe2446dcc0
[task 2022-04-21T15:29:50.122Z] 15:29:50     INFO -       rsp = 0x00007ffe2446dc90    r12 = 0x00007ffe2446dc90
[task 2022-04-21T15:29:50.126Z] 15:29:50     INFO -       r13 = 0xaaaaaaaaaaaaaaaa    r14 = 0x00007ffe2446de28
[task 2022-04-21T15:29:50.126Z] 15:29:50     INFO -       r15 = 0x00007f1fac2035f0    rip = 0x000055651aee7eaa
[task 2022-04-21T15:29:50.127Z] 15:29:50     INFO -      Found by: call frame info
[task 2022-04-21T15:29:50.130Z] 15:29:50     INFO -  25  plugin-container!main [MozillaRuntimeMain.cpp:5ea7c27a36a06c21b66344008612ad738a5a61c6 : 72 + 0xe]
[task 2022-04-21T15:29:50.131Z] 15:29:50     INFO -       rbx = 0x00007ffe2446de28    rbp = 0x00007ffe2446dd40
[task 2022-04-21T15:29:50.131Z] 15:29:50     INFO -       rsp = 0x00007ffe2446dcd0    r12 = 0x00007ffe2446dcf0
[task 2022-04-21T15:29:50.134Z] 15:29:50     INFO -       r13 = 0xaaaaaaaaaaaaaaaa    r14 = 0x0000000000000000
[task 2022-04-21T15:29:50.135Z] 15:29:50     INFO -       r15 = 0x0000000000000011    rip = 0x000055651aee7f91
[task 2022-04-21T15:29:50.135Z] 15:29:50     INFO -      Found by: call frame info
[task 2022-04-21T15:29:50.136Z] 15:29:50     INFO -  26  libc.so.6!__libc_start_main [libc-start.c : 310 + 0x19]
[task 2022-04-21T15:29:50.136Z] 15:29:50     INFO -       rbx = 0x0000000000000000    rbp = 0x000055651af87a00
[task 2022-04-21T15:29:50.137Z] 15:29:50     INFO -       rsp = 0x00007ffe2446dd50    r12 = 0x000055651aee7d50
[task 2022-04-21T15:29:50.142Z] 15:29:50     INFO -       r13 = 0x00007ffe2446de20    r14 = 0x0000000000000000
[task 2022-04-21T15:29:50.146Z] 15:29:50     INFO -       r15 = 0x0000000000000000    rip = 0x00007f1fb5ed2b97
[task 2022-04-21T15:29:50.146Z] 15:29:50     INFO -      Found by: call frame info
[task 2022-04-21T15:29:50.147Z] 15:29:50     INFO -  27  plugin-container!_start + 0x28
[task 2022-04-21T15:29:50.147Z] 15:29:50     INFO -       rbx = 0x0000000000000000    rbp = 0x0000000000000000
[task 2022-04-21T15:29:50.148Z] 15:29:50     INFO -       rsp = 0x00007ffe2446de10    r12 = 0x000055651aee7d50
[task 2022-04-21T15:29:50.148Z] 15:29:50     INFO -       r13 = 0x00007ffe2446de20    r14 = 0x0000000000000000
[task 2022-04-21T15:29:50.149Z] 15:29:50     INFO -       r15 = 0x0000000000000000    rip = 0x000055651aee7d79
[task 2022-04-21T15:29:50.149Z] 15:29:50     INFO -      Found by: call frame info
Summary: Intermittent [tier 2] test_ext_brokenlinks.js | application crashed [@ nsTimerImpl::InitCommon(mozilla::BaseTimeDuration<mozilla::TimeDurationValueCalculator> const&, unsigned int, mozilla::Variant<nsTimerImpl::UnknownCallback, nsCOMPtr<nsITimerCallback>, → Intermittent [tier 2] xpcshell <random_test> | application crashed [@ nsTimerImpl::InitCommon(mozilla::BaseTimeDuration<mozilla::TimeDurationValueCalculator> const&, unsigned int, mozilla::Variant<nsTimerImpl::UnknownCallback, nsCOMPtr<nsITimerCallba

Based on these backfills it's from changes in Bug 1764251. Jens, could you please have a look over these? Thank you.

Flags: needinfo?(jstutte)
Regressed by: 1764251
Summary: Intermittent [tier 2] xpcshell <random_test> | application crashed [@ nsTimerImpl::InitCommon(mozilla::BaseTimeDuration<mozilla::TimeDurationValueCalculator> const&, unsigned int, mozilla::Variant<nsTimerImpl::UnknownCallback, nsCOMPtr<nsITimerCallba → High freq [tier 2] xpcshell <random_test> | application crashed [@ nsTimerImpl::InitCommon(mozilla::BaseTimeDuration<mozilla::TimeDurationValueCalculator> const&, unsigned int, mozilla::Variant<nsTimerImpl::UnknownCallback, nsCOMPtr<nsITimerCallba

I'll be able to look into this on Monday. But if that bug changed something here, the test might be bogus. Landing the last patch there could reveal who is trying to start a child such late.

Has Regression Range: --- → yes
Summary: High freq [tier 2] xpcshell <random_test> | application crashed [@ nsTimerImpl::InitCommon(mozilla::BaseTimeDuration<mozilla::TimeDurationValueCalculator> const&, unsigned int, mozilla::Variant<nsTimerImpl::UnknownCallback, nsCOMPtr<nsITimerCallba → High freq xpcshell <random_test> | application crashed [@ nsTimerImpl::InitCommon(mozilla::BaseTimeDuration<mozilla::TimeDurationValueCalculator> const&, unsigned int, mozilla::Variant<nsTimerImpl::UnknownCallback, nsCOMPtr<nsITimerCallba

Looking closer at some stack, it seems we get this NS_ERROR which indicates that nsTimerImpl::mEventTarget is nullptr.

nsTimerImpl in general seems not prepared well to this situation and we even see some (few) crashes that might be related to mEventTarget being nullptr.

Given that the stack starts from nsCycleCollector_shutdown(shutdownCollect); in ShutdownXPCOM and thus far beyond AbstractThread::ShutdownMainThread(); I wonder if if (NS_IsMainThread()) { is sufficient here and we should also check for PastShutdownPhase(ShutdownPhase::XPCOMShutdownFinal) (BTW, this is in concurrence to AppShutdown::IsInOrBeyond, but driven by ClearOnShutdown and currently the only way to reliably check for XPCOMShutdownFinal and should be harmonized). Or should NS_IsMainThread() simply return false if we are beyond AbstractThread::ShutdownMainThread(); (such that the dispatch to main thread would fail, too) ?

It is also interesting why this triggers only in this configuration. :smaug, it might have to do with how shutdownCollect is determined such that we simply never collect in other situations?

Flags: needinfo?(jstutte) → needinfo?(bugs)
Assignee: nobody → jstutte
Status: NEW → ASSIGNED
Attachment #9273613 - Attachment description: Bug 1765822: Avoid late-in-shutdown main-thread-event-target usage in DoArmIPCTimerMainThread. r?#ipc-reviewers → Bug 1765822: Do not assert but just bail out on missing event target in nsTimerImpl::InitCommon. r?smaug
Pushed by jstutte@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/10567d2c2b50
Do not assert but just bail out on missing event target in nsTimerImpl::InitCommon. r=smaug

https://hg.mozilla.org/mozilla-central/rev/10567d2c2b50

Status: ASSIGNED → RESOLVED
Closed: 2 years ago
status-firefox101: --- → fixed
Resolution: --- → FIXED
Target Milestone: --- → 101 Branch

Set release status flags based on info from the regressing bug 1764251

status-firefox100: --- → unaffected
status-firefox99: --- → unaffected
status-firefox-esr91: --- → unaffected

Clearing this leftover needinfo.

Flags: needinfo?(smaug)
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: