1832628 - Intermittent /webrtc/RTCDTMFSender-ontonechange-long.https.html | application crashed [@ mozilla::WebrtcAudioConduit::OnDtmfEvent]

Status: RESOLVED WORKSFORME

(Core :: WebRTC, defect, P2)

Description

[Treeherder Bug Filer]

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

---

[task 2023-05-11T13:51:12.903Z] 13:51:12     INFO - PROCESS-CRASH | /webrtc/RTCDTMFSender-ontonechange-long.https.html | application crashed [@ mozilla::WebrtcAudioConduit::OnDtmfEvent]
[task 2023-05-11T13:51:12.903Z] 13:51:12     INFO - Crash dump filename: /tmp/tmprdds9xoa/minidumps/2f7fc9c1-d3a4-102a-53e3-074bc5148a7b.dmp
[task 2023-05-11T13:51:12.903Z] 13:51:12     INFO - Operating system: Linux
[task 2023-05-11T13:51:12.903Z] 13:51:12     INFO -                   4.4.0-1014-aws #14taskcluster1-Ubuntu SMP Tue Apr 3 10:27:00 UTC 2018
[task 2023-05-11T13:51:12.903Z] 13:51:12     INFO - CPU: amd64
[task 2023-05-11T13:51:12.904Z] 13:51:12     INFO -      family 6 model 85 stepping 7
[task 2023-05-11T13:51:12.904Z] 13:51:12     INFO -      4 CPUs
[task 2023-05-11T13:51:12.904Z] 13:51:12     INFO - Linux Ubuntu 18.04 - bionic (Ubuntu 18.04.6 LTS)
[task 2023-05-11T13:51:12.904Z] 13:51:12     INFO - 
[task 2023-05-11T13:51:12.904Z] 13:51:12     INFO - Crash reason:  SIGSEGV / SEGV_MAPERR
[task 2023-05-11T13:51:12.904Z] 13:51:12     INFO - Crash address: 0x0
[task 2023-05-11T13:51:12.904Z] 13:51:12     INFO - Crashing instruction: `mov rsi, qword [rdi]`
[task 2023-05-11T13:51:12.904Z] 13:51:12     INFO - Memory accessed by instruction:
[task 2023-05-11T13:51:12.905Z] 13:51:12     INFO -   0. Address: 0x0000000000000000
[task 2023-05-11T13:51:12.905Z] 13:51:12     INFO -      Size: 8
[task 2023-05-11T13:51:12.905Z] 13:51:12     INFO - Process uptime: not available
[task 2023-05-11T13:51:12.905Z] 13:51:12     INFO - 
[task 2023-05-11T13:51:12.905Z] 13:51:12     INFO - Thread 23 WebrtcC~read #1 (crashed)
[task 2023-05-11T13:51:12.905Z] 13:51:12     INFO -  0  libxul.so!mozilla::WebrtcAudioConduit::OnDtmfEvent(mozilla::DtmfEvent const&) [AudioConduit.cpp:48abc73003da5bfb2ffb1d6fed9a3ddba27a2a3f : 169 + 0x0]
[task 2023-05-11T13:51:12.905Z] 13:51:12     INFO -      rax = 0x0000000000000065    rdx = 0x0000000000001f40
[task 2023-05-11T13:51:12.905Z] 13:51:12     INFO -      rcx = 0x000000000000000c    rbx = 0x00007f6a93552598
[task 2023-05-11T13:51:12.905Z] 13:51:12     INFO -      rsi = 0x00007f6a93552598    rdi = 0x0000000000000000
[task 2023-05-11T13:51:12.905Z] 13:51:12     INFO -      rbp = 0x00007f6a93c7b470    rsp = 0x00007f6a93c7b470
[task 2023-05-11T13:51:12.905Z] 13:51:12     INFO -       r8 = 0x0000000000001770     r9 = 0x0000000000000003
[task 2023-05-11T13:51:12.906Z] 13:51:12     INFO -      r10 = 0x00007f6a93c7b660    r11 = 0x0000000000000206
[task 2023-05-11T13:51:12.906Z] 13:51:12     INFO -      r12 = 0x00007f6a9548fe10    r13 = 0x0000000000000000
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -      r14 = 0x00007f6a9353f6e8    r15 = 0x00007f6a93c7b48f
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -      rip = 0x00007f6a9df218c7
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -     Found by: given as instruction pointer in context
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -  1  libxul.so!mozilla::MediaEventSourceImpl<(mozilla::ListenerPolicy)1, mozilla::DtmfEvent>::ConnectInternal<mozilla::AbstractThread, mozilla::WebrtcAudioConduit, void (mozilla::WebrtcAudioConduit::*)(mozilla::DtmfEvent const&)>(mozilla::AbstractThread*, mozilla::WebrtcAudioConduit*, void (mozilla::WebrtcAudioConduit::*)(mozilla::DtmfEvent const&))::{lambda(mozilla::DtmfEvent&&)#1}::operator()(mozilla::DtmfEvent&&) const [MediaEventSource.h:48abc73003da5bfb2ffb1d6fed9a3ddba27a2a3f : 404]
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -     Found by: inlining
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -  2  libxul.so!mozilla::detail::ListenerImpl<mozilla::AbstractThread, mozilla::MediaEventSourceImpl<(mozilla::ListenerPolicy)1, mozilla::DtmfEvent>::ConnectInternal<mozilla::AbstractThread, mozilla::WebrtcAudioConduit, void (mozilla::WebrtcAudioConduit::*)(mozilla::DtmfEvent const&)>(mozilla::AbstractThread*, mozilla::WebrtcAudioConduit*, void (mozilla::WebrtcAudioConduit::*)(mozilla::DtmfEvent const&))::{lambda(mozilla::DtmfEvent&&)#1}, mozilla::DtmfEvent>::ApplyWithArgsImpl<mozilla::MediaEventSourceImpl<(mozilla::ListenerPolicy)1, mozilla::DtmfEvent>::ConnectInternal<mozilla::AbstractThread, mozilla::WebrtcAudioConduit, void (mozilla::WebrtcAudioConduit::*)(mozilla::DtmfEvent const&)>(mozilla::AbstractThread*, mozilla::WebrtcAudioConduit*, void (mozilla::WebrtcAudioConduit::*)(mozilla::DtmfEvent const&))::{lambda(mozilla::DtmfEvent&&)#1}>(mozilla::AbstractThread*, mozilla::MediaEventSourceImpl<(mozilla::ListenerPolicy)1, mozilla::DtmfEvent>::ConnectInternal<mozilla::AbstractThread, mozilla::WebrtcAudioConduit, void (mozilla::WebrtcAudioConduit::*)(mozilla::DtmfEvent const&)>(mozilla::AbstractThread*, mozilla::WebrtcAudioConduit*, void (mozilla::WebrtcAudioConduit::*)(mozilla::DtmfEvent const&))::{lambda(mozilla::DtmfEvent&&)#1} const&, mozilla::DtmfEvent&&) [MediaEventSource.h:48abc73003da5bfb2ffb1d6fed9a3ddba27a2a3f : 214 + 0x1b]
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -      rbx = 0x00007f6a93552598    rbp = 0x00007f6a93c7b4b0
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -      rsp = 0x00007f6a93c7b480    r12 = 0x00007f6a9548fe10
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -      r13 = 0x0000000000000000    r14 = 0x00007f6a9353f6e8
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -      r15 = 0x00007f6a93c7b48f    rip = 0x00007f6a9df3c06f
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -     Found by: call frame info
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -  3  libxul.so!mozilla::detail::ListenerImpl<mozilla::AbstractThread, mozilla::MediaEventSourceImpl<(mozilla::ListenerPolicy)1, mozilla::DtmfEvent>::ConnectInternal<mozilla::AbstractThread, mozilla::WebrtcAudioConduit, void (mozilla::WebrtcAudioConduit::*)(mozilla::DtmfEvent const&)>(mozilla::AbstractThread*, mozilla::WebrtcAudioConduit*, void (mozilla::WebrtcAudioConduit::*)(mozilla::DtmfEvent const&))::{lambda(mozilla::DtmfEvent&&)#1}, mozilla::DtmfEvent>::ApplyWithArgs(mozilla::DtmfEvent&&) [MediaEventSource.h:48abc73003da5bfb2ffb1d6fed9a3ddba27a2a3f : 236 + 0xb]
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -      rbx = 0x00007f6a93552598    rbp = 0x00007f6a93c7b500
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -      rsp = 0x00007f6a93c7b4c0    r12 = 0x00007f6a9548fe10
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -      r13 = 0x0000000000000000    r14 = 0x00007f6a9353f6d0
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -      r15 = 0x00007f6a93c7b4d7    rip = 0x00007f6a9df3bf28
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -     Found by: call frame info
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -  4  libxul.so!mozilla::detail::RunnableMethodArguments<mozilla::DtmfEvent&&>::apply<mozilla::detail::Listener<mozilla::DtmfEvent>, void (mozilla::detail::Listener<mozilla::DtmfEvent>::*)(mozilla::DtmfEvent&&)>(mozilla::detail::Listener<mozilla::DtmfEvent>*, void (mozilla::detail::Listener<mozilla::DtmfEvent>::*)(mozilla::DtmfEvent&&))::{lambda(auto:1&&)#1}::operator()<StoreCopyPassByRRef<mozilla::DtmfEvent>&>(StoreCopyPassByRRef<mozilla::DtmfEvent>&) const [nsThreadUtils.h:48abc73003da5bfb2ffb1d6fed9a3ddba27a2a3f : 1164]
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -     Found by: inlining
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -  5  libxul.so!std::__invoke_impl<void, mozilla::detail::RunnableMethodArguments<mozilla::DtmfEvent&&>::apply<mozilla::detail::Listener<mozilla::DtmfEvent>, void (mozilla::detail::Listener<mozilla::DtmfEvent>::*)(mozilla::DtmfEvent&&)>(mozilla::detail::Listener<mozilla::DtmfEvent>*, void (mozilla::detail::Listener<mozilla::DtmfEvent>::*)(mozilla::DtmfEvent&&))::{lambda(auto:1&&)#1}, StoreCopyPassByRRef<mozilla::DtmfEvent>&>(std::__invoke_other, mozilla::detail::RunnableMethodArguments<mozilla::DtmfEvent&&>::apply<mozilla::detail::Listener<mozilla::DtmfEvent>, void (mozilla::detail::Listener<mozilla::DtmfEvent>::*)(mozilla::DtmfEvent&&)>(mozilla::detail::Listener<mozilla::DtmfEvent>*, void (mozilla::detail::Listener<mozilla::DtmfEvent>::*)(mozilla::DtmfEvent&&))::{lambda(auto:1&&)#1}, StoreCopyPassByRRef<mozilla::DtmfEvent>&) [invoke.h : 60]
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -     Found by: inlining
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -  6  libxul.so!std::__invoke<mozilla::detail::RunnableMethodArguments<mozilla::DtmfEvent&&>::apply<mozilla::detail::Listener<mozilla::DtmfEvent>, void (mozilla::detail::Listener<mozilla::DtmfEvent>::*)(mozilla::DtmfEvent&&)>(mozilla::detail::Listener<mozilla::DtmfEvent>*, void (mozilla::detail::Listener<mozilla::DtmfEvent>::*)(mozilla::DtmfEvent&&))::{lambda(auto:1&&)#1}, StoreCopyPassByRRef<mozilla::DtmfEvent>&>(mozilla::detail::RunnableMethodArguments<mozilla::DtmfEvent&&>::apply<mozilla::detail::Listener<mozilla::DtmfEvent>, void (mozilla::detail::Listener<mozilla::DtmfEvent>::*)(mozilla::DtmfEvent&&)>(mozilla::detail::Listener<mozilla::DtmfEvent>*, void (mozilla::detail::Listener<mozilla::DtmfEvent>::*)(mozilla::DtmfEvent&&))::{lambda(auto:1&&)#1}, StoreCopyPassByRRef<mozilla::DtmfEvent>&) [invoke.h : 95]
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -     Found by: inlining
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -  7  libxul.so!std::__apply_impl<mozilla::detail::RunnableMethodArguments<mozilla::DtmfEvent&&>::apply<mozilla::detail::Listener<mozilla::DtmfEvent>, void (mozilla::detail::Listener<mozilla::DtmfEvent>::*)(mozilla::DtmfEvent&&)>(mozilla::detail::Listener<mozilla::DtmfEvent>*, void (mozilla::detail::Listener<mozilla::DtmfEvent>::*)(mozilla::DtmfEvent&&))::{lambda(auto:1&&)#1}, std::tuple<StoreCopyPassByRRef<mozilla::DtmfEvent> >&, (unsigned long)0>(mozilla::detail::RunnableMethodArguments<mozilla::DtmfEvent&&>::apply<mozilla::detail::Listener<mozilla::DtmfEvent>, void (mozilla::detail::Listener<mozilla::DtmfEvent>::*)(mozilla::DtmfEvent&&)>(mozilla::detail::Listener<mozilla::DtmfEvent>*, void (mozilla::detail::Listener<mozilla::DtmfEvent>::*)(mozilla::DtmfEvent&&))::{lambda(auto:1&&)#1}, std::tuple<StoreCopyPassByRRef<mozilla::DtmfEvent> >&, std::integer_sequence<unsigned long, ((unsigned long)0)...>) [tuple : 1662]
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -     Found by: inlining
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -  8  libxul.so!std::apply<mozilla::detail::RunnableMethodArguments<mozilla::DtmfEvent&&>::apply<mozilla::detail::Listener<mozilla::DtmfEvent>, void (mozilla::detail::Listener<mozilla::DtmfEvent>::*)(mozilla::DtmfEvent&&)>(mozilla::detail::Listener<mozilla::DtmfEvent>*, void (mozilla::detail::Listener<mozilla::DtmfEvent>::*)(mozilla::DtmfEvent&&))::{lambda(auto:1&&)#1}, std::tuple<StoreCopyPassByRRef<mozilla::DtmfEvent> >&>(mozilla::detail::RunnableMethodArguments<mozilla::DtmfEvent&&>::apply<mozilla::detail::Listener<mozilla::DtmfEvent>, void (mozilla::detail::Listener<mozilla::DtmfEvent>::*)(mozilla::DtmfEvent&&)>(mozilla::detail::Listener<mozilla::DtmfEvent>*, void (mozilla::detail::Listener<mozilla::DtmfEvent>::*)(mozilla::DtmfEvent&&))::{lambda(auto:1&&)#1}, std::tuple<StoreCopyPassByRRef<mozilla::DtmfEvent> >&) [tuple : 1671]
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -     Found by: inlining
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -  9  libxul.so!mozilla::detail::RunnableMethodArguments<mozilla::DtmfEvent&&>::apply<mozilla::detail::Listener<mozilla::DtmfEvent>, void (mozilla::detail::Listener<mozilla::DtmfEvent>::*)(mozilla::DtmfEvent&&)>(mozilla::detail::Listener<mozilla::DtmfEvent>*, void (mozilla::detail::Listener<mozilla::DtmfEvent>::*)(mozilla::DtmfEvent&&)) [nsThreadUtils.h:48abc73003da5bfb2ffb1d6fed9a3ddba27a2a3f : 1162]
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -     Found by: inlining
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO - 10  libxul.so!mozilla::detail::RunnableMethodImpl<mozilla::detail::Listener<mozilla::DtmfEvent>*, void (mozilla::detail::Listener<mozilla::DtmfEvent>::*)(mozilla::DtmfEvent&&), true, (mozilla::RunnableKind)0, mozilla::DtmfEvent&&>::Run() [nsThreadUtils.h:48abc73003da5bfb2ffb1d6fed9a3ddba27a2a3f : 1213 + 0x12]
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -      rbx = 0x00007f6a9542e3a0    rbp = 0x00007f6a93c7b510
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -      rsp = 0x00007f6a93c7b510    r12 = 0x00007f6a94c74a70
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -      r13 = 0x0000000000000000    r14 = 0x00007f6a9366b6f0
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -      r15 = 0x00007f6a9542e380    rip = 0x00007f6a9deecbfb
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -     Found by: call frame info
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO - 11  libxul.so!mozilla::TaskQueueWrapper<(mozilla::DeletionPolicy)1>::CreateTaskRunner(nsCOMPtr<nsIRunnable>)::{lambda()#1}::operator()() [TaskQueueWrapper.h:48abc73003da5bfb2ffb1d6fed9a3ddba27a2a3f : 100 + 0x5]
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -      rbx = 0x00007f6a9542e3a0    rbp = 0x00007f6a93c7b5b0
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -      rsp = 0x00007f6a93c7b520    r12 = 0x00007f6a94c74a70
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -      r13 = 0x0000000000000000    r14 = 0x00007f6a9366b6f0
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -      r15 = 0x00007f6a9542e380    rip = 0x00007f6a9dedd69c
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -     Found by: call frame info
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO - 12  libxul.so!mozilla::detail::RunnableFunction<mozilla::TaskQueueWrapper<(mozilla::DeletionPolicy)1>::CreateTaskRunner(nsCOMPtr<nsIRunnable>)::{lambda()#1}>::Run() [nsThreadUtils.h:48abc73003da5bfb2ffb1d6fed9a3ddba27a2a3f : 548 + 0x8]
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -      rbx = 0x00007f6a938693d0    rbp = 0x00007f6a93c7b5c0
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -      rsp = 0x00007f6a93c7b5c0    r12 = 0x00007f6a94c74a70
[task 2023-05-11T13:51:12.907Z] 13:51:12     INFO -      r13 = 0x0000000000000000    r14 = 0x0000000000000000
[task 2023-05-11T13:51:12.908Z] 13:51:12     INFO -      r15 = 0x00007f6a954c0580    rip = 0x00007f6a9dedd56d
[task 2023-05-11T13:51:12.908Z] 13:51:12     INFO -     Found by: call frame info
[task 2023-05-11T13:51:12.908Z] 13:51:12     INFO - 13  libxul.so!mozilla::AutoTaskDispatcher::TaskGroupRunnable::Run() [TaskDispatcher.h:48abc73003da5bfb2ffb1d6fed9a3ddba27a2a3f : 230 + 0x5]
[task 2023-05-11T13:51:12.908Z] 13:51:12     INFO -      rbx = 0x00007f6a938693d0    rbp = 0x00007f6a93c7b660
[task 2023-05-11T13:51:12.908Z] 13:51:12     INFO -      rsp = 0x00007f6a93c7b5d0    r12 = 0x00007f6a94c74a70
[task 2023-05-11T13:51:12.908Z] 13:51:12     INFO -      r13 = 0x0000000000000000    r14 = 0x0000000000000000
[task 2023-05-11T13:51:12.908Z] 13:51:12     INFO -      r15 = 0x00007f6a954c0580    rip = 0x00007f6aa0f7966f
[task 2023-05-11T13:51:12.908Z] 13:51:12     INFO -     Found by: call frame info
[task 2023-05-11T13:51:12.908Z] 13:51:12     INFO - 14  libxul.so!mozilla::TaskQueue::Runner::Run() [TaskQueue.cpp:48abc73003da5bfb2ffb1d6fed9a3ddba27a2a3f : 259 + 0x8]
[task 2023-05-11T13:51:12.908Z] 13:51:12     INFO -      rbx = 0x00007f6a9387d6d0    rbp = 0x00007f6a93c7b750
[task 2023-05-11T13:51:12.908Z] 13:51:12     INFO -      rsp = 0x00007f6a93c7b670    r12 = 0x00007f6a94c74a00
[task 2023-05-11T13:51:12.908Z] 13:51:12     INFO -      r13 = 0x0000000000000000    r14 = 0x00007f6a938693d0
[task 2023-05-11T13:51:12.908Z] 13:51:12     INFO -      r15 = 0x00007f6a9387d6d0    rip = 0x00007f6aa0f7af2d
[task 2023-05-11T13:51:12.908Z] 13:51:12     INFO -     Found by: call frame info
[task 2023-05-11T13:51:12.908Z] 13:51:12     INFO - 15  libxul.so!nsThreadPool::Run() [nsThreadPool.cpp:48abc73003da5bfb2ffb1d6fed9a3ddba27a2a3f : 343 + 0x9]
[task 2023-05-11T13:51:12.908Z] 13:51:12     INFO -      rbx = 0x00007f6a954fb280    rbp = 0x00007f6a93c7b870
[task 2023-05-11T13:51:12.908Z] 13:51:12     INFO -      rsp = 0x00007f6a93c7b760    r12 = 0x00007f6a954fb318
[task 2023-05-11T13:51:12.908Z] 13:51:12     INFO -      r13 = 0x00007f6a9387d6d0    r14 = 0x00007f6a954fb2b0
[task 2023-05-11T13:51:12.908Z] 13:51:12     INFO -      r15 = 0x0000000000000000    rip = 0x00007f6aa0f8704f
[task 2023-05-11T13:51:12.908Z] 13:51:12     INFO -     Found by: call frame info
[task 2023-05-11T13:51:12.908Z] 13:51:12     INFO - 16  libxul.so!nsThread::ProcessNextEvent(bool, bool*) [nsThread.cpp:48abc73003da5bfb2ffb1d6fed9a3ddba27a2a3f : 1233 + 0x9]
[task 2023-05-11T13:51:12.908Z] 13:51:12     INFO -      rbx = 0x00007f6ab11f5ae0    rbp = 0x00007f6a93c7bae0
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO -      rsp = 0x00007f6a93c7b880    r12 = 0x0000000000000000
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO -      r13 = 0x00007f6a954fb280    r14 = 0x00000000ffffffff
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO -      r15 = 0x000004b697285bfc    rip = 0x00007f6aa05a2c2a
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO -     Found by: call frame info
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO - 17  libxul.so!NS_ProcessNextEvent(nsIThread*, bool) [nsThreadUtils.cpp:48abc73003da5bfb2ffb1d6fed9a3ddba27a2a3f : 479]
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO -     Found by: inlining
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO - 18  libxul.so!mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate*) [MessagePump.cpp:48abc73003da5bfb2ffb1d6fed9a3ddba27a2a3f : 300 + 0x2a]
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO -      rbx = 0x00007f6a94c8b080    rbp = 0x00007f6a93c7bb50
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO -      rsp = 0x00007f6a93c7baf0    r12 = 0x00007f6a94c5c700
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO -      r13 = 0x0000000000000002    r14 = 0x00007f6a93c7bbb8
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO -      r15 = 0x00007f6ab11f5ae0    rip = 0x00007f6aa05ebc60
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO -     Found by: call frame info
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO - 19  libxul.so!MessageLoop::RunInternal() [message_loop.cc:48abc73003da5bfb2ffb1d6fed9a3ddba27a2a3f : 369]
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO -     Found by: inlining
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO - 20  libxul.so!MessageLoop::RunHandler() [message_loop.cc:48abc73003da5bfb2ffb1d6fed9a3ddba27a2a3f : 362]
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO -     Found by: inlining
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO - 21  libxul.so!MessageLoop::Run() [message_loop.cc:48abc73003da5bfb2ffb1d6fed9a3ddba27a2a3f : 344 + 0xb]
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO -      rbx = 0x00007f6a93c7bb68    rbp = 0x00007f6a93c7bb90
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO -      rsp = 0x00007f6a93c7bb60    r12 = 0x00007f6a94c84080
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO -      r13 = 0x0000000000000002    r14 = 0x00007f6ab11f5ae0
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO -      r15 = 0x00007f6a93c7bbb8    rip = 0x00007f6aa11821bf
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO -     Found by: call frame info
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO - 22  libxul.so!nsThread::ThreadFunc(void*) [nsThread.cpp:48abc73003da5bfb2ffb1d6fed9a3ddba27a2a3f : 391 + 0x7]
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO -      rbx = 0x0000000000000013    rbp = 0x00007f6a93c7bd90
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO -      rsp = 0x00007f6a93c7bba0    r12 = 0x00007f6a94c84080
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO -      r13 = 0x0000000000000002    r14 = 0x00007f6ab11f5ae0
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO -      r15 = 0x00007f6a93c7bbb8    rip = 0x00007f6aa0f804ec
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO -     Found by: call frame info
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO - 23  libnspr4.so!_pt_root [ptthread.c:48abc73003da5bfb2ffb1d6fed9a3ddba27a2a3f : 201 + 0x6]
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO -      rbx = 0x00007f6a94c5c700    rbp = 0x00007f6a93c7bde0
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO -      rsp = 0x00007f6a93c7bda0    r12 = 0x0000000000000000
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO -      r13 = 0x0000000000000002    r14 = 0x00007f6a93c7c700
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO -      r15 = 0x00007f6ab111dc40    rip = 0x00007f6ab282bf7f
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO -     Found by: call frame info
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO - 24  firefox-bin!set_alt_signal_stack_and_start(PthreadCreateParams*) [pthread_create_interposer.cpp:48abc73003da5bfb2ffb1d6fed9a3ddba27a2a3f : 81 + 0x5]
[task 2023-05-11T13:51:12.909Z] 13:51:12     INFO -      rbx = 0x00007f6a94c5c700    rbp = 0x00007f6a93c7beb0
[task 2023-05-11T13:51:12.910Z] 13:51:12     INFO -      rsp = 0x00007f6a93c7bdf0    r12 = 0x00007f6ab282bdf0
[task 2023-05-11T13:51:12.910Z] 13:51:12     INFO -      r13 = 0x0000000000000000    r14 = 0x00007f6a95702000
[task 2023-05-11T13:51:12.910Z] 13:51:12     INFO -      r15 = 0x00007f6a93c7be20    rip = 0x000055831c896eff
[task 2023-05-11T13:51:12.910Z] 13:51:12     INFO -     Found by: call frame info
[task 2023-05-11T13:51:12.910Z] 13:51:12     INFO - 25  libpthread.so.0!start_thread [pthread_create.c : 463 + 0xc]
[task 2023-05-11T13:51:12.910Z] 13:51:12     INFO -      rbx = 0x0000000000000000    rbp = 0x0000000000000000
[task 2023-05-11T13:51:12.910Z] 13:51:12     INFO -      rsp = 0x00007f6a93c7bec0    r12 = 0x00007f6a93c7bf80
[task 2023-05-11T13:51:12.910Z] 13:51:12     INFO -      r13 = 0x0000000000000000    r14 = 0x00007f6a94c6e250
[task 2023-05-11T13:51:12.910Z] 13:51:12     INFO -      r15 = 0x00007ffea98d8d08    rip = 0x00007f6ab244a6db
[task 2023-05-11T13:51:12.910Z] 13:51:12     INFO -     Found by: call frame info
[task 2023-05-11T13:51:12.910Z] 13:51:12     INFO - 26  libc.so.6!__GI___clone + 0x3e
[task 2023-05-11T13:51:12.910Z] 13:51:12     INFO -      rbx = 0x00007f6a93c7c700    rbp = 0x0000000000000000
[task 2023-05-11T13:51:12.910Z] 13:51:12     INFO -      rsp = 0x00007f6a93c7bf80    r12 = 0x00007f6a93c7bf80
[task 2023-05-11T13:51:12.910Z] 13:51:12     INFO -      r13 = 0x0000000000000000    r14 = 0x00007f6a94c6e250
[task 2023-05-11T13:51:12.911Z] 13:51:12     INFO -      r15 = 0x00007ffea98d8d08    rip = 0x00007f6ab1630a3f
[task 2023-05-11T13:51:12.911Z] 13:51:12     INFO -     Found by: call frame info
[task 2023-05-11T13:51:12.911Z] 13:51:12     INFO - 

Comment 1

[Intermittent Failures Robot]

1 failures in 4077 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-shippable-qr: 1
  • opt: 1

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

Comment 2

[Andreas Pehrson [:pehrsons]]

I have just audited through how the two paths (the dtmf event vs OnControlConfigChange) sync/race, and I don't think there's a problem.

mTransmitting:

• set on main here
• notification happens through a direct task, i.e. tail dispatched at least in the common case, i.e. the next step runs on main thread at the end of the current main thread task
• in the tail task other threads are notified through a statechange task
• the statechange task notifies watchers directly, i.e. calls OnControlConfigChange directly.

DTMFEvent:

• notified on main here. this is a timer callback so its own main thread task
• mTransmitting should be true because mCurrentDirection and mTransmitting are set in the same task, or is there a case we could reach where mCurrentDirection was set but not mTransmitting because mJsepSession->GetState() was not kJsepStateStable?
• the dtmf event is dispatched to the target through tail dispatch because the call thread is an AbstractThread that supports tail dispatch, which main thread also supports meaning it will be used

To summarize:

• state mirroring main thread -> call thread:
  taildispatch(main) -> statechangedispatch(call) -> run(call)
• media event main thread -> call thread:
  taildispatch(main) -> dispatch(call) -> run(call)

The statechange dispatch only matters in relation to other tasks dispatched from the same task, in that it runs before those dispatched regularly. See PerThreadTaskGroup and TaskGroupRunnable.

One question here though is whether it is possible that the direct task dispatched from the canonical could end up being dispatched as a regular task, while the task dispatched from notifying the media event ends up being dispatched as a tail task? It comes down to what IsTailDispatcherAvailable() returns for main thread, which is an XPCOMThreadWrapper. This could be a problem. However, I don't think it could happen in practice, looking at mNestedEventLoopDepth, and this comment.

---

Well, this leaves the question above on mJsepSession->GetState() and kJsepStateStable. Byron, could that be a problem?

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform and build breakdown:

• linux1804-64-qr: 1
  • opt: 1

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

Comment 4

[Byron Campen [:bwc]]

This has not happened in quite some time, either in CI or in the field. Maybe 1837570 fixed it?

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-esr115: 1

Platform and build breakdown:

• linux1804-64-shippable-qr: 1
  • opt: 1

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