Closed Bug 1734733 Opened 3 years ago Closed 3 years ago

Intermittent Main app process exited normally | application crashed [@ __CFRunLoopServiceMachPort + 0xf7]

Categories

(Core :: General, defect)

Product:
Core
Component:
General
Type:
defect

Tracking

()

Status:
RESOLVED INVALID

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: crash, intermittent-failure)

Crash Data

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

[task 2021-10-07T21:42:50.605Z] 21:42:50     INFO - TEST-PASS | layout/base/tests/chrome/test_printpreview.xhtml | Should have called beforeprint listener! 
[task 2021-10-07T21:42:50.605Z] 21:42:50     INFO - Buffered messages finished
[task 2021-10-07T21:42:50.606Z] 21:42:50    ERROR - TEST-UNEXPECTED-TIMEOUT | layout/base/tests/chrome/test_printpreview.xhtml | application timed out after 370 seconds with no output
[task 2021-10-07T21:42:50.606Z] 21:42:50    ERROR - Force-terminating active process(es).
[task 2021-10-07T21:42:50.606Z] 21:42:50     INFO - Determining child pids from psutil...
[task 2021-10-07T21:42:50.607Z] 21:42:50     INFO - []
[task 2021-10-07T21:42:50.607Z] 21:42:50     INFO - Found child pids: set()
[task 2021-10-07T21:42:50.607Z] 21:42:50     INFO - Killing process: 1639
[task 2021-10-07T21:42:50.608Z] 21:42:50     INFO - TEST-INFO | started process screencapture
[task 2021-10-07T21:42:50.615Z] 21:42:50     INFO - TEST-INFO | screencapture: exit 0
[task 2021-10-07T21:42:50.844Z] 21:42:50     INFO - psutil found pid 1639 dead
[task 2021-10-07T21:42:50.845Z] 21:42:50     INFO - TEST-INFO | Main app process: exit 0
[task 2021-10-07T21:42:50.845Z] 21:42:50     INFO - runtests.py | Application ran for: 0:06:41.137609
[task 2021-10-07T21:42:50.845Z] 21:42:50     INFO - zombiecheck | Reading PID log: /var/folders/rf/8ch_5vg10n922xvwyw19pdd4000014/T/tmpsbyw9oo6pidlog
[task 2021-10-07T21:42:50.846Z] 21:42:50     INFO - mozcrash Downloading symbols from: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/dT3MQi0nSEmZFRwCRGRAqw/artifacts/public/build/target.crashreporter-symbols.zip
[task 2021-10-07T21:42:56.238Z] 21:42:56     INFO - mozcrash Copy/paste: /opt/worker/tasks/task_163364225270844/fetches/minidump_stackwalk/minidump_stackwalk /var/folders/rf/8ch_5vg10n922xvwyw19pdd4000014/T/tmpt2nj2y27.mozrunner/minidumps/97BC663A-7B9E-496F-A291-A714F8C9E645.dmp /var/folders/rf/8ch_5vg10n922xvwyw19pdd4000014/T/tmpry_td6uk
[task 2021-10-07T21:43:02.295Z] 21:43:02     INFO - mozcrash Saved minidump as /opt/worker/tasks/task_163364225270844/build/blobber_upload_dir/97BC663A-7B9E-496F-A291-A714F8C9E645.dmp
[task 2021-10-07T21:43:02.295Z] 21:43:02     INFO - mozcrash Saved app info as /opt/worker/tasks/task_163364225270844/build/blobber_upload_dir/97BC663A-7B9E-496F-A291-A714F8C9E645.extra
[task 2021-10-07T21:43:02.601Z] 21:43:02     INFO - PROCESS-CRASH | Main app process exited normally | application crashed [@ __CFRunLoopServiceMachPort + 0xf7]
[task 2021-10-07T21:43:02.601Z] 21:43:02     INFO - Crash dump filename: /var/folders/rf/8ch_5vg10n922xvwyw19pdd4000014/T/tmpt2nj2y27.mozrunner/minidumps/97BC663A-7B9E-496F-A291-A714F8C9E645.dmp
[task 2021-10-07T21:43:02.601Z] 21:43:02     INFO - Operating system: Mac OS X
[task 2021-10-07T21:43:02.601Z] 21:43:02     INFO -                   10.15.7 19H524
[task 2021-10-07T21:43:02.601Z] 21:43:02     INFO - CPU: amd64
[task 2021-10-07T21:43:02.601Z] 21:43:02     INFO -      family 6 model 158 stepping 10
[task 2021-10-07T21:43:02.601Z] 21:43:02     INFO -      12 CPUs
[task 2021-10-07T21:43:02.601Z] 21:43:02     INFO - 
[task 2021-10-07T21:43:02.601Z] 21:43:02     INFO - GPU: UNKNOWN
[task 2021-10-07T21:43:02.601Z] 21:43:02     INFO - 
[task 2021-10-07T21:43:02.602Z] 21:43:02     INFO - Crash reason:  EXC_SOFTWARE / SIGABRT
[task 2021-10-07T21:43:02.602Z] 21:43:02     INFO - Crash address: 0x7fff7359fdfa
[task 2021-10-07T21:43:02.602Z] 21:43:02     INFO - Process uptime: 401 seconds
[task 2021-10-07T21:43:02.602Z] 21:43:02     INFO - 
[task 2021-10-07T21:43:02.602Z] 21:43:02     INFO - Thread 0 (crashed) - MainThread 0  libsystem_kernel.dylib!mach_msg_trap + 0xa
[task 2021-10-07T21:43:02.602Z] 21:43:02     INFO -     rax = 0x0000000010004005   rdx = 0x0000000000000000
[task 2021-10-07T21:43:02.602Z] 21:43:02     INFO -     rcx = 0x00007ffeeabe5c38   rbx = 0x0000000007000806
[task 2021-10-07T21:43:02.602Z] 21:43:02     INFO -     rsi = 0x0000000007000806   rdi = 0x00007ffeeabe5e00
[task 2021-10-07T21:43:02.602Z] 21:43:02     INFO -     rbp = 0x00007ffeeabe5c90   rsp = 0x00007ffeeabe5c38
[task 2021-10-07T21:43:02.602Z] 21:43:02     INFO -      r8 = 0x0000000000002c03    r9 = 0x00000000ffffffff
[task 2021-10-07T21:43:02.602Z] 21:43:02     INFO -     r10 = 0x0000000000000c00   r11 = 0x0000000000000206
[task 2021-10-07T21:43:02.602Z] 21:43:02     INFO -     r12 = 0x0000000007000806   r13 = 0x0000000000000c00
[task 2021-10-07T21:43:02.602Z] 21:43:02     INFO -     r14 = 0x00007ffeeabe5e00   r15 = 0x0000000000000000
[task 2021-10-07T21:43:02.602Z] 21:43:02     INFO -     rip = 0x00007fff7359fdfa
[task 2021-10-07T21:43:02.602Z] 21:43:02     INFO -     Found by: given as instruction pointer in context
[task 2021-10-07T21:43:02.602Z] 21:43:02     INFO -  1  CoreFoundation!__CFRunLoopServiceMachPort + 0xf7
[task 2021-10-07T21:43:02.602Z] 21:43:02     INFO -     rbp = 0x00007ffeeabe5d20   rsp = 0x00007ffeeabe5ca0
[task 2021-10-07T21:43:02.602Z] 21:43:02     INFO -     rip = 0x00007fff39557ef5
[task 2021-10-07T21:43:02.602Z] 21:43:02     INFO -     Found by: previous frame's frame pointer
[task 2021-10-07T21:43:02.602Z] 21:43:02     INFO -  2  CoreFoundation!__CFRunLoopRun + 0x527
[task 2021-10-07T21:43:02.602Z] 21:43:02     INFO -     rbp = 0x00007ffeeabe6a30   rsp = 0x00007ffeeabe5d30
[task 2021-10-07T21:43:02.602Z] 21:43:02     INFO -     rip = 0x00007fff395569c2
[task 2021-10-07T21:43:02.603Z] 21:43:02     INFO -     Found by: previous frame's frame pointer
[task 2021-10-07T21:43:02.603Z] 21:43:02     INFO -  3  CoreFoundation!CFRunLoopRunSpecific + 0x1ce
[task 2021-10-07T21:43:02.603Z] 21:43:02     INFO -     rbp = 0x00007ffeeabe6ac0   rsp = 0x00007ffeeabe6a40
[task 2021-10-07T21:43:02.603Z] 21:43:02     INFO -     rip = 0x00007fff39555e3e
[task 2021-10-07T21:43:02.603Z] 21:43:02     INFO -     Found by: previous frame's frame pointer
[task 2021-10-07T21:43:02.603Z] 21:43:02     INFO -  4  HIToolbox!RunCurrentEventLoopInMode + 0x124
[task 2021-10-07T21:43:02.603Z] 21:43:02     INFO -     rbp = 0x00007ffeeabe6b10   rsp = 0x00007ffeeabe6ad0
[task 2021-10-07T21:43:02.603Z] 21:43:02     INFO -     rip = 0x00007fff38182abd
[task 2021-10-07T21:43:02.603Z] 21:43:02     INFO -     Found by: previous frame's frame pointer
[task 2021-10-07T21:43:02.603Z] 21:43:02     INFO -  5  HIToolbox!ReceiveNextEventCommon + 0x248
[task 2021-10-07T21:43:02.603Z] 21:43:02     INFO -     rbx = 0x0000000000000001   rbp = 0x00007ffeeabe6b90
[task 2021-10-07T21:43:02.603Z] 21:43:02     INFO -     rsp = 0x00007ffeeabe6b20   r12 = 0x0000000000000000
[task 2021-10-07T21:43:02.603Z] 21:43:02     INFO -     r13 = 0x0000000000000000   r14 = 0x0000000000000000
[task 2021-10-07T21:43:02.603Z] 21:43:02     INFO -     r15 = 0x00000000ffffd96d   rip = 0x00007fff381827d5
[task 2021-10-07T21:43:02.603Z] 21:43:02     INFO -     Found by: call frame info
[task 2021-10-07T21:43:02.603Z] 21:43:02     INFO -  6  HIToolbox!_BlockUntilNextEventMatchingListInModeWithFilter + 0x40
[task 2021-10-07T21:43:02.603Z] 21:43:02     INFO -     rbx = 0xffffffffffffffff   rbp = 0x00007ffeeabe6bb0
[task 2021-10-07T21:43:02.603Z] 21:43:02     INFO -     rsp = 0x00007ffeeabe6ba0   r12 = 0x0000000000000001
[task 2021-10-07T21:43:02.603Z] 21:43:02     INFO -     r13 = 0x0000000000000000   r14 = 0x00007fff99d074c0
[task 2021-10-07T21:43:02.603Z] 21:43:02     INFO -     r15 = 0x00007fff90e0ab00   rip = 0x00007fff38182579
[task 2021-10-07T21:43:02.603Z] 21:43:02     INFO -     Found by: call frame info
[task 2021-10-07T21:43:02.603Z] 21:43:02     INFO -  7  AppKit!_DPSNextEvent + 0x373
[task 2021-10-07T21:43:02.603Z] 21:43:02     INFO -     rbx = 0xffffffffffffffff   rbp = 0x00007ffeeabe6fb0
[task 2021-10-07T21:43:02.603Z] 21:43:02     INFO -     rsp = 0x00007ffeeabe6bc0   r12 = 0x0000000000000001
[task 2021-10-07T21:43:02.604Z] 21:43:02     INFO -     r13 = 0x0000000000000000   r14 = 0x00007fff99d074c0
[task 2021-10-07T21:43:02.604Z] 21:43:02     INFO -     r15 = 0x00007fff90e0ab00   rip = 0x00007fff367c8039
[task 2021-10-07T21:43:02.604Z] 21:43:02     INFO -     Found by: call frame info
[task 2021-10-07T21:43:02.604Z] 21:43:02     INFO -  8  AppKit!-[NSApplication(NSEvent) _nextEventMatchingEventMask:untilDate:inMode:dequeue:] + 0x548
[task 2021-10-07T21:43:02.604Z] 21:43:02     INFO -     rbp = 0x00007ffeeabe7210   rsp = 0x00007ffeeabe6fc0
[task 2021-10-07T21:43:02.604Z] 21:43:02     INFO -     rip = 0x00007fff367c6880
[task 2021-10-07T21:43:02.604Z] 21:43:02     INFO -     Found by: previous frame's frame pointer
[task 2021-10-07T21:43:02.604Z] 21:43:02     INFO -  9  XUL!-[GeckoNSApplication nextEventMatchingMask:untilDate:inMode:dequeue:] [nsAppShell.mm:23286eb22089a33caf64a07d962ea36d9996d233 : 171 + 0x1d]
[task 2021-10-07T21:43:02.604Z] 21:43:02     INFO -     rbp = 0x00007ffeeabe72f0   rsp = 0x00007ffeeabe7220
[task 2021-10-07T21:43:02.604Z] 21:43:02     INFO -     rip = 0x0000000106755743
[task 2021-10-07T21:43:02.604Z] 21:43:02     INFO -     Found by: previous frame's frame pointer
[task 2021-10-07T21:43:02.604Z] 21:43:02     INFO - 10  AppKit!-[NSApplication run] + 0x292
[task 2021-10-07T21:43:02.604Z] 21:43:02     INFO -     rbp = 0x00007ffeeabe72f0   rsp = 0x00007ffeeabe7240
[task 2021-10-07T21:43:02.604Z] 21:43:02     INFO -     rip = 0x00007fff367b858e
[task 2021-10-07T21:43:02.604Z] 21:43:02     INFO -     Found by: call frame info
[task 2021-10-07T21:43:02.604Z] 21:43:02     INFO - 11  AppKit!.str.38 + 0x866e7
[task 2021-10-07T21:43:02.604Z] 21:43:02     INFO -     rbp = 0x00007ffeeabe72f0   rsp = 0x00007ffeeabe7268
[task 2021-10-07T21:43:02.604Z] 21:43:02     INFO -     rip = 0x00007fff3741fe59
[task 2021-10-07T21:43:02.604Z] 21:43:02     INFO -     Found by: stack scanning
[task 2021-10-07T21:43:02.604Z] 21:43:02     INFO - 12  AppKit!.str.38 + 0x8672d
[task 2021-10-07T21:43:02.604Z] 21:43:02     INFO -     rbp = 0x00007ffeeabe72f0   rsp = 0x00007ffeeabe7290
[task 2021-10-07T21:43:02.604Z] 21:43:02     INFO -     rip = 0x00007fff3741fe9f
[task 2021-10-07T21:43:02.605Z] 21:43:02     INFO -     Found by: stack scanning
[task 2021-10-07T21:43:02.605Z] 21:43:02     INFO - 13  AppKit!.str.38 + 0x8672d
[task 2021-10-07T21:43:02.605Z] 21:43:02     INFO -     rbp = 0x00007ffeeabe72f0   rsp = 0x00007ffeeabe72b8
[task 2021-10-07T21:43:02.605Z] 21:43:02     INFO -     rip = 0x00007fff3741fe9f
[task 2021-10-07T21:43:02.605Z] 21:43:02     INFO -     Found by: stack scanning
[task 2021-10-07T21:43:02.605Z] 21:43:02     INFO - 14  XUL!nsAppShell::Run() [nsAppShell.mm:23286eb22089a33caf64a07d962ea36d9996d233 : 741 + 0x19]
[task 2021-10-07T21:43:02.605Z] 21:43:02     INFO -     rsp = 0x00007ffeeabe7300   rip = 0x0000000106756472
[task 2021-10-07T21:43:02.605Z] 21:43:02     INFO -     Found by: stack scanning
[task 2021-10-07T21:43:02.605Z] 21:43:02     INFO - 15  XUL!nsAppStartup::Run() [nsAppStartup.cpp:23286eb22089a33caf64a07d962ea36d9996d233 : 273 + 0xa]
[task 2021-10-07T21:43:02.605Z] 21:43:02     INFO -     rbx = 0x00000001141a66a0   rbp = 0x00007ffeeabe73d4
[task 2021-10-07T21:43:02.605Z] 21:43:02     INFO -     rsp = 0x00007ffeeabe7330   rip = 0x000000010a35ca98
[task 2021-10-07T21:43:02.605Z] 21:43:02     INFO -     Found by: call frame info
[task 2021-10-07T21:43:02.605Z] 21:43:02     INFO - 16  XUL!XREMain::XRE_mainRun() [nsAppRunner.cpp:23286eb22089a33caf64a07d962ea36d9996d233 : 5249 + 0xb]
[task 2021-10-07T21:43:02.605Z] 21:43:02     INFO -     rbx = 0x00007ffeeabe73c0   rbp = 0x00007ffeeabe73d4
[task 2021-10-07T21:43:02.605Z] 21:43:02     INFO -     rsp = 0x00007ffeeabe7360   rip = 0x0000000106bae3ad
[task 2021-10-07T21:43:02.605Z] 21:43:02     INFO -     Found by: call frame info
[task 2021-10-07T21:43:02.605Z] 21:43:02     INFO - 17  XUL!XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) [nsAppRunner.cpp:23286eb22089a33caf64a07d962ea36d9996d233 : 5434 + 0x8]
[task 2021-10-07T21:43:02.605Z] 21:43:02     INFO -     rbx = 0x0000000000000000   rbp = 0x000000010520f370
[task 2021-10-07T21:43:02.605Z] 21:43:02     INFO -     rsp = 0x00007ffeeabe74f0   r12 = 0x00007ffeeabe7570
[task 2021-10-07T21:43:02.605Z] 21:43:02     INFO -     r13 = 0x0002002100000009   r14 = 0x00007ffeeabe76c0
[task 2021-10-07T21:43:02.605Z] 21:43:02     INFO -     r15 = 0x0000000000000000   rip = 0x000000010a3d94d4
[task 2021-10-07T21:43:02.605Z] 21:43:02     INFO -     Found by: call frame info
[task 2021-10-07T21:43:02.605Z] 21:43:02     INFO - 18  XUL!XRE_main(int, char**, mozilla::BootstrapConfig const&) [nsAppRunner.cpp:23286eb22089a33caf64a07d962ea36d9996d233 : 5493 + 0x10]
[task 2021-10-07T21:43:02.605Z] 21:43:02     INFO -     rbx = 0x00007ffeeabe7b10   rbp = 0x0000000000000005
[task 2021-10-07T21:43:02.605Z] 21:43:02     INFO -     rsp = 0x00007ffeeabe7570   r12 = 0x00007ffeeabe7b10
[task 2021-10-07T21:43:02.605Z] 21:43:02     INFO -     r13 = 0x00000099aeedcc6d   r14 = 0x00007ffeeabe76c0
[task 2021-10-07T21:43:02.606Z] 21:43:02     INFO -     r15 = 0x00007ffeeabe7570   rip = 0x000000010a3d9962
[task 2021-10-07T21:43:02.606Z] 21:43:02     INFO -     Found by: call frame info
[task 2021-10-07T21:43:02.606Z] 21:43:02     INFO - 19  firefox!main [nsBrowserApp.cpp:23286eb22089a33caf64a07d962ea36d9996d233 : 378 + 0x86]
[task 2021-10-07T21:43:02.606Z] 21:43:02     INFO -     rbx = 0x00007ffeeabe76c0   rbp = 0x00007ffeeabe76ac
[task 2021-10-07T21:43:02.606Z] 21:43:02     INFO -     rsp = 0x00007ffeeabe76a0   r12 = 0x00007ffeeabe7b10
[task 2021-10-07T21:43:02.606Z] 21:43:02     INFO -     r13 = 0x00000099aeedcc6d   r14 = 0x00007ffeeabe7b40
[task 2021-10-07T21:43:02.606Z] 21:43:02     INFO -     r15 = 0x0000000000000005   rip = 0x00000001050194d4
[task 2021-10-07T21:43:02.606Z] 21:43:02     INFO -     Found by: call frame info
[task 2021-10-07T21:43:02.606Z] 21:43:02     INFO - 20  libdyld.dylib!start + 0x1
[task 2021-10-07T21:43:02.606Z] 21:43:02     INFO -     rbx = 0x0000000000000000   rbp = 0x00007ffeeabe7b00
[task 2021-10-07T21:43:02.606Z] 21:43:02     INFO -     rsp = 0x00007ffeeabe7b00   r12 = 0x0000000000000000
[task 2021-10-07T21:43:02.606Z] 21:43:02     INFO -     r13 = 0x0000000000000000   r14 = 0x0000000000000000
[task 2021-10-07T21:43:02.606Z] 21:43:02     INFO -     r15 = 0x0000000000000000   rip = 0x00007fff7345ecc9
[task 2021-10-07T21:43:02.606Z] 21:43:02     INFO -     Found by: call frame info
[task 2021-10-07T21:43:02.606Z] 21:43:02     INFO - 
[task 2021-10-07T21:43:02.606Z] 21:43:02     INFO - Thread 1 - Breakpad ExceptionHandler
[task 2021-10-07T21:43:02.606Z] 21:43:02     INFO -  0  libsystem_kernel.dylib!mach_msg_trap + 0xa

Expected crash after a test timed out.

Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INVALID
You need to log in before you can comment on or make changes to this bug.