Closed Bug 1582609 Opened 5 years ago Closed 5 years ago

Intermittent PROCESS-CRASH | Main app process exited normally | application crashed [@ mozilla::MediaEncoder::AudioTrackListener::NotifyQueuedChanges(mozilla::MediaStreamGraph*, long long, mozilla::MediaSegment const&)]

Categories

(Core :: Audio/Video: Recording, defect)

Product:
Core
Component:
Audio/Video: Recording
Type:
defect
Priority:
Not set
Severity:
critical

Tracking

()

Status:
RESOLVED WORKSFORME

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: crash, intermittent-failure, regression)

Crash Data

Filed by: btara [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=267549655&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/YAxTvTx7QbGXVhCulf8i8g/runs/0/artifacts/public/logs/live_backing.log

[task 2019-09-20T00:32:26.065Z] 00:32:26 INFO - TEST-START | dom/media/test/test_mediarecorder_fires_start_event_once_when_erroring.html
[task 2019-09-20T00:32:26.087Z] 00:32:26 INFO - GECKO(1675) | MEMORY STAT | vsize 7442MB | residentFast 204MB | heapAllocated 28MB
[task 2019-09-20T00:32:26.431Z] 00:32:26 ERROR - GECKO(1675) | A content process crashed and MOZ_CRASHREPORTER_SHUTDOWN is set, shutting down
[task 2019-09-20T00:32:26.688Z] 00:32:26 INFO - GECKO(1675) | 1568939546634 Marionette TRACE Received observer notification xpcom-will-shutdown
[task 2019-09-20T00:32:26.688Z] 00:32:26 INFO - GECKO(1675) | 1568939546635 Marionette INFO Stopped listening on port 2828
[task 2019-09-20T00:32:26.689Z] 00:32:26 INFO - GECKO(1675) | 1568939546635 Marionette DEBUG Remote service is inactive
[task 2019-09-20T00:32:26.929Z] 00:32:26 INFO - TEST-INFO | Main app process: exit 0
[task 2019-09-20T00:32:26.930Z] 00:32:26 INFO - runtests.py | Application ran for: 0:06:05.864992
[task 2019-09-20T00:32:26.930Z] 00:32:26 INFO - zombiecheck | Reading PID log: /var/folders/rz/_kr3fpnn3t7fbhwpy7m0232h000017/T/tmpMws9U4pidlog
[task 2019-09-20T00:32:26.930Z] 00:32:26 INFO - ==> process 1675 launched child process 1676
[task 2019-09-20T00:32:26.930Z] 00:32:26 INFO - ==> process 1675 launched child process 1677
[task 2019-09-20T00:32:26.930Z] 00:32:26 INFO - ==> process 1675 launched child process 1678
[task 2019-09-20T00:32:26.930Z] 00:32:26 INFO - ==> process 1675 launched child process 1679
[task 2019-09-20T00:32:26.930Z] 00:32:26 INFO - ==> process 1675 launched child process 1680
[task 2019-09-20T00:32:26.930Z] 00:32:26 INFO - zombiecheck | Checking for orphan process with PID: 1680
[task 2019-09-20T00:32:26.930Z] 00:32:26 INFO - zombiecheck | Checking for orphan process with PID: 1676
[task 2019-09-20T00:32:26.930Z] 00:32:26 INFO - zombiecheck | Checking for orphan process with PID: 1677
[task 2019-09-20T00:32:26.931Z] 00:32:26 INFO - zombiecheck | Checking for orphan process with PID: 1678
[task 2019-09-20T00:32:26.931Z] 00:32:26 INFO - zombiecheck | Checking for orphan process with PID: 1679
[task 2019-09-20T00:32:26.931Z] 00:32:26 INFO - mozcrash Downloading symbols from: https://queue.taskcluster.net/v1/task/c1EyXZBsSXOuxiOuvTroIw/artifacts/public/build/target.crashreporter-symbols.zip
[task 2019-09-20T00:32:32.281Z] 00:32:32 INFO - mozcrash Copy/paste: /Users/cltbld/tasks/task_1568938928/build/macosx64-minidump_stackwalk /var/folders/rz/_kr3fpnn3t7fbhwpy7m0232h000017/T/tmpQuvOhk.mozrunner/minidumps/B965ED9F-FE92-40AF-9C63-C754B548A357.dmp /var/folders/rz/_kr3fpnn3t7fbhwpy7m0232h000017/T/tmpXW5qfO
[task 2019-09-20T00:32:36.279Z] 00:32:36 INFO - mozcrash Saved minidump as /Users/cltbld/tasks/task_1568938928/build/blobber_upload_dir/B965ED9F-FE92-40AF-9C63-C754B548A357.dmp
[task 2019-09-20T00:32:36.279Z] 00:32:36 INFO - mozcrash Saved app info as /Users/cltbld/tasks/task_1568938928/build/blobber_upload_dir/B965ED9F-FE92-40AF-9C63-C754B548A357.extra
[task 2019-09-20T00:32:36.363Z] 00:32:36 INFO - PROCESS-CRASH | Main app process exited normally | application crashed [@ mozilla::MediaEncoder::AudioTrackListener::NotifyQueuedChanges(mozilla::MediaStreamGraph*, long long, mozilla::MediaSegment const&)]
[task 2019-09-20T00:32:36.363Z] 00:32:36 INFO - Crash dump filename: /var/folders/rz/_kr3fpnn3t7fbhwpy7m0232h000017/T/tmpQuvOhk.mozrunner/minidumps/B965ED9F-FE92-40AF-9C63-C754B548A357.dmp
[task 2019-09-20T00:32:36.363Z] 00:32:36 INFO - Operating system: Mac OS X
[task 2019-09-20T00:32:36.363Z] 00:32:36 INFO - 10.14.5 18F132
[task 2019-09-20T00:32:36.363Z] 00:32:36 INFO - CPU: amd64
[task 2019-09-20T00:32:36.363Z] 00:32:36 INFO - family 6 model 69 stepping 1
[task 2019-09-20T00:32:36.363Z] 00:32:36 INFO - 4 CPUs
[task 2019-09-20T00:32:36.363Z] 00:32:36 INFO -
[task 2019-09-20T00:32:36.363Z] 00:32:36 INFO - GPU: UNKNOWN
[task 2019-09-20T00:32:36.363Z] 00:32:36 INFO -
[task 2019-09-20T00:32:36.363Z] 00:32:36 INFO - Crash reason: EXC_BAD_ACCESS / KERN_INVALID_ADDRESS
[task 2019-09-20T00:32:36.363Z] 00:32:36 INFO - Crash address: 0x0
[task 2019-09-20T00:32:36.363Z] 00:32:36 INFO - Process uptime: 365 seconds
[task 2019-09-20T00:32:36.363Z] 00:32:36 INFO -
[task 2019-09-20T00:32:36.363Z] 00:32:36 INFO - Thread 40 (crashed)
[task 2019-09-20T00:32:36.363Z] 00:32:36 INFO - 0 XUL!mozilla::MediaEncoder::AudioTrackListener::NotifyQueuedChanges(mozilla::MediaStreamGraph*, long long, mozilla::MediaSegment const&) [MediaEncoder.cpp:0257933d9e3104536d2352e559c64f6f42808ee0 : 111 + 0x11]
[task 2019-09-20T00:32:36.363Z] 00:32:36 INFO - rax = 0x0000000116f5b72b rdx = 0x0000000000000000
[task 2019-09-20T00:32:36.363Z] 00:32:36 INFO - rcx = 0x0000000107cff0e0 rbx = 0x000000010eb08000
[task 2019-09-20T00:32:36.364Z] 00:32:36 INFO - rsi = 0x0000000000010800 rdi = 0x0000000107e00020
[task 2019-09-20T00:32:36.364Z] 00:32:36 INFO - rbp = 0x0000700009c56de0 rsp = 0x0000700009c56840
[task 2019-09-20T00:32:36.364Z] 00:32:36 INFO - r8 = 0x0000000107e00800 r9 = 0x00000000000035b3
[task 2019-09-20T00:32:36.364Z] 00:32:36 INFO - r10 = 0x0000000110022c18 r11 = 0x0000000000000560
[task 2019-09-20T00:32:36.364Z] 00:32:36 INFO - r12 = 0x0000000107cff130 r13 = 0x0000000117be5ac8
[task 2019-09-20T00:32:36.364Z] 00:32:36 INFO - r14 = 0x0000000110022bd0 r15 = 0x000000010eb08010
[task 2019-09-20T00:32:36.364Z] 00:32:36 INFO - rip = 0x000000011337c430
[task 2019-09-20T00:32:36.364Z] 00:32:36 INFO - Found by: given as instruction pointer in context
[task 2019-09-20T00:32:36.364Z] 00:32:36 INFO - 1 XUL!mozilla::TrackUnionStream::CopyTrackData(mozilla::StreamTracks::Track*, unsigned int, long long, long long, bool*) [TrackUnionStream.cpp:0257933d9e3104536d2352e559c64f6f42808ee0 : 310 + 0x14]
[task 2019-09-20T00:32:36.364Z] 00:32:36 INFO - rbp = 0x0000700009c56fb0 rsp = 0x0000700009c56df0
[task 2019-09-20T00:32:36.364Z] 00:32:36 INFO - rip = 0x0000000113214efb
[task 2019-09-20T00:32:36.364Z] 00:32:36 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T00:32:36.364Z] 00:32:36 INFO - 2 XUL!mozilla::TrackUnionStream::ProcessInput(long long, long long, unsigned int) [TrackUnionStream.cpp:0257933d9e3104536d2352e559c64f6f42808ee0 : 107 + 0x1b]
[task 2019-09-20T00:32:36.364Z] 00:32:36 INFO - rbp = 0x0000700009c57300 rsp = 0x0000700009c56fc0
[task 2019-09-20T00:32:36.364Z] 00:32:36 INFO - rip = 0x0000000113213f28
[task 2019-09-20T00:32:36.364Z] 00:32:36 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T00:32:36.365Z] 00:32:36 INFO - 3 XUL!mozilla::MediaStreamGraphImpl::Process() [MediaStreamGraph.cpp:0257933d9e3104536d2352e559c64f6f42808ee0 : 1131 + 0x13]
[task 2019-09-20T00:32:36.365Z] 00:32:36 INFO - rbp = 0x0000700009c57800 rsp = 0x0000700009c57310
[task 2019-09-20T00:32:36.365Z] 00:32:36 INFO - rip = 0x000000011331dde6
[task 2019-09-20T00:32:36.365Z] 00:32:36 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T00:32:36.365Z] 00:32:36 INFO - 4 XUL!mozilla::MediaStreamGraphImpl::OneIterationImpl(long long) [MediaStreamGraph.cpp:0257933d9e3104536d2352e559c64f6f42808ee0 : 1407 + 0x8]
[task 2019-09-20T00:32:36.365Z] 00:32:36 INFO - rbp = 0x0000700009c57850 rsp = 0x0000700009c57810
[task 2019-09-20T00:32:36.365Z] 00:32:36 INFO - rip = 0x000000011331ea4a
[task 2019-09-20T00:32:36.365Z] 00:32:36 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T00:32:36.365Z] 00:32:36 INFO - 5 XUL!mozilla::AudioCallbackDriver::DataCallback_s(cubeb_stream*, void*, void const*, void*, long) [GraphDriver.cpp:0257933d9e3104536d2352e559c64f6f42808ee0 : 764 + 0x51f]
[task 2019-09-20T00:32:36.365Z] 00:32:36 INFO - rbp = 0x0000700009c578c0 rsp = 0x0000700009c57860
[task 2019-09-20T00:32:36.365Z] 00:32:36 INFO - rip = 0x000000011323f88c
[task 2019-09-20T00:32:36.365Z] 00:32:36 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T00:32:36.365Z] 00:32:36 INFO - 6 XUL!passthrough_resampler<float>::fill(void*, long*, void*, long) [cubeb_resampler.cpp:0257933d9e3104536d2352e559c64f6f42808ee0 : 76 + 0x17]
[task 2019-09-20T00:32:36.365Z] 00:32:36 INFO - rbp = 0x0000700009c57920 rsp = 0x0000700009c578d0
[task 2019-09-20T00:32:36.365Z] 00:32:36 INFO - rip = 0x000000011476a0a7
[task 2019-09-20T00:32:36.365Z] 00:32:36 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T00:32:36.365Z] 00:32:36 INFO - 7 XUL!audiounit_output_callback(void*, unsigned int*, AudioTimeStamp const*, unsigned int, unsigned int, AudioBufferList*) [cubeb_audiounit.cpp:0257933d9e3104536d2352e559c64f6f42808ee0 : 667 + 0x12]
[task 2019-09-20T00:32:36.365Z] 00:32:36 INFO - rbp = 0x0000700009c57a50 rsp = 0x0000700009c57930
[task 2019-09-20T00:32:36.365Z] 00:32:36 INFO - rip = 0x0000000114762651
[task 2019-09-20T00:32:36.365Z] 00:32:36 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T00:32:36.365Z] 00:32:36 INFO - 8 CoreAudio + 0xca3f
[task 2019-09-20T00:32:36.365Z] 00:32:36 INFO - rbp = 0x0000700009c57ad0 rsp = 0x0000700009c57a60
[task 2019-09-20T00:32:36.365Z] 00:32:36 INFO - rip = 0x000000014e50ca3f
[task 2019-09-20T00:32:36.365Z] 00:32:36 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T00:32:36.365Z] 00:32:36 INFO - 9 CoreAudio + 0x128587
[task 2019-09-20T00:32:36.365Z] 00:32:36 INFO - rbp = 0x0000700009c57b20 rsp = 0x0000700009c57ae0
[task 2019-09-20T00:32:36.365Z] 00:32:36 INFO - rip = 0x000000014e628587
[task 2019-09-20T00:32:36.365Z] 00:32:36 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T00:32:36.366Z] 00:32:36 INFO - 10 CoreAudio + 0x127ce7
[task 2019-09-20T00:32:36.366Z] 00:32:36 INFO - rbp = 0x0000700009c57bf0 rsp = 0x0000700009c57b30
[task 2019-09-20T00:32:36.366Z] 00:32:36 INFO - rip = 0x000000014e627ce7
[task 2019-09-20T00:32:36.366Z] 00:32:36 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T00:32:36.366Z] 00:32:36 INFO - 11 CoreAudio + 0xebc6
[task 2019-09-20T00:32:36.366Z] 00:32:36 INFO - rbp = 0x0000700009c57ce0 rsp = 0x0000700009c57c00
[task 2019-09-20T00:32:36.366Z] 00:32:36 INFO - rip = 0x000000014e50ebc6
[task 2019-09-20T00:32:36.366Z] 00:32:36 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T00:32:36.366Z] 00:32:36 INFO - 12 0x7fff48b462d2
[task 2019-09-20T00:32:36.366Z] 00:32:36 INFO - rbp = 0x0000700009c57e90 rsp = 0x0000700009c57cf0
[task 2019-09-20T00:32:36.366Z] 00:32:36 INFO - rip = 0x00007fff48b462d2
[task 2019-09-20T00:32:36.366Z] 00:32:36 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T00:32:36.366Z] 00:32:36 INFO - 13 0x7fff48b44df4
[task 2019-09-20T00:32:36.366Z] 00:32:36 INFO - rbp = 0x0000700009c57ee0 rsp = 0x0000700009c57ea0
[task 2019-09-20T00:32:36.366Z] 00:32:36 INFO - rip = 0x00007fff48b44df4
[task 2019-09-20T00:32:36.366Z] 00:32:36 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T00:32:36.366Z] 00:32:36 INFO - 14 0x7fff48b44956
[task 2019-09-20T00:32:36.366Z] 00:32:36 INFO - rbp = 0x0000700009c57f10 rsp = 0x0000700009c57ef0
[task 2019-09-20T00:32:36.366Z] 00:32:36 INFO - rip = 0x00007fff48b44956
[task 2019-09-20T00:32:36.366Z] 00:32:36 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T00:32:36.366Z] 00:32:36 INFO - 15 0x7fff751ed2eb
[task 2019-09-20T00:32:36.366Z] 00:32:36 INFO - rbp = 0x0000700009c57f30 rsp = 0x0000700009c57f20
[task 2019-09-20T00:32:36.366Z] 00:32:36 INFO - rip = 0x00007fff751ed2eb
[task 2019-09-20T00:32:36.366Z] 00:32:36 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T00:32:36.366Z] 00:32:36 INFO - 16 0x7fff751f0249
[task 2019-09-20T00:32:36.366Z] 00:32:36 INFO - rbp = 0x0000700009c57f50 rsp = 0x0000700009c57f40
[task 2019-09-20T00:32:36.367Z] 00:32:36 INFO - rip = 0x00007fff751f0249
[task 2019-09-20T00:32:36.367Z] 00:32:36 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T00:32:36.367Z] 00:32:36 INFO - 17 0x7fff751ec40d
[task 2019-09-20T00:32:36.367Z] 00:32:36 INFO - rbp = 0x0000700009c57f78 rsp = 0x0000700009c57f60
[task 2019-09-20T00:32:36.367Z] 00:32:36 INFO - rip = 0x00007fff751ec40d
[task 2019-09-20T00:32:36.367Z] 00:32:36 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T00:32:36.367Z] 00:32:36 INFO -

Component: Audio/Video: Playback → Audio/Video: Recording

The priority flag is not set for this bug.
:drno, could you have a look please?

For more information, please visit auto_nag documentation.

Flags: needinfo?(drno)

Just a couple occurrences a few months ago, and I know pehrsons has done lots of work in the area. Closing

Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → FIXED
Flags: needinfo?(drno)
Resolution: FIXED → WORKSFORME
You need to log in before you can comment on or make changes to this bug.