Closed Bug 1553625 Opened 5 years ago Closed 5 years ago

Intermittent Last test finished | application crashed [@ mozilla::ipc::MessageChannel::Close()]

Categories

(Core :: IPC, defect, P3)

Product:
Core
Component:
IPC
Type:
defect

Tracking

()

Status:
RESOLVED FIXED
Milestone:
mozilla69
Tracking Flags:
Tracking Status
firefox-esr60 --- wontfix
firefox67 --- wontfix
firefox68 --- fixed
firefox69 --- fixed

People

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

References

Details

(Keywords: crash, intermittent-failure, regression)

Crash Data

Filed by: btara [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=247815626&repo=mozilla-central
Full log: https://queue.taskcluster.net/v1/task/Y_g6SCCQSrOPlmWuzWFZUg/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/Y_g6SCCQSrOPlmWuzWFZUg/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

18:23:35 INFO - REFTEST TEST-START | file:///Z:/task_1558542783/build/tests/reftest/tests/layout/reftests/w3c-css/submitted/images3/color-stop-currentcolor.html == file:///Z:/task_1558542783/build/tests/reftest/tests/layout/reftests/w3c-css/submitted/images3/color-stop-currentcolor-ref.html
18:23:35 INFO - REFTEST TEST-LOAD | file:///Z:/task_1558542783/build/tests/reftest/tests/layout/reftests/w3c-css/submitted/images3/color-stop-currentcolor.html | 188 / 189 (99%)
18:23:35 INFO - ++DOMWINDOW == 2189 (0000027CF229BC00) [pid = 6076] [serial = 3002] [outer = 0000027CEDC1A020]
18:23:35 INFO - REFTEST TEST-LOAD | file:///Z:/task_1558542783/build/tests/reftest/tests/layout/reftests/w3c-css/submitted/images3/color-stop-currentcolor-ref.html | 188 / 189 (99%)
18:23:35 INFO - ++DOMWINDOW == 2190 (0000027CF20DA000) [pid = 6076] [serial = 3003] [outer = 0000027CEDC1A020]
18:23:35 INFO - REFTEST TEST-PASS | file:///Z:/task_1558542783/build/tests/reftest/tests/layout/reftests/w3c-css/submitted/images3/color-stop-currentcolor.html == file:///Z:/task_1558542783/build/tests/reftest/tests/layout/reftests/w3c-css/submitted/images3/color-stop-currentcolor-ref.html | image comparison, max difference: 0, number of differing pixels: 0
18:23:35 INFO - REFTEST TEST-END | file:///Z:/task_1558542783/build/tests/reftest/tests/layout/reftests/w3c-css/submitted/images3/color-stop-currentcolor.html == file:///Z:/task_1558542783/build/tests/reftest/tests/layout/reftests/w3c-css/submitted/images3/color-stop-currentcolor-ref.html
18:23:35 INFO - ++DOMWINDOW == 2191 (0000027CF1E55C00) [pid = 6076] [serial = 3004] [outer = 0000027CEDC1A020]
18:23:35 INFO - REFTEST INFO | Slowest test took 3821ms (file:///Z:/task_1558542783/build/tests/reftest/tests/layout/reftests/w3c-css/submitted/images3/object-fit-scale-down-svg-003i.html)
18:23:35 INFO - REFTEST INFO | Total canvas count = 2
18:23:35 INFO - --DOCSHELL 0000027CF29BB000 == 187 [pid = 6076] [id = {15f4ed47-87db-4f22-96ce-cd692e9c4f49}] [url = file:///Z:/task_1558542783/build/tests/reftest/tests/layout/reftests/w3c-css/submitted/images3/support/colors-8x16.svg]
18:23:36 INFO - --DOCSHELL 0000023B3A6DC000 == 4 [pid = 2940] [id = {b95f5d1e-81db-43a8-b2a3-ef862d220b04}] [url = chrome://reftest/content/reftest.xul]
18:23:36 INFO - --DOCSHELL 0000023B3754E000 == 3 [pid = 2940] [id = {83aea134-02c7-480b-b677-16e030479b06}] [url = chrome://extensions/content/dummy.xul]
18:23:36 INFO - --DOCSHELL 0000023B3A514800 == 2 [pid = 2940] [id = {b4b75fe7-c404-4441-bf67-4e054b3ef1af}] [url = about:blank]
18:23:36 INFO - [Child 7308, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:23:36 INFO - [Child 7308, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:23:36 INFO - JavaScript error: resource://reftest/reftest.jsm, line 1558: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPropertyBag2.getPropertyAsAString]
18:23:36 INFO - [Parent 2940, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:23:36 INFO - [GPU 9456, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:23:36 INFO - [GPU 9456, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome
18:23:36 INFO - ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
18:23:36 INFO - /common/ipc_channel_win.cc, line 341
18:23:36 INFO - [GPU 9456, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:23:36 INFO - [Parent 2940, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:23:36 INFO - [Parent 2940, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:23:36 INFO - [Parent 2940, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:23:36 INFO - --DOCSHELL 000002657FD79000 == 4 [pid = 7308] [id = {164322c0-6ef7-4f92-b52e-8c811d377896}] [url = moz-extension://d2a8131b-7d0e-4ecd-958f-8dc11b8425cc/_generated_background_page.html]
18:23:36 INFO - --DOCSHELL 000002657FD75800 == 3 [pid = 7308] [id = {c7d55be8-1475-45ae-a2c1-3f2ed4557504}] [url = moz-extension://ffd0db63-d6e2-4f9d-8a1d-0ce6d1a24d9e/_generated_background_page.html]
18:23:36 INFO - --DOCSHELL 000002657FD77800 == 2 [pid = 7308] [id = {04322e09-15ce-4e69-9979-21f5a168cf35}] [url = moz-extension://aaf4770d-c3bf-4f4c-9a2b-f3c5a4e356af/_generated_background_page.html]
18:23:36 INFO - --DOCSHELL 000002657FD7C800 == 1 [pid = 7308] [id = {dc501819-5be8-410f-ba49-8c4d6736d1ed}] [url = moz-extension://2b3ed19e-2857-4946-bfee-a2046a506582/_generated_background_page.html]
18:23:36 INFO - --DOCSHELL 000002657FD7A800 == 0 [pid = 7308] [id = {31f3c9b7-b2b8-46e3-bff5-2005138a8ca6}] [url = moz-extension://7b8b0625-5988-49ef-beb3-c9785f2f93b4/_generated_background_page.html]
18:23:37 INFO - --DOMWINDOW == 9 (000002650C5437A0) [pid = 7308] [serial = 4] [outer = 0000000000000000] [url = moz-extension://7b8b0625-5988-49ef-beb3-c9785f2f93b4/_generated_background_page.html]
...
18:23:51 INFO - ### ERROR: SymInitialize: The parameter is incorrect.
18:23:51 INFO - #10: ??? (???:???)
18:23:51 INFO - nsStringStats
18:23:51 INFO - => mAllocCount: 1716
18:23:51 INFO - => mReallocCount: 0
18:23:51 INFO - => mFreeCount: 1716
18:23:51 INFO - => mShareCount: 1127
18:23:51 INFO - => mAdoptCount: 0
18:23:51 INFO - => mAdoptFreeCount: 0
18:23:51 INFO - => Process ID: 9456, Thread ID: 5608
18:23:51 INFO - --DOMWINDOW == 14 (0000023B33E6B000) [pid = 2940] [serial = 4] [outer = 0000000000000000] [url = about:blank]
18:23:51 INFO - --DOMWINDOW == 13 (0000023B37D09000) [pid = 2940] [serial = 10] [outer = 0000000000000000] [url = about:blank]
18:23:51 INFO - --DOMWINDOW == 12 (0000023B37D05800) [pid = 2940] [serial = 19] [outer = 0000000000000000] [url = about:blank]
18:23:51 INFO - --DOMWINDOW == 11 (0000023B367A72E0) [pid = 2940] [serial = 16] [outer = 0000000000000000] [url = about:blank]
18:23:51 INFO - --DOMWINDOW == 10 (0000023B32AB0400) [pid = 2940] [serial = 17] [outer = 0000000000000000] [url = about:blank]
18:23:51 INFO - --DOMWINDOW == 9 (0000023B32EF4C40) [pid = 2940] [serial = 18] [outer = 0000000000000000] [url = chrome://reftest/content/reftest.xul]
18:23:51 INFO - --DOMWINDOW == 8 (0000023B3345E400) [pid = 2940] [serial = 2] [outer = 0000000000000000] [url = about:blank]
18:23:51 INFO - --DOMWINDOW == 7 (0000023B32EF3B60) [pid = 2940] [serial = 3] [outer = 0000000000000000] [url = chrome://browser/content/browser.xhtml]
18:23:51 INFO - --DOMWINDOW == 6 (0000023B32EF4E20) [pid = 2940] [serial = 13] [outer = 0000000000000000] [url = resource://gre-resources/hiddenWindow.html]
18:23:51 INFO - --DOMWINDOW == 5 (0000023B32EF3200) [pid = 2940] [serial = 1] [outer = 0000000000000000] [url = chrome://gfxsanity/content/sanityparent.html]
18:23:51 INFO - --DOMWINDOW == 4 (0000023B3643D200) [pid = 2940] [serial = 8] [outer = 0000000000000000] [url = about:blank]
18:23:51 INFO - --DOMWINDOW == 3 (0000023B37D02800) [pid = 2940] [serial = 9] [outer = 0000000000000000] [url = about:blank]
18:23:51 INFO - --DOMWINDOW == 2 (0000023B3643D7A0) [pid = 2940] [serial = 6] [outer = 0000000000000000] [url = chrome://extensions/content/dummy.xul]
18:23:51 INFO - --DOMWINDOW == 1 (0000023B37D0D000) [pid = 2940] [serial = 11] [outer = 0000000000000000] [url = chrome://extensions/content/dummy.xul]
18:23:52 INFO - --DOMWINDOW == 0 (0000023B392A4C00) [pid = 2940] [serial = 15] [outer = 0000000000000000] [url = resource://gre-resources/hiddenWindow.html]
18:23:57 INFO - nsStringStats
18:23:57 INFO - => mAllocCount: 173740
18:23:57 INFO - => mReallocCount: 0
18:23:57 INFO - => mFreeCount: 173740
18:23:57 INFO - => mShareCount: 152004
18:23:57 INFO - => mAdoptCount: 2868
18:23:57 INFO - => mAdoptFreeCount: 9018
18:23:57 INFO - => Process ID: 2940, Thread ID: 2156
18:24:03 INFO - REFTEST INFO | Copy/paste: Z:\task_1558542783\build\win32-minidump_stackwalk.exe c:\users\task_1558542783\appdata\local\temp\tmprgf0kb.mozrunner\minidumps\60083a1d-9c44-4f8f-bd64-adbc2b845088.dmp Z:\task_1558542783\build\symbols
18:24:19 INFO - REFTEST INFO | Saved minidump as Z:\task_1558542783\build\blobber_upload_dir\60083a1d-9c44-4f8f-bd64-adbc2b845088.dmp
18:24:19 INFO - REFTEST PROCESS-CRASH | Last test finished | application crashed [@ mozilla::ipc::MessageChannel::Close()]
18:24:19 INFO - Crash dump filename: c:\users\task_1558542783\appdata\local\temp\tmprgf0kb.mozrunner\minidumps\60083a1d-9c44-4f8f-bd64-adbc2b845088.dmp
18:24:19 INFO - Operating system: Windows NT
18:24:19 INFO - 10.0.17134
18:24:19 INFO - CPU: amd64
18:24:19 INFO - family 6 model 79 stepping 1
18:24:19 INFO - 16 CPUs
18:24:19 INFO -
18:24:19 INFO - GPU: UNKNOWN
18:24:19 INFO -
18:24:19 INFO - Crash reason: EXCEPTION_BREAKPOINT
18:24:19 INFO - Crash address: 0x7fffd2e1633b
18:24:19 INFO - Assertion: Unknown assertion type 0x00000000
18:24:19 INFO - Process uptime: 188 seconds
18:24:19 INFO -
18:24:19 INFO - Thread 14 (crashed)
18:24:19 INFO - 0 xul.dll!mozilla::ipc::MessageChannel::Close() [MessageChannel.cpp:aaae630f30291056f4f40bbd9e12a917309e401e : 2701 + 0x0]
18:24:19 INFO - rax = 0x00007ff812e89be0 rdx = 0x00007ff821eba640
18:24:19 INFO - rcx = 0x00007fffe2596147 rbx = 0x0000027ce160a560
18:24:19 INFO - rsi = 0x0000027ce5a8c148 rdi = 0x00007fffe91be868
18:24:19 INFO - rbp = 0x0000027ce5a8b548 rsp = 0x0000009ca550fad0
18:24:19 INFO - r8 = 0x0000009ca5509bc8 r9 = 0x0000009ca550b223
18:24:19 INFO - r10 = 0x0000000000000000 r11 = 0x0000009ca550b0f0
18:24:19 INFO - r12 = 0x00007ff80fb96308 r13 = 0x00007ff80fa6ee1e
18:24:19 INFO - r14 = 0x0000027ce5a8c178 r15 = 0x00007ff80fb963c0
18:24:19 INFO - rip = 0x00007fffd2e1633b
18:24:19 INFO - Found by: given as instruction pointer in context
18:24:19 INFO - 1 xul.dll!static void anonymous namespace'::ChildImpl::ThreadLocalDestructor(void *) [BackgroundImpl.cpp:aaae630f30291056f4f40bbd9e12a917309e401e : 1703 + 0x18] 18:24:19 INFO - rbx = 0x0000027ce160a560 rbp = 0x0000027ce5a8b548 18:24:19 INFO - rsp = 0x0000009ca550fb60 r12 = 0x00007ff80fb96308 18:24:19 INFO - r13 = 0x00007ff80fa6ee1e r14 = 0x0000027ce5a8c178 18:24:19 INFO - r15 = 0x00007ff80fb963c0 rip = 0x00007fffd2dd3dba 18:24:19 INFO - Found by: call frame info 18:24:19 INFO - 2 nss3.dll!PR_SetThreadPrivate [prtpd.c:aaae630f30291056f4f40bbd9e12a917309e401e : 184 + 0x3] 18:24:19 INFO - rbx = 0x0000027ce160a560 rbp = 0x0000027ce5a8b548 18:24:19 INFO - rsp = 0x0000009ca550fbe0 r12 = 0x00007ff80fb96308 18:24:19 INFO - r13 = 0x00007ff80fa6ee1e r14 = 0x0000027ce5a8c178 18:24:19 INFO - r15 = 0x00007ff80fb963c0 rip = 0x00007ff80f83e64a 18:24:19 INFO - Found by: call frame info 18:24:19 INFO - 3 xul.dll!static voidanonymous namespace'::ChildImpl::CloseThreadWithIndex(unsigned int) [BackgroundImpl.cpp:aaae630f30291056f4f40bbd9e12a917309e401e : 1655 + 0xa]
18:24:19 INFO - rbx = 0x0000027ce160a560 rbp = 0x0000027ce5a8b548
18:24:19 INFO - rsp = 0x0000009ca550fc30 r12 = 0x00007ff80fb96308
18:24:19 INFO - r13 = 0x00007ff80fa6ee1e r14 = 0x0000027ce5a8c178
18:24:19 INFO - r15 = 0x00007ff80fb963c0 rip = 0x00007fffd2ddae24
18:24:19 INFO - Found by: call frame info
18:24:19 INFO - 4 xul.dll!mozilla::ipc::BackgroundChild::CloseForCurrentThread() [BackgroundImpl.cpp:aaae630f30291056f4f40bbd9e12a917309e401e : 669 + 0x5d]
18:24:19 INFO - rbx = 0x0000027ce160a560 rbp = 0x0000027ce5a8b548
18:24:19 INFO - rsp = 0x0000009ca550fc70 r12 = 0x00007ff80fb96308
18:24:19 INFO - r13 = 0x00007ff80fa6ee1e r14 = 0x0000027ce5a8c178
18:24:19 INFO - r15 = 0x00007ff80fb963c0 rip = 0x00007fffd2da6647
18:24:19 INFO - Found by: call frame info
18:24:19 INFO - 5 xul.dll!nsThread::ThreadFunc(void *) [nsThread.cpp:aaae630f30291056f4f40bbd9e12a917309e401e : 456 + 0x5]
18:24:19 INFO - rbx = 0x0000027ce160a560 rbp = 0x0000027ce5a8b548
18:24:19 INFO - rsp = 0x0000009ca550fcb0 r12 = 0x00007ff80fb96308
18:24:19 INFO - r13 = 0x00007ff80fa6ee1e r14 = 0x0000027ce5a8c178
18:24:19 INFO - r15 = 0x00007ff80fb963c0 rip = 0x00007fffd1c7510a
18:24:19 INFO - Found by: call frame info
18:24:19 INFO - 6 nss3.dll!PR_NativeRunThread [pruthr.c:aaae630f30291056f4f40bbd9e12a917309e401e : 397 + 0x7]
18:24:19 INFO - rbx = 0x0000027ce160a560 rbp = 0x0000027ce5a8b548
18:24:19 INFO - rsp = 0x0000009ca550fd20 r12 = 0x00007ff80fb96308
18:24:19 INFO - r13 = 0x00007ff80fa6ee1e r14 = 0x0000027ce5a8c178
18:24:19 INFO - r15 = 0x00007ff80fb963c0 rip = 0x00007ff80f83616f
18:24:19 INFO - Found by: call frame info
18:24:19 INFO - 7 nss3.dll!static unsigned int pr_root(void *) [w95thred.c:aaae630f30291056f4f40bbd9e12a917309e401e : 137 + 0x6]
18:24:19 INFO - rbx = 0x0000027ce160a560 rbp = 0x0000027ce5a8b548
18:24:19 INFO - rsp = 0x0000009ca550fdb0 r12 = 0x00007ff80fb96308
18:24:19 INFO - r13 = 0x00007ff80fa6ee1e r14 = 0x0000027ce5a8c178
18:24:19 INFO - r15 = 0x00007ff80fb963c0 rip = 0x00007ff80f8159e2
18:24:19 INFO - Found by: call frame info
18:24:19 INFO - 8 ucrtbase.dll!RtlpHpSegPageRangeShrink + 0xda
18:24:19 INFO - rbx = 0x0000027ce160a560 rbp = 0x0000027ce5a8b548
18:24:19 INFO - rsp = 0x0000009ca550fde0 r12 = 0x00007ff80fb96308
18:24:19 INFO - r13 = 0x00007ff80fa6ee1e r14 = 0x0000027ce5a8c178
18:24:19 INFO - r15 = 0x00007ff80fb963c0 rip = 0x00007ff821dec4be
18:24:19 INFO - Found by: call frame info
18:24:19 INFO -

Summary: Intermittent Windows Reftest Last test finished | application crashed [@ mozilla::ipc::MessageChannel::Close()] → Intermittent Last test finished | application crashed [@ mozilla::ipc::MessageChannel::Close()]

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

For more information, please visit auto_nag documentation.

Flags: needinfo?(dholbert)

Not sure why this is in Layout. Moving to IPC & retargeting needinfo to Jed.

Component: Layout → IPC
Flags: needinfo?(dholbert) → needinfo?(jld)

I assume it was in Layout because it was filed from a reftest failure; this heuristic might be reasonable for failures in feature code, but for intermittent failures in infrastructure code it just splits the problem into lots of misfiled bugs that nobody looks at, and none of which will seem important because each one has only a fraction of the total failures. I've complained about this before.

(On the other hand, there are bugs that end up in IPC because there was IPC stuff in the stack, but the correct component is whatever was calling into IPC.)

Anyway, this is basically a duplicate of bug 1554244, along with a few other bugs (see above), but it's marked as dependent because Bugzilla's mass-edit feature doesn't allow closing as a duplicate.

Flags: needinfo?(jld)
Priority: -- → P3

Fixed by bug 1554244.

Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → FIXED
Assignee: nobody → jld
status-firefox67: --- → wontfix
status-firefox68: --- → affected
status-firefox69: --- → fixed
status-firefox-esr60: --- → wontfix
Target Milestone: --- → mozilla69

Mass-editing the bug 1554244 duplicates to set qe-verify -: they're intermittents with no reliable STR, so if we stop seeing them on CI then it's safe to say they're fixed.

Flags: qe-verify-
You need to log in before you can comment on or make changes to this bug.