1564529 - OSX Mojave (10.14) - Intermittent TEST-UNEXPECTED-CRASH | /fetch/api/basic/text-utf8.html | expected OK

Reporter

Description

•

5 years ago

treeherder

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

Platform: macosx1014/debug
Test: web-platform-test
Chunk: 8

Context:
10:16:49 INFO - TEST-START | /fetch/api/basic/text-utf8.html
10:16:49 INFO - Closing window 111669149697
10:16:49 INFO - PID 2060 | [Parent 2060, Main Thread] WARNING: Constructing RangeBoundary with invalid value: 'mRef || aOffset == 0', file /builds/worker/workspace/build/src/obj-firefox/dist/include/mozilla/RangeBoundary.h, line 79
10:16:49 INFO - PID 2060 | [Parent 2060, Main Thread] WARNING: Constructing RangeBoundary with invalid value: 'mRef || aOffset == 0', file /builds/worker/workspace/build/src/obj-firefox/dist/include/mozilla/RangeBoundary.h, line 79
10:16:49 INFO - PID 2060 | [Child 2063, Main Thread] WARNING: No active window: file /builds/worker/workspace/build/src/js/xpconnect/src/XPCJSContext.cpp, line 662
10:16:49 INFO - PID 2060 | [Child 2113, Main Thread] WARNING: Dispatch failed: file /builds/worker/workspace/build/src/xpcom/io/nsStreamUtils.cpp, line 81
10:16:49 INFO - PID 2060 | [Child 2113, Main Thread] WARNING: Dispatch failed: file /builds/worker/workspace/build/src/xpcom/io/nsStreamUtils.cpp, line 81
10:16:49 INFO - PID 2060 | Assertion failure: false (MOZ_ASSERT_UNREACHABLE: leaking stream event), at /builds/worker/workspace/build/src/xpcom/io/nsStreamUtils.cpp:67
10:16:50 INFO - IOError on command, setting status to CRASH
10:16:50 INFO - mozcrash Copy/paste: /Users/cltbld/tasks/task_1562666219/build/macosx64-minidump_stackwalk /var/folders/v0/86229zr15h38cvllvxcswy0r000017/T/tmpPvx7X_.mozrunner/minidumps/CAE0DC6C-3156-4D4A-A50F-EBA87F09A62C.dmp /Users/cltbld/tasks/task_1562666219/build/symbols
10:16:58 INFO - mozcrash Saved minidump as /Users/cltbld/tasks/task_1562666219/build/blobber_upload_dir/CAE0DC6C-3156-4D4A-A50F-EBA87F09A62C.dmp
10:16:58 INFO - mozcrash Saved app info as /Users/cltbld/tasks/task_1562666219/build/blobber_upload_dir/CAE0DC6C-3156-4D4A-A50F-EBA87F09A62C.extra
10:16:58 INFO - PROCESS-CRASH | /fetch/api/basic/text-utf8.html | application crashed [@ nsInputStreamReadyEvent::~nsInputStreamReadyEvent()]
10:16:58 INFO - Crash dump filename: /var/folders/v0/86229zr15h38cvllvxcswy0r000017/T/tmpPvx7X_.mozrunner/minidumps/CAE0DC6C-3156-4D4A-A50F-EBA87F09A62C.dmp
10:16:58 INFO - Operating system: Mac OS X
10:16:58 INFO - 10.14.0 18A391
10:16:58 INFO - CPU: amd64
10:16:58 INFO - family 6 model 69 stepping 1
10:16:58 INFO - 4 CPUs
10:16:58 INFO -
10:16:58 INFO - GPU: UNKNOWN
10:16:58 INFO -
10:16:58 INFO - Crash reason: EXC_BAD_ACCESS / KERN_INVALID_ADDRESS
10:16:58 INFO - Crash address: 0x0
10:16:58 INFO - Process uptime: 66 seconds
10:16:58 INFO -
10:16:58 INFO - Thread 0 (crashed)
10:16:58 INFO - 0 XUL!nsInputStreamReadyEvent::~nsInputStreamReadyEvent() [nsStreamUtils.cpp:e596664275d5e3e2fdcb7fa8d1447289f99269c3 : 67 + 0x0]
10:16:58 INFO - rax = 0x000000010fa74808 rdx = 0x0000000000000000
10:16:58 INFO - rcx = 0x0000000106194da8 rbx = 0x000000011e3efec0
10:16:58 INFO - rsi = 0x00000000000120a8 rdi = 0x00007fffa9469028
10:16:58 INFO - rbp = 0x00007ffee9dc3c00 rsp = 0x00007ffee9dc3bb0
10:16:58 INFO - r8 = 0x00000000000130a8 r9 = 0x0000000000000000
10:16:58 INFO - r10 = 0x00007fffa9469048 r11 = 0x00007fffa9469040
10:16:58 INFO - r12 = 0x000000011e3efec0 r13 = 0x0000000106194dd0
10:16:58 INFO - r14 = 0x000000011e3eff00 r15 = 0x00000001063cd9e0
10:16:58 INFO - rip = 0x00000001082eae5d
10:16:58 INFO - Found by: given as instruction pointer in context
10:16:58 INFO - 1 XUL!<name omitted> [nsStreamUtils.cpp:e596664275d5e3e2fdcb7fa8d1447289f99269c3 : 47 + 0xe]
10:16:58 INFO - rbp = 0x00007ffee9dc3c20 rsp = 0x00007ffee9dc3c10
10:16:58 INFO - rip = 0x00000001082e80ee
10:16:58 INFO - Found by: previous frame's frame pointer
10:16:58 INFO - 2 XUL!mozilla::Runnable::Release() [nsThreadUtils.cpp:e596664275d5e3e2fdcb7fa8d1447289f99269c3 : 54 + 0xb9]
10:16:58 INFO - rbp = 0x00007ffee9dc3c60 rsp = 0x00007ffee9dc3c30
10:16:58 INFO - rip = 0x0000000108327509
10:16:58 INFO - Found by: previous frame's frame pointer
10:16:58 INFO - 3 XUL!nsPipeEvents::~nsPipeEvents() [nsPipe3.cpp:e596664275d5e3e2fdcb7fa8d1447289f99269c3 : 1133 + 0x3b]
10:16:58 INFO - rbp = 0x00007ffee9dc3c90 rsp = 0x00007ffee9dc3c70
10:16:58 INFO - rip = 0x00000001082dc57e
10:16:58 INFO - Found by: previous frame's frame pointer
10:16:58 INFO - 4 XUL!nsPipe::OnPipeException(nsresult, bool) [nsPipe3.cpp:e596664275d5e3e2fdcb7fa8d1447289f99269c3 : 964 + 0x5]
10:16:58 INFO - rbp = 0x00007ffee9dc3d10 rsp = 0x00007ffee9dc3ca0
10:16:58 INFO - rip = 0x00000001082dc003
10:16:58 INFO - Found by: previous frame's frame pointer
10:16:58 INFO - 5 XUL!nsPipeOutputStream::CloseWithStatus(nsresult) [nsPipe3.cpp:e596664275d5e3e2fdcb7fa8d1447289f99269c3 : 1622 + 0xe]
10:16:58 INFO - rbp = 0x00007ffee9dc3d30 rsp = 0x00007ffee9dc3d20
10:16:58 INFO - rip = 0x00000001082dec9c
10:16:58 INFO - Found by: previous frame's frame pointer
10:16:58 INFO - 6 XUL!mozilla::dom::FetchDriver::OnStopRequest(nsIRequest*, nsresult) [FetchDriver.cpp:e596664275d5e3e2fdcb7fa8d1447289f99269c3 : 1230 + 0x8]
10:16:58 INFO - rbp = 0x00007ffee9dc3de0 rsp = 0x00007ffee9dc3d40
10:16:58 INFO - rip = 0x000000010b0d89df
10:16:58 INFO - Found by: previous frame's frame pointer
10:16:58 INFO - 7 XUL!nsCORSListenerProxy::OnStopRequest(nsIRequest*, nsresult) [nsCORSListenerProxy.cpp:e596664275d5e3e2fdcb7fa8d1447289f99269c3 : 648 + 0x19]
10:16:58 INFO - rbp = 0x00007ffee9dc3e30 rsp = 0x00007ffee9dc3df0
10:16:58 INFO - rip = 0x0000000108897988
10:16:58 INFO - Found by: previous frame's frame pointer
10:16:58 INFO - 8 XUL!mozilla::net::HttpChannelChild::DoOnStopRequest(nsIRequest*, nsresult, nsISupports*) [HttpChannelChild.cpp:e596664275d5e3e2fdcb7fa8d1447289f99269c3 : 1259 + 0x47]
10:16:58 INFO - rbp = 0x00007ffee9dc3ec0 rsp = 0x00007ffee9dc3e40
10:16:58 INFO - rip = 0x000000010884f19f
10:16:58 INFO - Found by: previous frame's frame pointer
10:16:58 INFO - 9 XUL!mozilla::net::HttpChannelChild::OnStopRequest(nsresult const&, mozilla::net::ResourceTimingStruct const&, mozilla::net::nsHttpHeaderArray const&) [HttpChannelChild.cpp:e596664275d5e3e2fdcb7fa8d1447289f99269c3 : 1136 + 0x12]
10:16:58 INFO - rbp = 0x00007ffee9dc3f50 rsp = 0x00007ffee9dc3ed0
10:16:58 INFO - rip = 0x00000001088551bb
10:16:58 INFO - Found by: previous frame's frame pointer
10:16:58 INFO - 10 XUL!mozilla::net::ChannelEventQueue::FlushQueue() [ChannelEventQueue.cpp:e596664275d5e3e2fdcb7fa8d1447289f99269c3 : 90 + 0x13]
10:16:58 INFO - rbp = 0x00007ffee9dc3fc0 rsp = 0x00007ffee9dc3f60
10:16:58 INFO - rip = 0x000000010897f193
10:16:58 INFO - Found by: previous frame's frame pointer
10:16:58 INFO - 11 XUL!mozilla::net::ChannelEventQueue::ResumeInternal()::CompleteResumeRunnable::Run() [ChannelEventQueue.cpp:e596664275d5e3e2fdcb7fa8d1447289f99269c3 : 148 + 0xe]
10:16:58 INFO - rbp = 0x00007ffee9dc3fd0 rsp = 0x00007ffee9dc3fd0
10:16:58 INFO - rip = 0x000000010898bd22
10:16:58 INFO - Found by: previous frame's frame pointer
10:16:58 INFO - 12 XUL!nsThread::ProcessNextEvent(bool, bool*) [nsThread.cpp:e596664275d5e3e2fdcb7fa8d1447289f99269c3 : 1225 + 0x6]
10:16:58 INFO - rbp = 0x00007ffee9dc44f0 rsp = 0x00007ffee9dc3fe0
10:16:58 INFO - rip = 0x0000000108333238
10:16:58 INFO - Found by: previous frame's frame pointer
10:16:58 INFO - 13 XUL!NS_ProcessNextEvent(nsIThread*, bool) [nsThreadUtils.cpp:e596664275d5e3e2fdcb7fa8d1447289f99269c3 : 486 + 0xd]
10:16:58 INFO - rbp = 0x00007ffee9dc4520 rsp = 0x00007ffee9dc4500
10:16:58 INFO - rip = 0x00000001083371f8
10:16:58 INFO - Found by: previous frame's frame pointer
10:16:58 INFO - 14 XUL!mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate*) [MessagePump.cpp:e596664275d5e3e2fdcb7fa8d1447289f99269c3 : 110 + 0xa]
10:16:58 INFO - rbp = 0x00007ffee9dc4580 rsp = 0x00007ffee9dc4530
10:16:58 INFO - rip = 0x0000000108a9628f
10:16:58 INFO - Found by: previous frame's frame pointer
10:16:58 INFO - 15 XUL!MessageLoop::Run() [message_loop.cc:e596664275d5e3e2fdcb7fa8d1447289f99269c3 : 290 + 0x5]
10:16:58 INFO - rbp = 0x00007ffee9dc45b0 rsp = 0x00007ffee9dc4590
10:16:58 INFO - rip = 0x0000000108a2fd95
10:16:58 INFO - Found by: previous frame's frame pointer
10:16:58 INFO - 16 XUL!nsBaseAppShell::Run() [nsBaseAppShell.cpp:e596664275d5e3e2fdcb7fa8d1447289f99269c3 : 137 + 0xd]
10:16:58 INFO - rbp = 0x00007ffee9dc45d0 rsp = 0x00007ffee9dc45c0
10:16:58 INFO - rip = 0x000000010bf38ee9
10:16:58 INFO - Found by: previous frame's frame pointer
10:16:58 INFO - 17 XUL!nsAppShell::Run() [nsAppShell.mm:e596664275d5e3e2fdcb7fa8d1447289f99269c3 : 705 + 0x8]
10:16:58 INFO - rbp = 0x00007ffee9dc4620 rsp = 0x00007ffee9dc45e0
10:16:58 INFO - rip = 0x000000010bfaece5
10:16:58 INFO - Found by: previous frame's frame pointer
10:16:58 INFO - 18 XUL!XRE_RunAppShell() [nsEmbedFunctions.cpp:e596664275d5e3e2fdcb7fa8d1447289f99269c3 : 919 + 0x13]
10:16:58 INFO - rbp = 0x00007ffee9dc4670 rsp = 0x00007ffee9dc4630
10:16:58 INFO - rip = 0x000000010d7b7375
10:16:58 INFO - Found by: previous frame's frame pointer
10:16:58 INFO - 19 XUL!mozilla::ipc::MessagePumpForChildProcess::Run(base::MessagePump::Delegate*) [MessagePump.cpp:e596664275d5e3e2fdcb7fa8d1447289f99269c3 : 238 + 0x5]
10:16:58 INFO - rbp = 0x00007ffee9dc46a0 rsp = 0x00007ffee9dc4680
10:16:58 INFO - rip = 0x0000000108a96c51
10:16:58 INFO - Found by: previous frame's frame pointer
10:16:58 INFO - 20 XUL!MessageLoop::Run() [message_loop.cc:e596664275d5e3e2fdcb7fa8d1447289f99269c3 : 290 + 0x5]
10:16:58 INFO - rbp = 0x00007ffee9dc46d0 rsp = 0x00007ffee9dc46b0
10:16:58 INFO - rip = 0x0000000108a2fd95
10:16:58 INFO - Found by: previous frame's frame pointer
10:16:58 INFO - 21 XUL!XRE_InitChildProcess(int, char**, XREChildData const*) [nsEmbedFunctions.cpp:e596664275d5e3e2fdcb7fa8d1447289f99269c3 : 754 + 0x8]
10:16:58 INFO - rbp = 0x00007ffee9dc4a00 rsp = 0x00007ffee9dc46e0
10:16:58 INFO - rip = 0x000000010d7b6dc8
10:16:58 INFO - Found by: previous frame's frame pointer
10:16:58 INFO - 22 plugin-container!main [MozillaRuntimeMain.cpp:e596664275d5e3e2fdcb7fa8d1447289f99269c3 : 23 + 0x2c]
10:16:58 INFO - rbp = 0x00007ffee9dc4a40 rsp = 0x00007ffee9dc4a10
10:16:58 INFO - rip = 0x0000000105e3af07
10:16:58 INFO - Found by: previous frame's frame pointer
10:16:58 INFO - 23 0x7fff773cb085
10:16:58 INFO - rbp = 0x00007ffee9dc4a58 rsp = 0x00007ffee9dc4a50
10:16:58 INFO - rip = 0x00007fff773cb085
10:16:58 INFO - Found by: previous frame's frame pointer

10:16:58 INFO - TEST-UNEXPECTED-CRASH | /fetch/api/basic/text-utf8.html | expected OK
10:16:58 INFO - TEST-INFO took 8940ms
10:17:14 INFO - PID 2060 | #01: <name omitted> [xpcom/io/nsStreamUtils.cpp:47]
10:17:14 INFO -
10:17:14 INFO - PID 2060 | #02: mozilla::Runnable::Release() [xpcom/threads/nsThreadUtils.cpp:54]
10:17:14 INFO -
10:17:14 INFO - PID 2060 | #03: nsPipeEvents::~nsPipeEvents() [xpcom/io/nsPipe3.cpp:1133]
10:17:14 INFO -
10:17:14 INFO - PID 2060 | #04: nsPipe::OnPipeException(nsresult, bool) [xpcom/io/nsPipe3.cpp:964]
10:17:14 INFO -
10:17:14 INFO - PID 2060 | #05: nsPipeOutputStream::CloseWithStatus(nsresult) [xpcom/io/nsPipe3.cpp:1623]
10:17:14 INFO -
10:17:14 INFO - PID 2060 | #06: mozilla::dom::FetchDriver::OnStopRequest(nsIRequest*, nsresult) [dom/fetch/FetchDriver.cpp:1233]
10:17:14 INFO -
10:17:14 INFO - PID 2060 | #07: nsCORSListenerProxy::OnStopRequest(nsIRequest*, nsresult) [netwerk/protocol/http/nsCORSListenerProxy.cpp:648]
10:17:14 INFO -
10:17:14 INFO - PID 2060 | #08: mozilla::net::HttpChannelChild::DoOnStopRequest(nsIRequest*, nsresult, nsISupports*) [netwerk/protocol/http/HttpChannelChild.cpp:1260]
10:17:14 INFO -
10:17:14 INFO - PID 2060 | #09: mozilla::net::HttpChannelChild::OnStopRequest(nsresult const&, mozilla::net::ResourceTimingStruct const&, mozilla::net::nsHttpHeaderArray const&) [netwerk/protocol/http/HttpChannelChild.cpp:1138]
10:17:14 INFO -
10:17:14 INFO - PID 2060 | #10: mozilla::net::ChannelEventQueue::FlushQueue() [netwerk/ipc/ChannelEventQueue.cpp:90]
10:17:14 INFO -
10:17:14 INFO - PID 2060 | #11: mozilla::net::ChannelEventQueue::ResumeInternal()::CompleteResumeRunnable::Run() [netwerk/ipc/ChannelEventQueue.cpp:149]
10:17:14 INFO -
10:17:14 INFO - PID 2060 | #12: nsThread::ProcessNextEvent(bool, bool*) [xpcom/threads/nsThread.cpp:1214]
10:17:14 INFO -
10:17:14 INFO - PID 2060 | #13: NS_ProcessNextEvent(nsIThread*, bool) [xpcom/threads/nsThreadUtils.cpp:486]
10:17:14 INFO -
10:17:14 INFO - PID 2060 | #14: mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate*) [ipc/glue/MessagePump.cpp:86]
10:17:14 INFO -
10:17:14 INFO - PID 2060 | #15: MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:291]
10:17:14 INFO -
10:17:14 INFO - PID 2060 | #16: nsBaseAppShell::Run() [widget/nsBaseAppShell.cpp:139]
10:17:14 INFO -
10:17:14 INFO - PID 2060 | #17: nsAppShell::Run() [widget/cocoa/nsAppShell.mm:705]
10:17:14 INFO -
10:17:14 INFO - PID 2060 | #18: XRE_RunAppShell() [toolkit/xre/nsEmbedFunctions.cpp:919]
10:17:14 INFO -
10:17:14 INFO - PID 2060 | #19: mozilla::ipc::MessagePumpForChildProcess::Run(base::MessagePump::Delegate*) [ipc/glue/MessagePump.cpp:238]
10:17:14 INFO -
10:17:14 INFO - PID 2060 | #20: MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:291]
10:17:14 INFO -
10:17:14 INFO - PID 2060 | #21: XRE_InitChildProcess(int, char**, XREChildData const*) [toolkit/xre/nsEmbedFunctions.cpp:758]
10:17:14 INFO -
10:17:14 INFO - PID 2060 | #22: main [ipc/app/MozillaRuntimeMain.cpp:23]
10:17:14 INFO -
10:17:14 INFO - PID 2060 | ++DOCSHELL 0x127a0a000 == 1 [pid = 2114] [id = {f49573ba-72cb-fd4e-b2f1-ff2b567f9bd1}]
10:17:14 INFO - PID 2060 | ++DOMWINDOW == 1 (0x127a45020) [pid = 2114] [serial = 1] [outer = 0x0]
10:17:14 INFO - PID 2060 | [Child 2114, Main Thread] WARNING: No active window: file /builds/worker/workspace/build/src/js/xpconnect/src/XPCJSContext.cpp, line 662
10:17:14 INFO - PID 2060 | [Parent 2060, Main Thread] WARNING: Constructing RangeBoundary with invalid value: 'mRef || aOffset == 0', file /builds/worker/workspace/build/src/obj-firefox/dist/include/mozilla/RangeBoundary.h, line 79
10:17:14 INFO - PID 2060 | [Parent 2060, Main Thread] WARNING: Constructing RangeBoundary with invalid value: 'mRef || aOffset == 0', file /builds/worker/workspace/build/src/obj-firefox/dist/include/mozilla/RangeBoundary.h, line 79
10:17:14 INFO - PID 2060 | ++DOMWINDOW == 2 (0x127a2f400) [pid = 2114] [serial = 2] [outer = 0x127a45020]
10:17:14 INFO - PID 2060 | [2116, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
10:17:14 INFO - PID 2060 | [2116, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
10:17:14 INFO - PID 2060 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/v0/86229zr15h38cvllvxcswy0r000017/T/tmpPvx7X_.mozrunner/runtests_leaks_1469_tab_pid2116.log
10:17:14 INFO - PID 2060 | [2116, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
10:17:14 INFO - PID 2060 | [2116, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
10:17:14 INFO - PID 2060 | ++DOMWINDOW == 3 (0x127ab4400) [pid = 2114] [serial = 3] [outer = 0x127a45020]
10:17:14 INFO - PID 2060 | [Child 2114, Main Thread] WARNING: NS_ENSURE_SUCCESS(mStatus, *this) failed with result 0x80004005: file /builds/worker/workspace/build/src/obj-firefox/dist/include/nsIURIMutator.h, line 489
10:17:14 INFO - PID 2060 | A content process crashed and MOZ_CRASHREPORTER_SHUTDOWN is set, shutting down
10:17:14 INFO - PID 2060 | ++DOMWINDOW == 4 (0x127dd8c00) [pid = 2114] [serial = 4] [outer = 0x127a45020]
10:17:14 INFO - PID 2060 | [Parent 2060, Main Thread] WARNING: Constructing RangeBoundary with invalid value: 'mRef || aOffset == 0', file /builds/worker/workspace/build/src/obj-firefox/dist/include/mozilla/RangeBoundary.h, line 79
10:17:14 INFO - PID 2060 | [Parent 2060, Main Thread] WARNING: Constructing RangeBoundary with invalid value: 'mRef || aOffset == 0', file /builds/worker/workspace/build/src/obj-firefox/dist/include/mozilla/RangeBoundary.h, line 79
10:17:14 INFO - PID 2060 | [Child 2062, Main Thread] WARNING: '!window', file /builds/worker/workspace/build/src/dom/cache/CacheStorage.cpp, line 575
10:17:14 INFO - PID 2060 | [Child 2062, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/workspace/build/src/widget/cocoa/nsAppShell.mm, line 725
10:17:14 INFO - PID 2060 | [Parent 2060, Main Thread] WARNING: unable to post continuation event: file /builds/worker/workspace/build/src/xpcom/io/nsStreamUtils.cpp, line 445
10:17:14 INFO - PID 2060 | [Child 2063, Main Thread] WARNING: '!window', file /builds/worker/workspace/build/src/dom/cache/CacheStorage.cpp, line 575
10:17:14 INFO - PID 2060 | [Child 2063, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/workspace/build/src/widget/cocoa/nsAppShell.mm, line 725
10:17:14 INFO - PID 2060 | [Child 2114, Main Thread] WARNING: '!window', file /builds/worker/workspace/build/src/dom/cache/CacheStorage.cpp, line 575
10:17:14 INFO - PID 2060 | [Child 2114, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/workspace/build/src/widget/cocoa/nsAppShell.mm, line 725
10:17:14 INFO - PID 2060 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
10:17:14 INFO - PID 2060 | --DOCSHELL 0x119d0d000 == 3 [pid = 2062] [id = {c20675f5-efb8-7b43-8874-b1b6eabbf052}] [url = moz-extension://5b40e272-f14c-9746-ac7e-b4bab6e4ec55/_generated_background_page.html]
10:17:14 INFO - PID 2060 | --DOCSHELL 0x119d08800 == 2 [pid = 2062] [id = {4936ea4d-19ef-9e4a-b9ec-fe8a7456dd2b}] [url = moz-extension://b1d1b1c6-595d-c044-a688-bbbcf183db7e/_generated_background_page.html]
10:17:14 INFO - PID 2060 | --DOCSHELL 0x119d0e800 == 1 [pid = 2062] [id = {6dbebdcb-beb6-d545-8b0d-99f2db84fc0e}] [url = moz-extension://ba94f75a-74c1-814b-a6d6-f3fcfdd1e7af/_generated_background_page.html]
10:17:14 INFO - PID 2060 | --DOCSHELL 0x119d10800 == 0 [pid = 2062] [id = {10ae2726-02fa-8349-9ebb-8d63f684d467}] [url = moz-extension://fbfe75ba-cc00-c546-a48b-4872640d96a5/_generated_background_page.html]
10:17:14 INFO - PID 2060 | --DOCSHELL 0x127a0a000 == 0 [pid = 2114] [id = {f49573ba-72cb-fd4e-b2f1-ff2b567f9bd1}] [url = http://web-platform.test:8000/fetch/api/basic/text-utf8.html]
10:17:14 INFO - PID 2060 | --DOCSHELL 0x10aedf000 == 0 [pid = 2063] [id = {ad4f0026-0eed-d14d-a994-21935849e963}] [url = http://web-platform.test:8000/testharness_runner.html]
10:17:14 INFO - PID 2060 | --DOMWINDOW == 7 (0x119d425c0) [pid = 2062] [serial = 3] [outer = 0x0] [url = moz-extension://ba94f75a-74c1-814b-a6d6-f3fcfdd1e7af/_generated_background_page.html]
10:17:14 INFO - PID 2060 | --DOMWINDOW == 6 (0x119d427a0) [pid = 2062] [serial = 4] [outer = 0x0] [url = moz-extension://fbfe75ba-cc00-c546-a48b-4872640d96a5/_generated_background_page.html]
10:17:14 INFO - PID 2060 | --DOMWINDOW == 5 (0x119d42020) [pid = 2062] [serial = 1] [outer = 0x0] [url = moz-extension://b1d1b1c6-595d-c044-a688-bbbcf183db7e/_generated_background_page.html]
10:17:14 INFO - PID 2060 | --DOMWINDOW == 4 (0x119d423e0) [pid = 2062] [serial = 2] [outer = 0x0] [url = moz-extension://5b40e272-f14c-9746-ac7e-b4bab6e4ec55/_generated_background_page.html]
10:17:14 INFO - PID 2060 | --DOMWINDOW == 3 (0x119f64000) [pid = 2062] [serial = 14] [outer = 0x0] [url = moz-extension://fbfe75ba-cc00-c546-a48b-4872640d96a5/_generated_background_page.html]
10:17:14 INFO - PID 2060 | --DOMWINDOW == 2 (0x119f62c00) [pid = 2062] [serial = 13] [outer = 0x0] [url = moz-extension://ba94f75a-74c1-814b-a6d6-f3fcfdd1e7af/generated_background_page.html]
10:17:14 INFO - PID 2060 | --DOMWINDOW == 1 (0x119f61400) [pid = 2062] [serial = 12] [outer = 0x0] [url = moz-extension://5b40e272-f14c-9746-ac7e-b4bab6e4ec55/generated_background_page.html]
10:17:14 INFO - PID 2060 | --DOMWINDOW == 0 (0x119d2d000) [pid = 2062] [serial = 11] [outer = 0x0] [url = moz-extension://b1d1b1c6-595d-c044-a688-bbbcf183db7e/generated_background_page.html]
10:17:14 INFO - PID 2060 | [Child 2062, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
10:17:14 INFO - PID 2060 | [Child 2062, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
10:17:14 INFO - PID 2060 | nsStringStats
10:17:14 INFO - PID 2060 | => mAllocCount: 15263
10:17:14 INFO - PID 2060 | => mReallocCount: 0
10:17:14 INFO - PID 2060 | => mFreeCount: 15263
10:17:14 INFO - PID 2060 | => mShareCount: 10621
10:17:14 INFO - PID 2060 | => mAdoptCount: 476
10:17:14 INFO - PID 2060 | => mAdoptFreeCount: 510
10:17:14 INFO - PID 2060 | => Process ID: 2062, Thread ID: 4592018880
10:17:14 INFO - PID 2060 | --DOMWINDOW == 3 (0x127ab4400) [pid = 2114] [serial = 3] [outer = 0x0] [url = about:blank]
10:17:14 INFO - PID 2060 | --DOMWINDOW == 2 (0x127a45020) [pid = 2114] [serial = 1] [outer = 0x0] [url = http://web-platform.test:8000/fetch/api/basic/text-utf8.html]
10:17:14 INFO - PID 2060 | --DOMWINDOW == 1 (0x127a2f400) [pid = 2114] [serial = 2] [outer = 0x0] [url = about:blank]
10:17:14 INFO - PID 2060 | --DOMWINDOW == 0 (0x127dd8c00) [pid = 2114] [serial = 4] [outer = 0x0] [url = http://web-platform.test:8000/fetch/api/basic/text-utf8.html]
10:17:14 INFO - PID 2060 | [Child 2114, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
10:17:14 INFO - PID 2060 | [Child 2114, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
10:17:14 INFO - PID 2060 | nsStringStats
10:17:14 INFO - PID 2060 | => mAllocCount: 8132
10:17:14 INFO - PID 2060 | => mReallocCount: 0
10:17:14 INFO - PID 2060 | => mFreeCount: 8132
10:17:14 INFO - PID 2060 | => mShareCount: 7406
10:17:14 INFO - PID 2060 | => mAdoptCount: 506
10:17:14 INFO - PID 2060 | => mAdoptFreeCount: 512
10:17:14 INFO - PID 2060 | => Process ID: 2114, Thread ID: 4771075520
10:17:14 INFO - PID 2060 | --DOMWINDOW == 4 (0x108964000) [pid = 2063] [serial = 7] [outer = 0x0] [url = https://web-platform.test:8443/testharness_runner.html]
10:17:14 INFO - PID 2060 | --DOMWINDOW == 3 (0x10895fc00) [pid = 2063] [serial = 6] [outer = 0x0] [url = http://web-platform.test:8000/testharness_runner.html]
10:17:14 INFO - PID 2060 | --DOMWINDOW == 2 (0x10895b000) [pid = 2063] [serial = 5] [outer = 0x0] [url = https://web-platform.test:8443/testharness_runner.html]
10:17:14 INFO - PID 2060 | --DOMWINDOW == 1 (0x10af38020) [pid = 2063] [serial = 1] [outer = 0x0] [url = http://web-platform.test:8000/testharness_runner.html]
10:17:14 INFO - PID 2060 | --DOMWINDOW == 0 (0x10afb0800) [pid = 2063] [serial = 8] [outer = 0x0] [url = http://web-platform.test:8000/testharness_runner.html]
10:17:14 INFO - PID 2060 | [Child 2063, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
10:17:14 INFO - PID 2060 | [Child 2063, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
10:17:14 INFO - PID 2060 | nsStringStats
10:17:14 INFO - PID 2060 | => mAllocCount: 13119
10:17:14 INFO - PID 2060 | => mReallocCount: 0
10:17:14 INFO - PID 2060 | => mFreeCount: 13119
10:17:14 INFO - PID 2060 | => mShareCount: 25018
10:17:14 INFO - PID 2060 | => mAdoptCount: 888
10:17:14 INFO - PID 2060 | => mAdoptFreeCount: 912
10:17:14 INFO - PID 2060 | => Process ID: 2063, Thread ID: 4483872192
10:17:14 INFO - PID 2060 | [Child 2116, Main Thread] WARNING: '!window', file /builds/worker/workspace/build/src/dom/cache/CacheStorage.cpp, line 575
10:17:14 INFO - PID 2060 | [Child 2116, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/workspace/build/src/widget/cocoa/nsAppShell.mm, line 725
10:17:14 INFO - PID 2060 | [Child 2065, Main Thread] WARNING: '!window', file /builds/worker/workspace/build/src/dom/cache/CacheStorage.cpp, line 575
10:17:14 INFO - PID 2060 | [Child 2065, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/workspace/build/src/widget/cocoa/nsAppShell.mm, line 725
10:17:14 INFO - PID 2060 | nsStringStats
10:17:14 INFO - PID 2060 | => mAllocCount: 5359
10:17:14 INFO - PID 2060 | => mReallocCount: 0
10:17:14 INFO - PID 2060 | => mFreeCount: 5359
10:17:14 INFO - PID 2060 | => mShareCount: 3256
10:17:14 INFO - PID 2060 | => mAdoptCount: 68
10:17:14 INFO - PID 2060 | => mAdoptFreeCount: 70
10:17:14 INFO - PID 2060 | => Process ID: 2116, Thread ID: 4493141440
10:17:14 INFO - PID 2060 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
10:17:14 INFO - PID 2060 | --DOMWINDOW == 1 (0x10b0c4200) [pid = 2065] [serial = 1] [outer = 0x0] [url = about:newtab]
10:17:14 INFO - PID 2060 | --DOCSHELL 0x122d09000 == 0 [pid = 2065] [id = {e5febd51-55e0-e74c-9f5c-89b262e2f0c2}] [url = about:newtab]
10:17:14 INFO - PID 2060 | --DOMWINDOW == 0 (0x122dbec00) [pid = 2065] [serial = 3] [outer = 0x0] [url = about:newtab]
10:17:14 INFO - PID 2060 | [Child 2065, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
10:17:14 INFO - PID 2060 | [Child 2065, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
10:17:14 INFO - PID 2060 | nsStringStats
10:17:14 INFO - PID 2060 | => mAllocCount: 11802
10:17:14 INFO - PID 2060 | => mReallocCount: 0
10:17:14 INFO - PID 2060 | => mFreeCount: 11802
10:17:14 INFO - PID 2060 | => mShareCount: 15265
10:17:14 INFO - PID 2060 | => mAdoptCount: 512
10:17:14 INFO - PID 2060 | => mAdoptFreeCount: 520
10:17:14 INFO - PID 2060 | => Process ID: 2065, Thread ID: 4484363712
10:17:14 INFO - PID 2060 | 1562667411395 Marionette INFO Stopped listening on port 50228
10:17:14 INFO - PID 2060 | [Parent 2060, Main Thread] WARNING: NS_ENSURE_TRUE(mDB) failed: file /builds/worker/workspace/build/src/netwerk/cache/nsDiskCacheDeviceSQL.cpp, line 1285
10:17:14 INFO - PID 2060 | [Parent 2060, Main Thread] WARNING: '!window', file /builds/worker/workspace/build/src/dom/cache/CacheStorage.cpp, line 575
10:17:14 INFO - PID 2060 | [Parent 2060, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/workspace/build/src/widget/cocoa/nsAppShell.mm, line 725
10:17:14 INFO - PID 2060 | [Parent 2060, IPDL Background] WARNING: 'aRv.Failed()', file /builds/worker/workspace/build/src/dom/cache/CacheOpParent.cpp, line 154
10:17:14 INFO - PID 2060 | [Parent 2060, Main Thread] WARNING: 'aRv.Failed()', file /builds/worker/workspace/build/src/dom/cache/CacheOpChild.cpp, line 97
10:17:14 INFO - PID 2060 | --DOCSHELL 0x11ad9d800 == 4 [pid = 2060] [id = {6236011e-6651-9049-bcf6-29d3e7063cb9}] [url = chrome://browser/content/hiddenWindowMac.xhtml]
10:17:14 INFO - PID 2060 | --DOCSHELL 0x123fed800 == 3 [pid = 2060] [id = {8b24705f-3f16-dc4a-8ab5-3dbab0101bb7}] [url = about:blank]
10:17:14 INFO - PID 2060 | --DOCSHELL 0x124a06000 == 2 [pid = 2060] [id = {cfedfedf-9291-3e48-a7c5-bbaa427c8c3f}] [url = about:blank]
10:17:14 INFO - PID 2060 | --DOCSHELL 0x1257c2800 == 1 [pid = 2060] [id = {81fb0b50-4b2f-8c46-9fbf-2686b2306088}] [url = chrome://extensions/content/dummy.xul]
10:17:14 INFO - PID 2060 | --DOCSHELL 0x123716000 == 0 [pid = 2060] [id = {39154b43-3784-3549-a4dc-26188521a278}] [url = chrome://browser/content/browser.xhtml]
10:17:14 INFO - PID 2060 | --DOMWINDOW == 9 (0x107e252e0) [pid = 2060] [serial = 3] [outer = 0x0] [url = chrome://browser/content/browser.xhtml]
10:17:14 INFO - PID 2060 | --DOMWINDOW == 8 (0x123769400) [pid = 2060] [serial = 4] [outer = 0x0] [url = about:blank]
10:17:14 INFO - PID 2060 | --DOMWINDOW == 7 (0x12581e400) [pid = 2060] [serial = 9] [outer = 0x0] [url = chrome://extensions/content/dummy.xul]
10:17:14 INFO - PID 2060 | --DOMWINDOW == 6 (0x107e25a60) [pid = 2060] [serial = 7] [outer = 0x0] [url = chrome://extensions/content/dummy.xul]
10:17:14 INFO - PID 2060 | --DOMWINDOW == 5 (0x107e254c0) [pid = 2060] [serial = 5] [outer = 0x0] [url = about:blank]
10:17:14 INFO - PID 2060 | --DOMWINDOW == 4 (0x126ad6000) [pid = 2060] [serial = 10] [outer = 0x0] [url = about:blank]
10:17:14 INFO - PID 2060 | --DOMWINDOW == 3 (0x107e256a0) [pid = 2060] [serial = 6] [outer = 0x0] [url = about:blank]
10:17:14 INFO - PID 2060 | --DOMWINDOW == 2 (0x126ad7800) [pid = 2060] [serial = 11] [outer = 0x0] [url = about:blank]
10:17:14 INFO - PID 2060 | --DOMWINDOW == 1 (0x107e24200) [pid = 2060] [serial = 1] [outer = 0x0] [url = chrome://browser/content/hiddenWindowMac.xhtml]
10:17:14 INFO - PID 2060 | --DOMWINDOW == 0 (0x11bb8c800) [pid = 2060] [serial = 2] [outer = 0x0] [url = about:blank]
10:17:14 INFO - PID 2060 | [Parent 2060, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
10:17:14 INFO - PID 2060 | [Parent 2060, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
10:17:14 INFO - PID 2060 | nsStringStats
10:17:14 INFO - PID 2060 | => mAllocCount: 321202
10:17:14 INFO - PID 2060 | => mReallocCount: 0
10:17:14 INFO - PID 2060 | => mFreeCount: 321201 -- LEAKED 1 !!!
10:17:14 INFO - PID 2060 | => mShareCount: 352432
10:17:14 INFO - PID 2060 | => mAdoptCount: 5159
10:17:14 INFO - PID 2060 | => mAdoptFreeCount: 6001
10:17:14 INFO - PID 2060 | => Process ID: 2060, Thread ID: 4438943168
10:17:14 INFO - Browser exited with return code 0
10:17:14 INFO - PROCESS LEAKS /var/folders/v0/86229zr15h38cvllvxcswy0r000017/T/tmpPvx7X.mozrunner/runtests_leaks_1469.log
10:17:14 INFO - leakcheck | Processing log file /var/folders/v0/86229zr15h38cvllvxcswy0r000017/T/tmpPvx7X.mozrunner/runtests_leaks_1469.log for scope /fetch/api/basic
10:17:14 INFO - TEST-INFO | leakcheck | default process: leak threshold set at 51200 bytes
10:17:14 INFO - TEST-INFO | leakcheck | plugin process: leak threshold set at 0 bytes
10:17:14 INFO - TEST-INFO | leakcheck | tab process: leak threshold set at 10000 bytes
10:17:14 INFO - TEST-INFO | leakcheck | gmplugin process: leak threshold set at 20000 bytes
10:17:14 INFO - TEST-INFO | leakcheck | gpu process: leak threshold set at 0 bytes
10:17:14 INFO - TEST-INFO | leakcheck | rdd process: leak threshold set at 400 bytes
10:17:14 INFO - TEST-INFO | leakcheck | vr process: leak threshold set at 0 bytes
10:17:14 INFO - TEST-INFO | leakcheck | socket process: leak threshold set at 0 bytes
10:17:14 INFO - leakcheck | Processing leak log file /var/folders/v0/86229zr15h38cvllvxcswy0r000017/T/tmpPvx7X.mozrunner/runtests_leaks_1469.log

Edwin Takahashi (:egao | infrequent contributor)

Comment 1

•

5 years ago

:jgraham - I see that you worked on this back in 2016. This seems to crash on macosx1014-64/debug only on web-platform-test chunk 8, intermittently. The try push is https://treeherder.mozilla.org/#/jobs?repo=try&group_state=expanded&revision=a8b4. I am proposing to have this test disabled for the time being for macosx1014-64/debug only while an investigation takes place.

Flags: needinfo?(james)

Keywords: regression

Summary: Intermittent TEST-UNEXPECTED-CRASH | /fetch/api/basic/text-utf8.html | expected OK → OSX Mojave (10.14) - Intermittent TEST-UNEXPECTED-CRASH | /fetch/api/basic/text-utf8.html | expected OK

Edwin Takahashi (:egao | infrequent contributor)

Updated

•

5 years ago

Comment 2

•

5 years ago

Yeah, an intermittent crash is currently a good reason to disable a test.

Flags: needinfo?(james)

Kershaw Chang [:kershaw]

Assignee

Updated

•

5 years ago

Priority: P5 → P2

Whiteboard: [necko-triaged]

Kohei Yoshino [:kohei]

Updated

•

5 years ago

Type: -- → defect

Comment hidden (Intermittent Failures Robot)

Boris Zbarsky [:bzbarsky]

Comment 4

•

5 years ago

So I poked around a bit and we're hitting the assert at https://searchfox.org/mozilla-central/rev/6566d92dd46417a2f57e75c515135ebe84c9cef5/xpcom/io/nsStreamUtils.cpp#67

This used to be a non-fatal assert, then was changed to a fatal one.

It's not 100% clear to me whether there should be an assert here at all. That is, is the situation here actually an expected one, or is someone messing something up?

I've not succeeded so far at reproducing this on Linux, unfortunately, but I'll see if I can get more info on Mac out of try.

Flags: needinfo?(nfroyd)

Boris Zbarsky [:bzbarsky]

Comment 5

•

5 years ago

OK, so what happens here is that we're under HttpChannelChild::OnStopRequest which calls nsCORSListenerProxy::OnStopReques which calls FetchDriver::OnStopRequest. The load has presumably failed, because we then call nsPipeOutputStream::CloseWithStatus which lands in nsPipe::OnPipeException and then in nsPipeEvents::~nsPipeEvents.

That ends up in nsInputStreamReadyEvent::~nsInputStreamReadyEvent which tries to post a runnable to do the release on the target event target (presumably to try to run the dtor there; it's refcounting the object on the thread we're on right now anyway), and that runnable-posting fails.

It looks like the test right before this one is fetch/api/basic/stream-response.any.worker.html which sort of aims to stop the test partway through a fetch stream. I wonder whether it's that test that is actually hitting this problem, if the HTTP channel is stopped before delivering all the data and the cancel races just right with the worker thread shutdown...

Sylvestre Ledru [:Sylvestre]

Updated

•

3 years ago

Flags: needinfo?(froydnj+bz)

Valentin Gosu [:valentin] (he/him)

Comment 6

•

2 years ago

It's not clear if this is still failing.
https://searchfox.org/mozilla-central/rev/71befbc9bc348d33f0c7307dc48e3cb36ba78650/testing/web-platform/meta/fetch/api/basic/stream-response.any.js.ini#26 we should probably remove the crash expectations and see if it still happens.

Kershaw Chang [:kershaw]

Assignee

Updated

•

2 years ago

Flags: needinfo?(kershaw)

Kershaw Chang [:kershaw]

Assignee

Comment 7

•

2 years ago

Attached file Bug 1564529 - Remove crash expectations, r=#necko — Details

Phabricator Automation

Updated

•

2 years ago

Assignee: nobody → kershaw

Status: NEW → ASSIGNED

Kershaw Chang [:kershaw]

Assignee

Comment 8

•

2 years ago

(In reply to Valentin Gosu [:valentin] (he/him) [PTO until Jan 17th] from comment #6)

It's not clear if this is still failing.
https://searchfox.org/mozilla-central/rev/71befbc9bc348d33f0c7307dc48e3cb36ba78650/testing/web-platform/meta/fetch/api/basic/stream-response.any.js.ini#26 we should probably remove the crash expectations and see if it still happens.

https://treeherder.mozilla.org/jobs?repo=try&revision=52cca775dd57dbd66749e1f39c0adef49adbe128

Flags: needinfo?(kershaw)

Kershaw Chang [:kershaw]

Assignee

Updated

•

2 years ago

Severity: normal → S4

Pulsebot

Comment 9

•

2 years ago

Pushed by kjang@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/a428d96af61d
Remove crash expectations, r=necko-reviewers,valentin

Norisz Fay [:noriszfay]

Comment 10

•

2 years ago

bugherder

https://hg.mozilla.org/mozilla-central/rev/a428d96af61d

Status: ASSIGNED → RESOLVED

Closed: 2 years ago

status-firefox98: --- → fixed

Resolution: --- → FIXED

Target Milestone: --- → 98 Branch