1634264 - Intermittent toolkit/components/places/tests/browser/browser_redirect.js or browser_redirect_self.js | application crashed [@ mozilla::MozPromise<unsigned long, nsresult, true>::AssertIsDead()]

Reporter

Description

•

5 years ago

treeherder

Filed by: abutkovits [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=300147074&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Sow1QAp4Rz2pW_OkMbQ9FA/runs/1/artifacts/public/logs/live_backing.log

INFO - TEST-START | toolkit/components/p [task 2020-04-30T05:04:24.642Z] 05:04:24 [task 2020-04-30T05:04:24.642Z] 05:04:24 [task 2020-04-30T05:04:24.643Z] 05:04:24 [task 2020-04-30T05:04:24.828Z] 05:04:24 [task 2020-04-30T05:04:24.828Z] 05:04:24 [task 2020-04-30T05:04:24.828Z] 05:04:24 [task 2020-04-30T05:04:24.828Z] 05:04:24 [task 2020-04-30T05:04:24.850Z] 05:04:24 [task 2020-04-30T05:04:24.850Z] 05:04:24 [task 2020-04-30T05:04:24.850Z] 05:04:24 [task 2020-04-30T05:04:24.850Z] 05:04:24 [task 2020-04-30T05:04:24.850Z] 05:04:24 [task 2020-04-30T05:04:24.850Z] 05:04:24 [task 2020-04-30T05:04:24.850Z] 05:04:24 [task 2020-04-30T05:04:24.850Z] 05:04:24 [task 2020-04-30T05:04:24.858Z] 05:04:24 [task 2020-04-30T05:04:24.880Z] 05:04:24 [task 2020-04-30T05:04:24.880Z] 05:04:24 [task 2020-04-30T05:04:24.880Z] 05:04:24 [task 2020-04-30T05:04:24.881Z] 05:04:24 [task 2020-04-30T05:04:24.881Z] 05:04:24 [task 2020-04-30T05:04:24.882Z] 05:04:24 [task 2020-04-30T05:04:24.882Z] 05:04:24 laces/tests/browser/browser_redirect.js
INFO - GECKO(7816) | ExceptionHandler::GenerateDump cloned child 9074
INFO - GECKO(7816) | ExceptionHandler::SendContinueSignalToChild sent continue signal to child
INFO - GECKO(7816) | ExceptionHandler::WaitForContinueSignal waiting for continue signal...
INFO - GECKO(7816) | Exiting due to channel error.
INFO - GECKO(7816) | Exiting due to channel error.
INFO - GECKO(7816) | Exiting due to channel error.
INFO - GECKO(7816) | Exiting due to channel error.
INFO - GECKO(7816) | Exiting due to channel error.
INFO - GECKO(7816) | Exiting due to channel error.
INFO - GECKO(7816) | Exiting due to channel error.
INFO - GECKO(7816) | Exiting due to channel error.
INFO - GECKO(7816) | [GFX1-]: Receive IPC close with reason=AbnormalShutdown
INFO - GECKO(7816) | Exiting due to channel error.
INFO - GECKO(7816) | [GFX1-]: Receive IPC close with reason=AbnormalShutdown
INFO - GECKO(7816) | Exiting due to channel error.
INFO - GECKO(7816) | Exiting due to channel error.
INFO - TEST-INFO | Main app process: exit 11
INFO - Buffered messages logged at 05:04:24
INFO - Entering test bound redirect_check_new_typed_visit
INFO - Buffered messages finished
ERROR - TEST-UNEXPECTED-FAIL | toolkit/components/places/tests/browser/browser_redirect.js | application terminated with exit code 11
INFO - runtests.py | Application ran for: 0:00:51.504089
INFO - zombiecheck | Reading PID log: /tmp/tmp78LACGpidlog

[task 2020-04-30T05:04:33.064Z] 05:04:33 INFO - PROCESS-CRASH | toolkit/components/places/tests/browser/browser_redirect.js | application crashed [@ mozilla::MozPromise<unsigned long, nsresult, true>::AssertIsDead()]
[task 2020-04-30T05:04:33.064Z] 05:04:33 INFO - Mozilla crash reason: MOZ_DIAGNOSTIC_ASSERT(Request::mDisconnected)
[task 2020-04-30T05:04:33.064Z] 05:04:33 INFO - Crash dump filename: /tmp/tmpmQEsSy.mozrunner/minidumps/13f6c453-de5f-5b2c-73c4-175e4c533d71.dmp
[task 2020-04-30T05:04:33.065Z] 05:04:33 INFO - Operating system: Linux
[task 2020-04-30T05:04:33.065Z] 05:04:33 INFO - 0.0.0 Linux 4.4.0-1014-aws #14taskcluster1-Ubuntu SMP Tue Apr 3 10:27:00 UTC 2018 x86_64
[task 2020-04-30T05:04:33.065Z] 05:04:33 INFO - CPU: amd64
[task 2020-04-30T05:04:33.065Z] 05:04:33 INFO - family 6 model 85 stepping 7
[task 2020-04-30T05:04:33.066Z] 05:04:33 INFO - 2 CPUs
[task 2020-04-30T05:04:33.066Z] 05:04:33 INFO -
[task 2020-04-30T05:04:33.067Z] 05:04:33 INFO - GPU: UNKNOWN
[task 2020-04-30T05:04:33.067Z] 05:04:33 INFO -
[task 2020-04-30T05:04:33.068Z] 05:04:33 INFO - Crash reason: SIGSEGV /SEGV_MAPERR
[task 2020-04-30T05:04:33.068Z] 05:04:33 INFO - Crash address: 0x0
[task 2020-04-30T05:04:33.069Z] 05:04:33 INFO - Process uptime: not available
[task 2020-04-30T05:04:33.069Z] 05:04:33 INFO -
[task 2020-04-30T05:04:33.070Z] 05:04:33 INFO - Thread 0 (crashed)
[task 2020-04-30T05:04:33.070Z] 05:04:33 INFO - 0 libxul.so!mozilla::MozPromise<unsigned long, nsresult, true>::AssertIsDead() [MozPromise.h:2345eeb8e9ff02438c8a69b3fd64c010d9cab63e : 442 + 0x0]
[task 2020-04-30T05:04:33.071Z] 05:04:33 INFO - rax = 0x00007fc32657eab9 rdx = 0x0000000000000001
[task 2020-04-30T05:04:33.071Z] 05:04:33 INFO - rcx = 0x00005576f87d6df0 rbx = 0x00007fc30de11100
[task 2020-04-30T05:04:33.072Z] 05:04:33 INFO - rsi = 0x0000000000000003 rdi = 0x00007fc30de11100
[task 2020-04-30T05:04:33.072Z] 05:04:33 INFO - rbp = 0x00007ffd1ff3c820 rsp = 0x00007ffd1ff3c7f0
[task 2020-04-30T05:04:33.073Z] 05:04:33 INFO - r8 = 0x00007fc30aea9178 r9 = 0x00007fc30a100b48
[task 2020-04-30T05:04:33.073Z] 05:04:33 INFO - r10 = 0x0000000000000000 r11 = 0x00007fc309e011d8
[task 2020-04-30T05:04:33.074Z] 05:04:33 INFO - r12 = 0x0000000000000000 r13 = 0x0000000000000000
[task 2020-04-30T05:04:33.074Z] 05:04:33 INFO - r14 = 0x00007fc30aea9178 r15 = 0x00007fc30aea9160
[task 2020-04-30T05:04:33.075Z] 05:04:33 INFO - rip = 0x00007fc321b4c3ff
[task 2020-04-30T05:04:33.075Z] 05:04:33 INFO - Found by: given as instruction pointer in context
[task 2020-04-30T05:04:33.076Z] 05:04:33 INFO - 1 libxul.so!mozilla::MozPromise<unsigned long, nsresult, true>::~MozPromise() [MozPromise.h:2345eeb8e9ff02438c8a69b3fd64c010d9cab63e : 1022 + 0x9]
[task 2020-04-30T05:04:33.076Z] 05:04:33 INFO - rbx = 0x00007fc30aea9160 rbp = 0x00007ffd1ff3c860
[task 2020-04-30T05:04:33.077Z] 05:04:33 INFO - rsp = 0x00007ffd1ff3c830 r12 = 0x00007fc3288626c0
[task 2020-04-30T05:04:33.077Z] 05:04:33 INFO - r13 = 0x00007ffd1ff3ca28 r14 = 0x00007fc30aea9160
[task 2020-04-30T05:04:33.078Z] 05:04:33 INFO - r15 = 0x00007fc3320a5ac8 rip = 0x00007fc321b4c475
[task 2020-04-30T05:04:33.078Z] 05:04:33 INFO - Found by: call frame info
[task 2020-04-30T05:04:33.078Z] 05:04:33 INFO - 2 libxul.so!mozilla::MozPromise<unsigned long, nsresult, true>::Private::~Private() [MozPromise.h:2345eeb8e9ff02438c8a69b3fd64c010d9cab63e : 253 + 0xe]
[task 2020-04-30T05:04:33.079Z] 05:04:33 INFO - rbx = 0x00007fc30aea9160 rbp = 0x00007ffd1ff3c880
[task 2020-04-30T05:04:33.083Z] 05:04:33 INFO - rsp = 0x00007ffd1ff3c870 r12 = 0x00007fc3288626c0
[task 2020-04-30T05:04:33.083Z] 05:04:33 INFO - r13 = 0x00007ffd1ff3ca28 r14 = 0x00007fc30ddebfb0
[task 2020-04-30T05:04:33.083Z] 05:04:33 INFO - r15 = 0x00007fc3320a5ac8 rip = 0x00007fc321b4c28e
[task 2020-04-30T05:04:33.083Z] 05:04:33 INFO - Found by: call frame info
[task 2020-04-30T05:04:33.084Z] 05:04:33 INFO - 3 libxul.so!std::_Function_base::_Base_manager<mozilla::MozPromise<unsigned long, nsresult, true>::FromDomPromise(mozilla::dom::Promise*)::{lambda(JSContext*, JS::Handle<JS::Value>)#1}>::_M_manager(std::_Any_data&, std::_Function_base::_Base_manager<mozilla::MozPromise<unsigned long, nsresult, true>::FromDomPromise(mozilla::dom::Promise*)::{lambda(JSContext*, JS::Handle<JS::Value>)#1}> const&, std::_Manager_operation) [std_function.h : 231 + 0x1e]
[task 2020-04-30T05:04:33.084Z] 05:04:33 INFO - rbx = 0x00007fc30ddb8f90 rbp = 0x00007ffd1ff3c8a0
[task 2020-04-30T05:04:33.084Z] 05:04:33 INFO - rsp = 0x00007ffd1ff3c890 r12 = 0x00007fc3288626c0
[task 2020-04-30T05:04:33.085Z] 05:04:33 INFO - r13 = 0x00007ffd1ff3ca28 r14 = 0x00007fc30ddebfb0
[task 2020-04-30T05:04:33.085Z] 05:04:33 INFO - r15 = 0x00007fc3320a5ac8 rip = 0x00007fc321b4c71f
[task 2020-04-30T05:04:33.085Z] 05:04:33 INFO - Found by: call frame info
[task 2020-04-30T05:04:33.085Z] 05:04:33 INFO - 4 libxul.so!mozilla::dom::DomPromiseListener::~DomPromiseListener() [PromiseNativeHandler.h:2345eeb8e9ff02438c8a69b3fd64c010d9cab63e : 54 + 0x4d]
[task 2020-04-30T05:04:33.086Z] 05:04:33 INFO - rbx = 0x00007fc30ddebfa0 rbp = 0x00007ffd1ff3c8c0
[task 2020-04-30T05:04:33.086Z] 05:04:33 INFO - rsp = 0x00007ffd1ff3c8b0 r12 = 0x00007fc3288626c0
[task 2020-04-30T05:04:33.086Z] 05:04:33 INFO - r13 = 0x00007ffd1ff3ca28 r14 = 0x00007fc30a7d0801
[task 2020-04-30T05:04:33.086Z] 05:04:33 INFO - r15 = 0x00007fc3320a5ac8 rip = 0x00007fc323aec21d
[task 2020-04-30T05:04:33.086Z] 05:04:33 INFO - Found by: call frame info
[task 2020-04-30T05:04:33.087Z] 05:04:33 INFO - 5 libxul.so!mozilla::dom::DomPromiseListener::Release() [PromiseNativeHandler.cpp:2345eeb8e9ff02438c8a69b3fd64c010d9cab63e : 14 + 0x22]
[task 2020-04-30T05:04:33.087Z] 05:04:33 INFO - rbx = 0x00007fc30ae977c0 rbp = 0x00007ffd1ff3c8d0
[task 2020-04-30T05:04:33.087Z] 05:04:33 INFO - rsp = 0x00007ffd1ff3c8d0 r12 = 0x00007fc3288626c0
[task 2020-04-30T05:04:33.087Z] 05:04:33 INFO - r13 = 0x00007ffd1ff3ca28 r14 = 0x00007fc30a7d0801
[task 2020-04-30T05:04:33.087Z] 05:04:33 INFO - r15 = 0x00007fc3320a5ac8 rip = 0x00007fc323aebe22
[task 2020-04-30T05:04:33.087Z] 05:04:33 INFO - Found by: call frame info
[task 2020-04-30T05:04:33.087Z] 05:04:33 INFO - 6 libxul.so!mozilla::dom::(anonymous namespace)::PromiseNativeHandlerShim::~PromiseNativeHandlerShim() [Promise.cpp:2345eeb8e9ff02438c8a69b3fd64c010d9cab63e : 374 + 0x22]
[task 2020-04-30T05:04:33.087Z] 05:04:33 INFO - rbx = 0x00007fc30ae977c0 rbp = 0x00007ffd1ff3c8f0
[task 2020-04-30T05:04:33.087Z] 05:04:33 INFO - rsp = 0x00007ffd1ff3c8e0 r12 = 0x00007fc3288626c0
[task 2020-04-30T05:04:33.088Z] 05:04:33 INFO - r13 = 0x00007ffd1ff3ca28 r14 = 0x00007fc30a7d0801
[task 2020-04-30T05:04:33.088Z] 05:04:33 INFO - r15 = 0x00007fc3320a5ac8 rip = 0x00007fc323aec4c2
[task 2020-04-30T05:04:33.088Z] 05:04:33 INFO - Found by: call frame info
[task 2020-04-30T05:04:33.088Z] 05:04:33 INFO - 7 libxul.so!SnowWhiteKiller::Visit(nsPurpleBuffer&, nsPurpleBufferEntry*) [nsCycleCollector.cpp:2345eeb8e9ff02438c8a69b3fd64c010d9cab63e : 2457 + 0xc8]
[task 2020-04-30T05:04:33.089Z] 05:04:33 INFO - rbx = 0x00007fc30ae977c0 rbp = 0x00007ffd1ff3c950
[task 2020-04-30T05:04:33.089Z] 05:04:33 INFO - rsp = 0x00007ffd1ff3c900 r12 = 0x00007fc3288626c0
[task 2020-04-30T05:04:33.089Z] 05:04:33 INFO - r13 = 0x00007ffd1ff3ca28 r14 = 0x00007fc30a7d0801
[task 2020-04-30T05:04:33.089Z] 05:04:33 INFO - r15 = 0x00007fc3320a5ac8 rip = 0x00007fc3216401a8
[task 2020-04-30T05:04:33.089Z] 05:04:33 INFO - Found by: call frame info
[task 2020-04-30T05:04:33.089Z] 05:04:33 INFO - 8 libxul.so!void nsPurpleBuffer::VisitEntries<SnowWhiteKiller>(SnowWhiteKiller&) [nsCycleCollector.cpp:2345eeb8e9ff02438c8a69b3fd64c010d9cab63e : 957 + 0x8]
[task 2020-04-30T05:04:33.089Z] 05:04:33 INFO - rbx = 0x0000000000001645 rbp = 0x00007ffd1ff3ca10
[task 2020-04-30T05:04:33.089Z] 05:04:33 INFO - rsp = 0x00007ffd1ff3c960 r12 = 0x00007fc30a7c9000
[task 2020-04-30T05:04:33.089Z] 05:04:33 INFO - r13 = 0x0000000000000f0f r14 = 0x00007fc30a7d0890
[task 2020-04-30T05:04:33.090Z] 05:04:33 INFO - r15 = 0x0000000000000505 rip = 0x00007fc3216364c4
[task 2020-04-30T05:04:33.090Z] 05:04:33 INFO - Found by: call frame info
[task 2020-04-30T05:04:33.090Z] 05:04:33 INFO - 9 libxul.so!nsCycleCollector_doDeferredDeletionWithBudget(js::SliceBudget&) [nsCycleCollector.cpp:2345eeb8e9ff02438c8a69b3fd64c010d9cab63e : 3889 + 0x55]
[task 2020-04-30T05:04:33.091Z] 05:04:33 INFO - rbx = 0x00007fc3320a5a00 rbp = 0x00007ffd1ff3ca80
[task 2020-04-30T05:04:33.091Z] 05:04:33 INFO - rsp = 0x00007ffd1ff3ca20 r12 = 0x00007fc304f0a940
[task 2020-04-30T05:04:33.091Z] 05:04:33 INFO - r13 = 0x0000000000000000 r14 = 0x00007ffd1ff3ca90
[task 2020-04-30T05:04:33.091Z] 05:04:33 INFO - r15 = 0x00007ffd1ff3ca28 rip = 0x00007fc321639c45
[task 2020-04-30T05:04:33.092Z] 05:04:33 INFO - Found by: call frame info
[task 2020-04-30T05:04:33.092Z] 05:04:33 INFO - 10 libxul.so!AsyncFreeSnowWhite::Run() [XPCJSRuntime.cpp:2345eeb8e9ff02438c8a69b3fd64c010d9cab63e : 148 + 0x8]
[task 2020-04-30T05:04:33.092Z] 05:04:33 INFO - rbx = 0x00000843a45d5c8e rbp = 0x00007ffd1ff3cae0
[task 2020-04-30T05:04:33.092Z] 05:04:33 INFO - rsp = 0x00007ffd1ff3ca90 r12 = 0x00007fc304f0a940
[task 2020-04-30T05:04:33.093Z] 05:04:33 INFO - r13 = 0x0000000000000000 r14 = 0x00007fc31917fca0
[task 2020-04-30T05:04:33.093Z] 05:04:33 INFO - r15 = 0x00007ffd1ff3ca90 rip = 0x00007fc321fb9a1e
[task 2020-04-30T05:04:33.093Z] 05:04:33 INFO - Found by: call frame info
[task 2020-04-30T05:04:33.094Z] 05:04:33 INFO - 11 libxul.so!<name omitted> [nsThreadUtils.cpp:2345eeb8e9ff02438c8a69b3fd64c010d9cab63e : 326 + 0x9]
[task 2020-04-30T05:04:33.094Z] 05:04:33 INFO - rbx = 0x00007fc31917fca0 rbp = 0x00007ffd1ff3cb00
[task 2020-04-30T05:04:33.094Z] 05:04:33 INFO - rsp = 0x00007ffd1ff3caf0 r12 = 0x00007fc304f0a940
[task 2020-04-30T05:04:33.095Z] 05:04:33 INFO - r13 = 0x0000000000000000 r14 = 0x00007fc304f0a940
[task 2020-04-30T05:04:33.095Z] 05:04:33 INFO - r15 = 0x00007fc3320a5700 rip = 0x00007fc3216c2337
[task 2020-04-30T05:04:33.095Z] 05:04:33 INFO - Found by: call frame info
[task 2020-04-30T05:04:33.095Z] 05:04:33 INFO - 12 libxul.so!nsThread::ProcessNextEvent(bool, bool*) [nsThread.cpp:2345eeb8e9ff02438c8a69b3fd64c010d9cab63e : 1200 + 0xa]
[task 2020-04-30T05:04:33.096Z] 05:04:33 INFO - rbx = 0x00007fc3320a57c0 rbp = 0x00007ffd1ff3d010
[task 2020-04-30T05:04:33.096Z] 05:04:33 INFO - rsp = 0x00007ffd1ff3cb10 r12 = 0x00007fc304f0a940
[task 2020-04-30T05:04:33.096Z] 05:04:33 INFO - r13 = 0x0000000000000000 r14 = 0x0000000000000001
[task 2020-04-30T05:04:33.096Z] 05:04:33 INFO - r15 = 0x00007fc3320a5700 rip = 0x00007fc3216b8c0d
[task 2020-04-30T05:04:33.097Z] 05:04:33 INFO - Found by: call frame info
[task 2020-04-30T05:04:33.097Z] 05:04:33 INFO - 13 libxul.so!NS_ProcessNextEvent(nsIThread*, bool) [nsThreadUtils.cpp:2345eeb8e9ff02438c8a69b3fd64c010d9cab63e : 481 + 0xd]
[task 2020-04-30T05:04:33.097Z] 05:04:33 INFO - rbx = 0x0000000000000000 rbp = 0x00007ffd1ff3d040
[task 2020-04-30T05:04:33.097Z] 05:04:33 INFO - rsp = 0x00007ffd1ff3d020 r12 = 0x00007fc31fc90ea0
[task 2020-04-30T05:04:33.097Z] 05:04:33 INFO - r13 = 0x00007fc31fc90e80 r14 = 0x00007fc3320cf930
[task 2020-04-30T05:04:33.098Z] 05:04:33 INFO - r15 = 0x0000000000000000 rip = 0x00007fc3216bb6d8
[task 2020-04-30T05:04:33.098Z] 05:04:33 INFO - Found by: call frame info
[task 2020-04-30T05:04:33.098Z] 05:04:33 INFO - 14 libxul.so!mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate*) [MessagePump.cpp:2345eeb8e9ff02438c8a69b3fd64c010d9cab63e : 87 + 0x7]
[task 2020-04-30T05:04:33.099Z] 05:04:33 INFO - rbx = 0x0000000000000000 rbp = 0x00007ffd1ff3d080
[task 2020-04-30T05:04:33.099Z] 05:04:33 INFO - rsp = 0x00007ffd1ff3d050 r12 = 0x00007fc31fc90ea0
[task 2020-04-30T05:04:33.099Z] 05:04:33 INFO - r13 = 0x00007fc31fc90e80 r14 = 0x00007fc3320cf930
[task 2020-04-30T05:04:33.099Z] 05:04:33 INFO - r15 = 0x0000000000000000 rip = 0x00007fc321c15d7b
[task 2020-04-30T05:04:33.100Z] 05:04:33 INFO - Found by: call frame info
[task 2020-04-30T05:04:33.100Z] 05:04:33 INFO - 15 libxul.so!MessageLoop::Run() [message_loop.cc:2345eeb8e9ff02438c8a69b3fd64c010d9cab63e : 290 + 0xc]
[task 2020-04-30T05:04:33.100Z] 05:04:33 INFO - rbx = 0x00007fc31e9551c0 rbp = 0x00007ffd1ff3d0b0
[task 2020-04-30T05:04:33.100Z] 05:04:33 INFO - rsp = 0x00007ffd1ff3d090 r12 = 0x0003001100000000
[task 2020-04-30T05:04:33.101Z] 05:04:33 INFO - r13 = 0x00007fc3337163c0 r14 = 0x00007fc3320a5700
[task 2020-04-30T05:04:33.101Z] 05:04:33 INFO - r15 = 0x00007ffd1ff3d148 rip = 0x00007fc321bd3cd6
[task 2020-04-30T05:04:33.101Z] 05:04:33 INFO - Found by: call frame info
[task 2020-04-30T05:04:33.102Z] 05:04:33 INFO - 16 libxul.so!nsBaseAppShell::Run() [nsBaseAppShell.cpp:2345eeb8e9ff02438c8a69b3fd64c010d9cab63e : 137 + 0xd]
[task 2020-04-30T05:04:33.102Z] 05:04:33 INFO - rbx = 0x00007fc31e9551c0 rbp = 0x00007ffd1ff3d0d0
[task 2020-04-30T05:04:33.102Z] 05:04:33 INFO - rsp = 0x00007ffd1ff3d0c0 r12 = 0x0003001100000000
[task 2020-04-30T05:04:33.103Z] 05:04:33 INFO - r13 = 0x00007fc3337163c0 r14 = 0x00007fc3320a5700
[task 2020-04-30T05:04:33.103Z] 05:04:33 INFO - r15 = 0x00007ffd1ff3d148 rip = 0x00007fc323d02049
[task 2020-04-30T05:04:33.103Z] 05:04:33 INFO - Found by: call frame info
[task 2020-04-30T05:04:33.104Z] 05:04:33 INFO - 17 libxul.so!nsAppStartup::Run() [nsAppStartup.cpp:2345eeb8e9ff02438c8a69b3fd64c010d9cab63e : 271 + 0xa]
[task 2020-04-30T05:04:33.104Z] 05:04:33 INFO - rbx = 0x00007fc31fc97740 rbp = 0x00007ffd1ff3d0f0
[task 2020-04-30T05:04:33.104Z] 05:04:33 INFO - rsp = 0x00007ffd1ff3d0e0 r12 = 0x0003001100000000
[task 2020-04-30T05:04:33.104Z] 05:04:33 INFO - r13 = 0x00007fc3337163c0 r14 = 0x00007ffd1ff3d350
[task 2020-04-30T05:04:33.105Z] 05:04:33 INFO - r15 = 0x00007ffd1ff3d148 rip = 0x00007fc324e18df8
[task 2020-04-30T05:04:33.105Z] 05:04:33 INFO - Found by: call frame info
[task 2020-04-30T05:04:33.105Z] 05:04:33 INFO - 18 libxul.so!XREMain::XRE_mainRun() [nsAppRunner.cpp:2345eeb8e9ff02438c8a69b3fd64c010d9cab63e : 4608 + 0xd]
[task 2020-04-30T05:04:33.105Z] 05:04:33 INFO - rbx = 0x00007ffd1ff3d1a0 rbp = 0x00007ffd1ff3d2c0
[task 2020-04-30T05:04:33.105Z] 05:04:33 INFO - rsp = 0x00007ffd1ff3d100 r12 = 0x0003001100000000
[task 2020-04-30T05:04:33.105Z] 05:04:33 INFO - r13 = 0x00007fc3337163c0 r14 = 0x00007ffd1ff3d350
[task 2020-04-30T05:04:33.106Z] 05:04:33 INFO - r15 = 0x00007ffd1ff3d148 rip = 0x00007fc324ee11d2
[task 2020-04-30T05:04:33.106Z] 05:04:33 INFO - Found by: call frame info
[task 2020-04-30T05:04:33.106Z] 05:04:33 INFO - 19 libxul.so!XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) [nsAppRunner.cpp:2345eeb8e9ff02438c8a69b3fd64c010d9cab63e : 4743 + 0x8]
[task 2020-04-30T05:04:33.107Z] 05:04:33 INFO - rbx = 0x0000000000000000 rbp = 0x00007ffd1ff3d340
[task 2020-04-30T05:04:33.107Z] 05:04:33 INFO - rsp = 0x00007ffd1ff3d2d0 r12 = 0x00007ffd1ff3d350
[task 2020-04-30T05:04:33.107Z] 05:04:33 INFO - r13 = 0x00007ffd1ff3f65a r14 = 0x00007ffd1ff3d380
[task 2020-04-30T05:04:33.107Z] 05:04:33 INFO - r15 = 0x00007fc31fca3448 rip = 0x00007fc324ee1916
[task 2020-04-30T05:04:33.108Z] 05:04:33 INFO - Found by: call frame info
[task 2020-04-30T05:04:33.108Z] 05:04:33 INFO - 20 libxul.so!XRE_main(int, char**, mozilla::BootstrapConfig const&) [nsAppRunner.cpp:2345eeb8e9ff02438c8a69b3fd64c010d9cab63e : 4797 + 0x10]
[task 2020-04-30T05:04:33.108Z] 05:04:33 INFO - rbx = 0x0000000000000005 rbp = 0x00007ffd1ff3d490
[task 2020-04-30T05:04:33.108Z] 05:04:33 INFO - rsp = 0x00007ffd1ff3d350 r12 = 0x00007ffd1ff3d350
[task 2020-04-30T05:04:33.108Z] 05:04:33 INFO - r13 = 0x00007ffd1ff3f65a r14 = 0x00007ffd1ff3d4e0
[task 2020-04-30T05:04:33.108Z] 05:04:33 INFO - r15 = 0x00007ffd1ff3e5f8 rip = 0x00007fc324ee1c3f
[task 2020-04-30T05:04:33.108Z] 05:04:33 INFO - Found by: call frame info
[task 2020-04-30T05:04:33.108Z] 05:04:33 INFO - 21 firefox-bin!main [nsBrowserApp.cpp:2345eeb8e9ff02438c8a69b3fd64c010d9cab63e : 331 + 0x13a]
[task 2020-04-30T05:04:33.109Z] 05:04:33 INFO - rbx = 0x00007ffd1ff3e5f8 rbp = 0x00007ffd1ff3e510
[task 2020-04-30T05:04:33.109Z] 05:04:33 INFO - rsp = 0x00007ffd1ff3d4a0 r12 = 0x00005576f87bc0e1
[task 2020-04-30T05:04:33.109Z] 05:04:33 INFO - r13 = 0x00007ffd1ff3f65a r14 = 0x000000000000006d
[task 2020-04-30T05:04:33.110Z] 05:04:33 INFO - r15 = 0x0000000000000005 rip = 0x00005576f8726161
[task 2020-04-30T05:04:33.110Z] 05:04:33 INFO - Found by: call frame info
[task 2020-04-30T05:04:33.110Z] 05:04:33 INFO - 22 libc.so.6 + 0x21b97
[task 2020-04-30T05:04:33.110Z] 05:04:33 INFO - rbx = 0x0000000000000000 rbp = 0x00005576f87bb620
[task 2020-04-30T05:04:33.110Z] 05:04:33 INFO - rsp = 0x00007ffd1ff3e520 r12 = 0x00005576f8725afc
[task 2020-04-30T05:04:33.110Z] 05:04:33 INFO - r13 = 0x00007ffd1ff3e5f0 r14 = 0x0000000000000000
[task 2020-04-30T05:04:33.111Z] 05:04:33 INFO - r15 = 0x0000000000000000 rip = 0x00007fc33229cb97
[task 2020-04-30T05:04:33.111Z] 05:04:33 INFO - Found by: call frame info
[task 2020-04-30T05:04:33.111Z] 05:04:33 INFO - 23 libstdc++.so.6 + 0x379278
[task 2020-04-30T05:04:33.111Z] 05:04:33 INFO - rsp = 0x00007ffd1ff3e528 rip = 0x00007fc332f9b278
[task 2020-04-30T05:04:33.111Z] 05:04:33 INFO - Found by: stack scanning
[task 2020-04-30T05:04:33.112Z] 05:04:33 INFO - 24 firefox-bin + 0xfcc0
[task 2020-04-30T05:04:33.112Z] 05:04:33 INFO - rsp = 0x00007ffd1ff3e540 rip = 0x00005576f8725cc0
[task 2020-04-30T05:04:33.112Z] 05:04:33 INFO - Found by: stack scanning
[task 2020-04-30T05:04:33.112Z] 05:04:33 INFO - 25 firefox-bin + 0xfafc
[task 2020-04-30T05:04:33.112Z] 05:04:33 INFO - rsp = 0x00007ffd1ff3e558 rip = 0x00005576f8725afc
[task 2020-04-30T05:04:33.112Z] 05:04:33 INFO - Found by: stack scanning
[task 2020-04-30T05:04:33.113Z] 05:04:33 INFO - 26 ld-linux-x86-64.so.2 + 0x10733
[task 2020-04-30T05:04:33.113Z] 05:04:33 INFO - rsp = 0x00007ffd1ff3e5a0 rip = 0x00007fc3335e6733
[task 2020-04-30T05:04:33.113Z] 05:04:33 INFO - Found by: stack scanning
[task 2020-04-30T05:04:33.113Z] 05:04:33 INFO - 27 libdl.so.2 + 0x202d80
[task 2020-04-30T05:04:33.113Z] 05:04:33 INFO - rsp = 0x00007ffd1ff3e5a8 rip = 0x00007fc3333b5d80
[task 2020-04-30T05:04:33.113Z] 05:04:33 INFO - Found by: stack scanning
[task 2020-04-30T05:04:33.113Z] 05:04:33 INFO - 28 libpthread.so.0 + 0x219bb0
[task 2020-04-30T05:04:33.113Z] 05:04:33 INFO - rsp = 0x00007ffd1ff3e5b0 rip = 0x00007fc3335d0bb0
[task 2020-04-30T05:04:33.113Z] 05:04:33 INFO - Found by: stack scanning
[task 2020-04-30T05:04:33.114Z] 05:04:33 INFO - 29 firefox-bin + 0xfafc
[task 2020-04-30T05:04:33.114Z] 05:04:33 INFO - rsp = 0x00007ffd1ff3e5d0 rip = 0x00005576f8725afc
[task 2020-04-30T05:04:33.114Z] 05:04:33 INFO - Found by: stack scanning
[task 2020-04-30T05:04:33.115Z] 05:04:33 INFO - 30 firefox-bin!_start + 0x29
[task 2020-04-30T05:04:33.115Z] 05:04:33 INFO - rsp = 0x00007ffd1ff3e5e0 rip = 0x00005576f8725b25
[task 2020-04-30T05:04:33.115Z] 05:04:33 INFO - Found by: stack scanning

Comment hidden (Intermittent Failures Robot)

Firefox Bug Husbandry Bot

Comment 3

•

5 years ago

Because this bug's Severity has not been changed from the default since it was filed, and it's Priority is -- (non,) indicating it has has not been previously triaged, the bug's Severity is being updated to -- (default, untriaged.)

Severity: normal → --

Mark Banner (:standard8)

Comment 4

•

5 years ago

Places doesn't use promises yet. This seems to be happening during garbage collection so moving across.

Component: Places → JavaScript: GC

Product: Toolkit → Core

Jon Coppeard (:jonco) (PTO until 14th September)

Comment 5

•

5 years ago

This is during cycle collection.

Component: JavaScript: GC → XPCOM

Nathan Froyd [:froydnj]

Comment 6

•

5 years ago

mccr8, is this DOM promises or mozpromise? The stack suggests the former?

Flags: needinfo?(continuation)

Andrew McCreight [:mccr8]

Comment 7

•

5 years ago

I haven't looked at this code before, but PromiseNativeHandlerShim is a subclass of PromiseNativeHandler, which has the comment that it "allows C++ to react to a Promise being rejected/resolved", so it sounds like it is some kind of bridge from DOM promises to MozPromise.

This also isn't actually the cycle collector per se. This is code we go through to destroy objects that are cycle collected (because the CC potentially has a weak reference to all CC'd objects), so the issue is in the dtor of some class.

There are only 3 instances of this crash so far, but it does seem suspicious that they are all in one of two redirection related places tests, toolkit/components/places/tests/browser/browser_redirect.js and toolkit/components/places/tests/browser/browser_redirect_self.js. Also, all 3 failures are with Fission enabled. They are in opt, but maybe we assert earlier in debug builds.

The combination of redirection and Fission makes me think of document channel. Matt, any ideas here? Thanks.

Blocks: fission-mochitests

Flags: needinfo?(continuation) → needinfo?(matt.woodrow)

Andrew McCreight [:mccr8]

Updated

•

5 years ago

Summary: Intermittent toolkit/components/places/tests/browser/browser_redirect.js | application crashed [@ mozilla::MozPromise<unsigned long, nsresult, true>::AssertIsDead()] → Intermittent toolkit/components/places/tests/browser/browser_redirect.js or browser_redirect_self.js | application crashed [@ mozilla::MozPromise<unsigned long, nsresult, true>::AssertIsDead()]

Andrew McCreight [:mccr8]

Comment 8

•

5 years ago

FWIW, I don't see any other bugs with browser_redirect_self.js or browser_redirect.js in the name.

Matt Woodrow (:mattwoodrow)

Comment 9

•

5 years ago

Sounds believable that it could be DocumentChannel (which uses the PromiseNativeHandler).

I'm not really sure where to start on this though, without a testcase, STR or a pernosco trace.

CC'ing jya, since he's looked at the promise code recently.

Flags: needinfo?(matt.woodrow)

Andrew McCreight [:mccr8]

Updated

•

5 years ago

See Also: → https://bugzilla.mozilla.org/show_bug.cgi?id=1635953, https://bugzilla.mozilla.org/show_bug.cgi?id=1636545

Jean-Yves Avenard [:jya]

Assignee

Comment 10

•

5 years ago

I think I know what is happening here.

The DocumentLoadListener gets a DOM promise from nsIBrowser::performProcessSwitch [1] and convert this into a MozPromise [2].

MozPromise::FromDomPromise uses a DomPromiseListener to determine when to resolve or reject the MozPromise [3].

If shutting down early, it is possible for the Dom promise to just get CCed and not be resolved nor rejected and the DomPromiseListener simply gets deleted.
At that stage, the DomPromiseListener keeps a reference to the MozPromise ; the MozPromise destructor will assert that it has been resolved.

Several ways we can go about it; don't use a Dom promise with performProcessSwitch, particularly since I believe this has now been moved to native code.
Or modify the DomPromiseListener with an optional construction argument so that the callback will get called with a rejection in the destructor.

[1] https://searchfox.org/mozilla-central/rev/2c619e2a98d75c40a0aba2c19bb768659ac53860/dom/interfaces/base/nsIBrowser.idl#191
[2] https://searchfox.org/mozilla-central/rev/2c619e2a98d75c40a0aba2c19bb768659ac53860/netwerk/ipc/DocumentLoadListener.cpp#1351-1368
[3] https://searchfox.org/mozilla-central/rev/2c619e2a98d75c40a0aba2c19bb768659ac53860/xpcom/threads/MozPromiseInlines.h#28

Happy to take this one on as I'm already struggling in fixing shutdown errors at the moment.

Assignee: nobody → jyavenard

Jean-Yves Avenard [:jya]

Assignee

Comment 11

•

5 years ago

And just then I got a pernosco trace for it :)
https://pernos.co/debug/RCiMdl2iFhX2eL-u4YweAw/index.html

What a lucky coincidence.

Jean-Yves Avenard [:jya]

Assignee

Comment 12

•

5 years ago

Attached file Bug 1634264 - Reject MozPromise when DomPromiseListener is deleted. r?mattwoodrow (deleted) — Details

We remove the assertion that the error code was NS_OK, as users of FromDomPromise assert it must be a failure.

Comment hidden (Intermittent Failures Robot)

Pulsebot

Comment 14

•

5 years ago

Pushed by jyavenard@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/8b5c903523ed Reject MozPromise when DomPromiseListener is deleted. r=mattwoodrow

Narcis Beleuzu [:NarcisB]

Comment 15

•

5 years ago

bugherder

https://hg.mozilla.org/mozilla-central/rev/8b5c903523ed

Status: NEW → RESOLVED

Closed: 5 years ago

status-firefox78: --- → fixed

Resolution: --- → FIXED

Target Milestone: --- → mozilla78

Ryan VanderMeulen [:RyanVM]

Updated

•

4 years ago

status-firefox76: --- → unaffected

status-firefox77: --- → wontfix

status-firefox-esr68: --- → unaffected