Closed Bug 1565221 Opened 6 years ago Closed 5 years ago

Intermittent Last test finished | application crashed [@ nsTArray_Impl<nsAutoPtr<nsThreadShutdownContext>, nsTArrayInfallibleAllocator>::~nsTArray_Impl()]

Categories

(Core :: XPCOM, defect)

Product:
Core
Component:
XPCOM
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: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=255894977&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/ewXz0JK4TOyHovg-U3WYOQ/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://queue.taskcluster.net/v1/task/ewXz0JK4TOyHovg-U3WYOQ/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

[task 2019-07-11T08:39:23.649Z] 08:39:23 INFO - [Parent 11412, Gecko_IOThread] WARNING: pipe error: Broken pipe: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 728
[task 2019-07-11T08:39:24.132Z] 08:39:24 INFO - --DOCSHELL 0x7f661bd3d000 == 2 [pid = 11412] [id = {69c7393e-596c-4203-acd2-c3bbcdcd6a6e}] [url = resource://gre-resources/hiddenWindow.html]
[task 2019-07-11T08:39:24.148Z] 08:39:24 INFO - --DOCSHELL 0x7f661881b000 == 1 [pid = 11412] [id = {8f660fca-7e2f-49f5-820f-4ac1bc90aaea}] [url = about:blank]
[task 2019-07-11T08:39:24.148Z] 08:39:24 INFO - --DOCSHELL 0x7f661da1a000 == 0 [pid = 11412] [id = {bb293a5b-726c-4a94-b464-61116bd9a7ca}] [url = chrome://extensions/content/dummy.xul]
[task 2019-07-11T08:39:25.330Z] 08:39:25 INFO - --DOMWINDOW == 9 (0x7f662d594880) [pid = 11412] [serial = 1] [outer = (nil)] [url = chrome://browser/content/browser.xhtml]
[task 2019-07-11T08:39:25.330Z] 08:39:25 INFO - --DOMWINDOW == 8 (0x7f661dbd3c00) [pid = 11412] [serial = 16] [outer = (nil)] [url = about:blank]
[task 2019-07-11T08:39:25.330Z] 08:39:25 INFO - --DOMWINDOW == 7 (0x7f662ae8d020) [pid = 11412] [serial = 15] [outer = (nil)] [url = chrome://reftest/content/reftest.xul]
[task 2019-07-11T08:39:25.330Z] 08:39:25 INFO - --DOMWINDOW == 6 (0x7f661bb6c880) [pid = 11412] [serial = 13] [outer = (nil)] [url = about:blank]
[task 2019-07-11T08:39:25.330Z] 08:39:25 INFO - --DOMWINDOW == 5 (0x7f6619419800) [pid = 11412] [serial = 14] [outer = (nil)] [url = about:blank]
[task 2019-07-11T08:39:25.330Z] 08:39:25 INFO - --DOMWINDOW == 4 (0x7f661e0ef5c0) [pid = 11412] [serial = 5] [outer = (nil)] [url = chrome://extensions/content/dummy.xul]
[task 2019-07-11T08:39:25.331Z] 08:39:25 INFO - --DOMWINDOW == 3 (0x7f661da41000) [pid = 11412] [serial = 7] [outer = (nil)] [url = chrome://extensions/content/dummy.xul]
[task 2019-07-11T08:39:25.331Z] 08:39:25 INFO - --DOMWINDOW == 2 (0x7f661bb6b7a0) [pid = 11412] [serial = 10] [outer = (nil)] [url = resource://gre-resources/hiddenWindow.html]
[task 2019-07-11T08:39:25.346Z] 08:39:25 INFO - --DOMWINDOW == 1 (0x7f661fb5e400) [pid = 11412] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2019-07-11T08:39:25.347Z] 08:39:25 INFO - --DOMWINDOW == 0 (0x7f661ac14400) [pid = 11412] [serial = 12] [outer = (nil)] [url = resource://gre-resources/hiddenWindow.html]
[task 2019-07-11T08:39:25.424Z] 08:39:25 INFO - [Parent 11412, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
[task 2019-07-11T08:39:25.426Z] 08:39:25 INFO - [Parent 11412, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
[task 2019-07-11T08:39:25.426Z] 08:39:25 INFO - nsStringStats
[task 2019-07-11T08:39:25.427Z] 08:39:25 INFO - => mAllocCount: 128750
[task 2019-07-11T08:39:25.428Z] 08:39:25 INFO - => mReallocCount: 0
[task 2019-07-11T08:39:25.428Z] 08:39:25 INFO - => mFreeCount: 128750
[task 2019-07-11T08:39:25.429Z] 08:39:25 INFO - => mShareCount: 98899
[task 2019-07-11T08:39:25.430Z] 08:39:25 INFO - => mAdoptCount: 2531
[task 2019-07-11T08:39:25.430Z] 08:39:25 INFO - => mAdoptFreeCount: 3333
[task 2019-07-11T08:39:25.431Z] 08:39:25 INFO - => Process ID: 11412, Thread ID: 140077278181184
[task 2019-07-11T08:39:25.493Z] 08:39:25 INFO - REFTEST INFO | Copy/paste: /builds/worker/workspace/build/linux64-minidump_stackwalk /tmp/tmp9xCACa.mozrunner/minidumps/64fa848f-1d55-3365-3cc8-6b2362b3da62.dmp /builds/worker/workspace/build/symbols
[task 2019-07-11T08:39:32.156Z] 08:39:32 INFO - REFTEST INFO | Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/64fa848f-1d55-3365-3cc8-6b2362b3da62.dmp
[task 2019-07-11T08:39:32.159Z] 08:39:32 INFO - REFTEST PROCESS-CRASH | Last test finished | application crashed [@ nsTArray_Impl<nsAutoPtr<nsThreadShutdownContext>, nsTArrayInfallibleAllocator>::~nsTArray_Impl()]
[task 2019-07-11T08:39:32.160Z] 08:39:32 INFO - Crash dump filename: /tmp/tmp9xCACa.mozrunner/minidumps/64fa848f-1d55-3365-3cc8-6b2362b3da62.dmp
[task 2019-07-11T08:39:32.161Z] 08:39:32 INFO - Operating system: Linux
[task 2019-07-11T08:39:32.162Z] 08:39:32 INFO - 0.0.0 Linux 4.4.0-1014-aws #14taskcluster1-Ubuntu SMP Tue Apr 3 10:27:00 UTC 2018 x86_64
[task 2019-07-11T08:39:32.163Z] 08:39:32 INFO - CPU: amd64
[task 2019-07-11T08:39:32.163Z] 08:39:32 INFO - family 6 model 62 stepping 4
[task 2019-07-11T08:39:32.164Z] 08:39:32 INFO - 2 CPUs
[task 2019-07-11T08:39:32.165Z] 08:39:32 INFO -
[task 2019-07-11T08:39:32.166Z] 08:39:32 INFO - GPU: UNKNOWN
[task 2019-07-11T08:39:32.167Z] 08:39:32 INFO -
[task 2019-07-11T08:39:32.168Z] 08:39:32 INFO - Crash reason: SIGSEGV /0x00000080
[task 2019-07-11T08:39:32.169Z] 08:39:32 INFO - Crash address: 0x0
[task 2019-07-11T08:39:32.170Z] 08:39:32 INFO - Process uptime: not available
[task 2019-07-11T08:39:32.171Z] 08:39:32 INFO -
[task 2019-07-11T08:39:32.171Z] 08:39:32 INFO - Thread 1 (crashed)
[task 2019-07-11T08:39:32.172Z] 08:39:32 INFO - 0 libxul.so!nsTArray_Impl<nsAutoPtr<nsThreadShutdownContext>, nsTArrayInfallibleAllocator>::~nsTArray_Impl() [nsTArray.h:478e22e44bdf746c7367c8df7185fef0521a81b3 : 880 + 0x3]
[task 2019-07-11T08:39:32.173Z] 08:39:32 INFO - rax = 0xe5e5e5e5e5e5e5e5 rdx = 0x00007f8fee875248
[task 2019-07-11T08:39:32.174Z] 08:39:32 INFO - rcx = 0x0000000000000000 rbx = 0x00007f8fe0304070
[task 2019-07-11T08:39:32.175Z] 08:39:32 INFO - rsi = 0x0000000000000000 rdi = 0x00007f8fe0304070
[task 2019-07-11T08:39:32.176Z] 08:39:32 INFO - rbp = 0x00007f8fe08e5e00 rsp = 0x00007f8fe08e5df0
[task 2019-07-11T08:39:32.177Z] 08:39:32 INFO - r8 = 0x0000000000000000 r9 = 0x0000000000000200
[task 2019-07-11T08:39:32.178Z] 08:39:32 INFO - r10 = 0x00007f8ff88dbb50 r11 = 0x0000000000000206
[task 2019-07-11T08:39:32.179Z] 08:39:32 INFO - r12 = 0x0000000000000000 r13 = 0x00007f8ff9d7a2c8
[task 2019-07-11T08:39:32.180Z] 08:39:32 INFO - r14 = 0x00007f8fe0304020 r15 = 0x00007f8fe0302040
[task 2019-07-11T08:39:32.181Z] 08:39:32 INFO - rip = 0x00007f8fe659c5f4
[task 2019-07-11T08:39:32.182Z] 08:39:32 INFO - Found by: given as instruction pointer in context
[task 2019-07-11T08:39:32.183Z] 08:39:32 INFO - 1 libxul.so!nsThread::~nsThread() [nsThread.cpp:478e22e44bdf746c7367c8df7185fef0521a81b3 : 656 + 0x31]
[task 2019-07-11T08:39:32.184Z] 08:39:32 INFO - rbx = 0x00007f8fe0304070 rbp = 0x00007f8fe08e5e20
[task 2019-07-11T08:39:32.185Z] 08:39:32 INFO - rsp = 0x00007f8fe08e5e10 r12 = 0x0000000000000000
[task 2019-07-11T08:39:32.186Z] 08:39:32 INFO - r13 = 0x00007f8ff9d7a2c8 r14 = 0x00007f8fe0304020
[task 2019-07-11T08:39:32.187Z] 08:39:32 INFO - r15 = 0x00007f8fe0302040 rip = 0x00007f8fe659c5b7
[task 2019-07-11T08:39:32.188Z] 08:39:32 INFO - Found by: call frame info
[task 2019-07-11T08:39:32.189Z] 08:39:32 INFO - 2 libxul.so!nsThread::~nsThread() [nsThread.cpp:478e22e44bdf746c7367c8df7185fef0521a81b3 : 639 + 0xe]

Type: -- → defect

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

For more information, please visit auto_nag documentation.

Flags: needinfo?(mats)

This only happened once, so it's hard to know if we've inadvertently fixed it or not (i.e. maybe we should resolve this as INCOMPLETE at some point).

But in any case, it was a shutdown crash in thread teardown code (in PR_DestroyThreadPrivate, calling nsThread::Release, calling ~nsThread, destructing a nsTArray). Possibly a thread double-free, or a reference counting bug somewhere... Hard to guess about where the problem would be, but it's highly unlikely to be a layout bug, since layout doesn't really deal with threads.

Moving to XPCOM to be a bit closer to thread code.

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