Closed Bug 1632619 Opened 5 years ago Closed 1 year ago

Intermittent Assertion failure: Request::mDisconnected, at /builds/worker/workspace/obj-build/dist/include/mozilla/MozPromise.h:442

Categories

(Core :: XPCOM, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure, Whiteboard: [stockwell unknown])

Crash Data

Filed by: opoprus [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=299066766&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/XcLuBYX9Tz26JGmvIrp2Yw/runs/0/artifacts/public/logs/live_backing.log


TEST-START | browser/base/content/test/popups/browser_popup_frames.js

INFO - GECKO(3803) | Assertion failure: Request::mDisconnected, at /builds/worker/workspace/obj-build/dist/include/mozilla/MozPromise.h:442
[task 2020-04-23T18:37:59.988Z] 18:37:59 INFO - Initializing stack-fixing for the first stack frame, this may take a while...
[task 2020-04-23T18:38:09.787Z] 18:38:09 INFO - GECKO(3803) | #01: mozilla::MozPromise<unsigned long, nsresult, true>::AssertIsDead() [hg:hg.mozilla.org/integration/autoland:xpcom/threads/MozPromise.h:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:980]
[task 2020-04-23T18:38:09.789Z] 18:38:09 INFO - GECKO(3803) | #02: mozilla::MozPromise<unsigned long, nsresult, true>::~MozPromise() [hg:hg.mozilla.org/integration/autoland:xpcom/threads/MozPromise.h:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:1025]
[task 2020-04-23T18:38:09.794Z] 18:38:09 INFO - GECKO(3803) | #03: mozilla::MozPromise<unsigned long, nsresult, true>::Private::~Private() [hg:hg.mozilla.org/integration/autoland:xpcom/threads/MozPromise.h:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:253]
[task 2020-04-23T18:38:09.795Z] 18:38:09 INFO - GECKO(3803) | #04: mozilla::MozPromiseRefcountable::Release() [hg:hg.mozilla.org/integration/autoland:xpcom/threads/MozPromise.h:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:148]
[task 2020-04-23T18:38:09.796Z] 18:38:09 INFO - GECKO(3803) | #05: std::_Function_base::_Base_manager<mozilla::MozPromise<unsigned long, nsresult, true>::FromDomPromise(mozilla::dom::Promise*)::{lambda(JSContext*, JS::Handle<JS::Value>)#1}>::_M_destroy(std::_Any_data&, std::integral_constant<bool, false>) [/builds/worker/fetches/clang/include/c++/7.4.0/bits/std_function.h:207]
[task 2020-04-23T18:38:09.796Z] 18:38:09 INFO - GECKO(3803) | #06: 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) [/builds/worker/fetches/clang/include/c++/7.4.0/bits/std_function.h:0]
[task 2020-04-23T18:38:09.797Z] 18:38:09 INFO - GECKO(3803) | #07: mozilla::dom::DomPromiseListener::~DomPromiseListener() [hg:hg.mozilla.org/integration/autoland:dom/promise/PromiseNativeHandler.h:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:54]
[task 2020-04-23T18:38:09.797Z] 18:38:09 INFO - GECKO(3803) | #08: mozilla::dom::DomPromiseListener::Release() [hg:hg.mozilla.org/integration/autoland:dom/promise/PromiseNativeHandler.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:14]
[task 2020-04-23T18:38:09.798Z] 18:38:09 INFO - GECKO(3803) | #09: mozilla::dom::(anonymous namespace)::PromiseNativeHandlerShim::~PromiseNativeHandlerShim() [hg:hg.mozilla.org/integration/autoland:dom/promise/Promise.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:374]
[task 2020-04-23T18:38:09.799Z] 18:38:09 INFO - GECKO(3803) | #10: SnowWhiteKiller::MaybeKillObject(SnowWhiteKiller::SnowWhiteObject&) [hg:hg.mozilla.org/integration/autoland:xpcom/base/nsCycleCollector.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:0]
[task 2020-04-23T18:38:09.800Z] 18:38:09 INFO - GECKO(3803) | #11: SnowWhiteKiller::~SnowWhiteKiller() [hg:hg.mozilla.org/integration/autoland:xpcom/base/nsCycleCollector.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:2417]
[task 2020-04-23T18:38:09.800Z] 18:38:09 INFO - GECKO(3803) | #12: nsCycleCollector::FreeSnowWhite(bool) [hg:hg.mozilla.org/integration/autoland:xpcom/base/nsCycleCollector.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:2603]
[task 2020-04-23T18:38:09.800Z] 18:38:09 INFO - GECKO(3803) | #13: nsCycleCollector::BeginCollection(ccType, nsICycleCollectorListener*) [hg:hg.mozilla.org/integration/autoland:xpcom/base/nsCycleCollector.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:3583]
[task 2020-04-23T18:38:09.801Z] 18:38:09 INFO - GECKO(3803) | #14: nsCycleCollector::Collect(ccType, js::SliceBudget&, nsICycleCollectorListener*, bool) [hg:hg.mozilla.org/integration/autoland:xpcom/base/nsCycleCollector.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:0]
[task 2020-04-23T18:38:09.801Z] 18:38:09 INFO - GECKO(3803) | #15: nsCycleCollector_collect(nsICycleCollectorListener*) [hg:hg.mozilla.org/integration/autoland:xpcom/base/nsCycleCollector.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:3907]
[task 2020-04-23T18:38:09.801Z] 18:38:09 INFO - GECKO(3803) | #16: nsJSContext::CycleCollectNow(nsICycleCollectorListener*) [hg:hg.mozilla.org/integration/autoland:dom/base/nsJSEnvironment.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:1539]
[task 2020-04-23T18:38:09.802Z] 18:38:09 INFO - GECKO(3803) | #17: nsJSEnvironmentObserver::Observe(nsISupports*, char const*, char16_t const*) [hg:hg.mozilla.org/integration/autoland:dom/base/nsJSEnvironment.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:404]
[task 2020-04-23T18:38:09.802Z] 18:38:09 INFO - GECKO(3803) | #18: nsObserverList::NotifyObservers(nsISupports*, char const*, char16_t const*) [hg:hg.mozilla.org/integration/autoland:xpcom/ds/nsObserverList.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:64]
[task 2020-04-23T18:38:09.802Z] 18:38:09 INFO - GECKO(3803) | #19: nsObserverService::NotifyObservers(nsISupports*, char const*, char16_t const*) [hg:hg.mozilla.org/integration/autoland:xpcom/ds/nsObserverService.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:295]
[task 2020-04-23T18:38:09.803Z] 18:38:09 INFO - GECKO(3803) | #20: ??? [/builds/worker/workspace/build/application/firefox/libxul.so + 0x10a03c6]
[task 2020-04-23T18:38:09.803Z] 18:38:09 INFO - GECKO(3803) | #21: CallMethodHelper::Call() [hg:hg.mozilla.org/integration/autoland:js/xpconnect/src/XPCWrappedNative.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:1175]
[task 2020-04-23T18:38:09.804Z] 18:38:09 INFO - GECKO(3803) | #22: XPCWrappedNative::CallMethod(XPCCallContext&, XPCWrappedNative::CallMode) [hg:hg.mozilla.org/integration/autoland:js/xpconnect/src/XPCWrappedNative.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:1141]
[task 2020-04-23T18:38:09.804Z] 18:38:09 INFO - GECKO(3803) | #23: XPC_WN_CallMethod(JSContext*, unsigned int, JS::Value*) [hg:hg.mozilla.org/integration/autoland:js/xpconnect/src/XPCWrappedNativeJSOps.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:947]
[task 2020-04-23T18:38:09.804Z] 18:38:09 INFO - GECKO(3803) | #24: CallJSNative(JSContext*, bool ()(JSContext, unsigned int, JS::Value*), js::CallReason, JS::CallArgs const&) [hg:hg.mozilla.org/integration/autoland:js/src/vm/Interpreter.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:492]
[task 2020-04-23T18:38:09.805Z] 18:38:09 INFO - GECKO(3803) | #25: js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) [hg:hg.mozilla.org/integration/autoland:js/src/vm/Interpreter.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:584]
[task 2020-04-23T18:38:09.805Z] 18:38:09 INFO - GECKO(3803) | #26: Interpret(JSContext*, js::RunState&) [hg:hg.mozilla.org/integration/autoland:js/src/vm/Interpreter.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:0]
[task 2020-04-23T18:38:09.805Z] 18:38:09 INFO - GECKO(3803) | #27: js::RunScript(JSContext*, js::RunState&) [hg:hg.mozilla.org/integration/autoland:js/src/vm/Interpreter.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:464]
[task 2020-04-23T18:38:09.805Z] 18:38:09 INFO - GECKO(3803) | #28: js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) [hg:hg.mozilla.org/integration/autoland:js/src/vm/Interpreter.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:619]
[task 2020-04-23T18:38:09.805Z] 18:38:09 INFO - GECKO(3803) | #29: <name omitted> [hg:hg.mozilla.org/integration/autoland:js/src/vm/Interpreter.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:664]
[task 2020-04-23T18:38:09.805Z] 18:38:09 INFO - GECKO(3803) | #30: js::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, JS::Handle<JS::Value>, JS::MutableHandle<JS::Value>) [hg:hg.mozilla.org/integration/autoland:js/src/vm/Interpreter.h:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:107]
[task 2020-04-23T18:38:09.808Z] 18:38:09 INFO - GECKO(3803) | #31: PromiseReactionJob(JSContext*, unsigned int, JS::Value*) [hg:hg.mozilla.org/integration/autoland:js/src/builtin/Promise.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:1871]
[task 2020-04-23T18:38:09.808Z] 18:38:09 INFO - GECKO(3803) | #32: CallJSNative(JSContext*, bool ()(JSContext, unsigned int, JS::Value*), js::CallReason, JS::CallArgs const&) [hg:hg.mozilla.org/integration/autoland:js/src/vm/Interpreter.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:492]
[task 2020-04-23T18:38:09.809Z] 18:38:09 INFO - GECKO(3803) | #33: js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) [hg:hg.mozilla.org/integration/autoland:js/src/vm/Interpreter.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:584]
[task 2020-04-23T18:38:09.809Z] 18:38:09 INFO - GECKO(3803) | #34: <name omitted> [hg:hg.mozilla.org/integration/autoland:js/src/vm/Interpreter.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:664]
[task 2020-04-23T18:38:09.809Z] 18:38:09 INFO - GECKO(3803) | #35: JS::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, JS::HandleValueArray const&, JS::MutableHandle<JS::Value>) [hg:hg.mozilla.org/integration/autoland:js/src/jsapi.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:2807]
[task 2020-04-23T18:38:09.818Z] 18:38:09 INFO - GECKO(3803) | #36: mozilla::dom::PromiseJobCallback::Call(mozilla::dom::BindingCallContext&, JS::Handle<JS::Value>, mozilla::ErrorResult&) [s3:gecko-generated-sources:fbfdbc97d873d463f9ffcf1f5609d5f3e6a873871fed36c07c628fc0be95758dea1cf5ead3762aaa157d8b13918529228d012695c5e2ea4cc30dde5517a8cfcb/dom/bindings/PromiseBinding.cpp::28]
[task 2020-04-23T18:38:09.818Z] 18:38:09 INFO - GECKO(3803) | #37: mozilla::dom::PromiseJobCallback::Call(mozilla::ErrorResult&, char const*, mozilla::dom::CallbackObject::ExceptionHandling, JS::Realm*) [s3:gecko-generated-sources:09cbe7f9e1409cd4cca288356b597724157d7f93ab5efbaede65be8bf535e6469c7590bf6c7211a89f760ea37ac901f3d1d5fcbeb89c9dfc80643c98c831255f/dist/include/mozilla/dom/PromiseBinding.h::91]
[task 2020-04-23T18:38:09.826Z] 18:38:09 INFO - GECKO(3803) | #38: mozilla::PromiseJobRunnable::Run(mozilla::AutoSlowOperation&) [hg:hg.mozilla.org/integration/autoland:xpcom/base/CycleCollectedJSContext.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:213]
[task 2020-04-23T18:38:09.827Z] 18:38:09 INFO - GECKO(3803) | #39: mozilla::CycleCollectedJSContext::PerformMicroTaskCheckPoint(bool) [hg:hg.mozilla.org/integration/autoland:xpcom/base/CycleCollectedJSContext.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:644]
[task 2020-04-23T18:38:09.827Z] 18:38:09 INFO - GECKO(3803) | #40: mozilla::CycleCollectedJSContext::AfterProcessTask(unsigned int) [hg:hg.mozilla.org/integration/autoland:xpcom/base/CycleCollectedJSContext.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:466]
[task 2020-04-23T18:38:09.828Z] 18:38:09 INFO - GECKO(3803) | #41: XPCJSContext::AfterProcessTask(unsigned int) [hg:hg.mozilla.org/integration/autoland:js/xpconnect/src/XPCJSContext.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:1332]
[task 2020-04-23T18:38:09.828Z] 18:38:09 INFO - GECKO(3803) | #42: nsThread::ProcessNextEvent(bool, bool*) [hg:hg.mozilla.org/integration/autoland:xpcom/threads/nsThread.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:0]
[task 2020-04-23T18:38:09.829Z] 18:38:09 INFO - GECKO(3803) | #43: NS_ProcessNextEvent(nsIThread*, bool) [hg:hg.mozilla.org/integration/autoland:xpcom/threads/nsThreadUtils.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:481]
[task 2020-04-23T18:38:09.829Z] 18:38:09 INFO - GECKO(3803) | #44: mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate*) [hg:hg.mozilla.org/integration/autoland:ipc/glue/MessagePump.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:0]
[task 2020-04-23T18:38:09.830Z] 18:38:09 INFO - GECKO(3803) | #45: MessageLoop::RunInternal() [hg:hg.mozilla.org/integration/autoland:ipc/chromium/src/base/message_loop.cc:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:315]
[task 2020-04-23T18:38:09.830Z] 18:38:09 INFO - GECKO(3803) | #46: MessageLoop::Run() [hg:hg.mozilla.org/integration/autoland:ipc/chromium/src/base/message_loop.cc:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:291]
[task 2020-04-23T18:38:09.830Z] 18:38:09 INFO - GECKO(3803) | #47: nsBaseAppShell::Run() [hg:hg.mozilla.org/integration/autoland:widget/nsBaseAppShell.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:139]
[task 2020-04-23T18:38:09.831Z] 18:38:09 INFO - GECKO(3803) | #48: nsAppStartup::Run() [hg:hg.mozilla.org/integration/autoland:toolkit/components/startup/nsAppStartup.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:272]
[task 2020-04-23T18:38:09.831Z] 18:38:09 INFO - GECKO(3803) | #49: XREMain::XRE_mainRun() [hg:hg.mozilla.org/integration/autoland:toolkit/xre/nsAppRunner.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:4608]
[task 2020-04-23T18:38:09.832Z] 18:38:09 INFO - GECKO(3803) | #50: XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) [hg:hg.mozilla.org/integration/autoland:toolkit/xre/nsAppRunner.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:4743]
[task 2020-04-23T18:38:09.832Z] 18:38:09 INFO - GECKO(3803) | #51: XRE_main(int, char**, mozilla::BootstrapConfig const&) [hg:hg.mozilla.org/integration/autoland:toolkit/xre/nsAppRunner.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6:4797]
[task 2020-04-23T18:38:09.832Z] 18:38:09 INFO - GECKO(3803) | #52: ??? [/builds/worker/workspace/build/application/firefox/firefox + 0xfeeb]
[task 2020-04-23T18:38:09.833Z] 18:38:09 INFO - fix-stacks error: failed to read breakpad symbols dir /builds/worker/workspace/build/symbols/libc.so.6 for /lib/x86_64-linux-gnu/libc.so.6
[task 2020-04-23T18:38:09.833Z] 18:38:09 INFO - fix-stacks note: this is expected and harmless for system libraries on debug automation runs
[task 2020-04-23T18:38:09.833Z] 18:38:09 INFO - GECKO(3803) | #53: __libc_start_main [/lib/x86_64-linux-gnu/libc.so.6 + 0x21b97]
[task 2020-04-23T18:38:09.834Z] 18:38:09 INFO - GECKO(3803) | #54: ??? [/builds/worker/workspace/build/application/firefox/firefox + 0xfa99]
[task 2020-04-23T18:38:09.834Z] 18:38:09 INFO - GECKO(3803) | ExceptionHandler::GenerateDump cloned child 4389
[task 2020-04-23T18:38:09.834Z] 18:38:09 INFO - GECKO(3803) | ExceptionHandler::SendContinueSignalToChild sent continue signal to child
[task 2020-04-23T18:38:09.835Z] 18:38:09 INFO - GECKO(3803) | ExceptionHandler::WaitForContinueSignal waiting for continue signal...
[task 2020-04-23T18:38:09.836Z] 18:38:09 INFO - GECKO(3803) | Exiting due to channel error.
[task 2020-04-23T18:38:09.836Z] 18:38:09 INFO - GECKO(3803) | Exiting due to channel error.
[task 2020-04-23T18:38:09.837Z] 18:38:09 INFO - GECKO(3803) | Exiting due to channel error.
[task 2020-04-23T18:38:09.837Z] 18:38:09 INFO - GECKO(3803) | [GFX1-]: Receive IPC close with reason=AbnormalShutdown
[task 2020-04-23T18:38:09.837Z] 18:38:09 INFO - GECKO(3803) | Exiting due to channel error.
[task 2020-04-23T18:38:09.839Z] 18:38:09 INFO - GECKO(3803) | Exiting due to channel error.
[task 2020-04-23T18:38:09.839Z] 18:38:09 INFO - GECKO(3803) | Exiting due to channel error.
[task 2020-04-23T18:38:09.839Z] 18:38:09 INFO - GECKO(3803) | Exiting due to channel error.
[task 2020-04-23T18:38:09.840Z] 18:38:09 INFO - GECKO(3803) | Exiting due to channel error.
[task 2020-04-23T18:38:09.840Z] 18:38:09 INFO - GECKO(3803) | Exiting due to channel error.
[task 2020-04-23T18:38:09.840Z] 18:38:09 INFO - GECKO(3803) | Exiting due to channel error.
[task 2020-04-23T18:38:09.862Z] 18:38:09 INFO - TEST-INFO | Main app process: exit 11
[task 2020-04-23T18:38:09.862Z] 18:38:09 ERROR - TEST-UNEXPECTED-FAIL | ShutdownLeaks | process() called before end of test suite
[task 2020-04-23T18:38:09.863Z] 18:38:09 INFO - TEST-INFO | Confirming we saw 44 DOCSHELL created and 33 destroyed log strings.
[task 2020-04-23T18:38:09.863Z] 18:38:09 INFO - TEST-INFO | Confirming we saw 114 DOMWINDOW created and 68 destroyed log strings.
[task 2020-04-23T18:38:09.863Z] 18:38:09 INFO - Buffered messages finished
[task 2020-04-23T18:38:09.864Z] 18:38:09 ERROR - TEST-UNEXPECTED-FAIL | Last test finished | application terminated with exit code 11
[task 2020-04-23T18:38:09.864Z] 18:38:09 INFO - runtests.py | Application ran for: 0:00:41.351222
[task 2020-04-23T18:38:09.864Z] 18:38:09 INFO - zombiecheck | Reading PID log: /tmp/tmpZ0tqMHpidlog
[task 2020-04-23T18:38:09.865Z] 18:38:09 INFO - ==> process 3803 launched child process 3820
[task 2020-04-23T18:38:09.865Z] 18:38:09 INFO - ==> process 3803 launched child process 3934
[task 2020-04-23T18:38:09.865Z] 18:38:09 INFO - ==> process 3803 launched child process 3950
[task 2020-04-23T18:38:09.866Z] 18:38:09 INFO - ==> process 3803 launched child process 4019
[task 2020-04-23T18:38:09.866Z] 18:38:09 INFO - ==> process 3803 launched child process 4044
[task 2020-04-23T18:38:09.866Z] 18:38:09 INFO - ==> process 3803 launched child process 4092
[task 2020-04-23T18:38:09.866Z] 18:38:09 INFO - ==> process 3803 launched child process 4121
[task 2020-04-23T18:38:09.867Z] 18:38:09 INFO - ==> process 3803 launched child process 4131
[task 2020-04-23T18:38:09.867Z] 18:38:09 INFO - ==> process 3803 launched child process 4171
[task 2020-04-23T18:38:09.867Z] 18:38:09 INFO - ==> process 3803 launched child process 4182
[task 2020-04-23T18:38:09.867Z] 18:38:09 INFO - ==> process 3803 launched child process 4223
[task 2020-04-23T18:38:09.868Z] 18:38:09 INFO - ==> process 3803 launched child process 4251
[task 2020-04-23T18:38:09.868Z] 18:38:09 INFO - ==> process 3803 launched child process 4282
[task 2020-04-23T18:38:09.869Z] 18:38:09 INFO - ==> process 3803 launched child process 4308
[task 2020-04-23T18:38:09.869Z] 18:38:09 INFO - ==> process 3803 launched child process 4336
[task 2020-04-23T18:38:09.869Z] 18:38:09 INFO - ==> process 3803 launched child process 4360
[task 2020-04-23T18:38:09.869Z] 18:38:09 INFO - zombiecheck | Checking for orphan process with PID: 4131
[task 2020-04-23T18:38:09.870Z] 18:38:09 INFO - zombiecheck | Checking for orphan process with PID: 4360
[task 2020-04-23T18:38:09.870Z] 18:38:09 INFO - zombiecheck | Checking for orphan process with PID: 3820
[task 2020-04-23T18:38:09.870Z] 18:38:09 INFO - zombiecheck | Checking for orphan process with PID: 4171
[task 2020-04-23T18:38:09.870Z] 18:38:09 INFO - zombiecheck | Checking for orphan process with PID: 4044
[task 2020-04-23T18:38:09.870Z] 18:38:09 INFO - zombiecheck | Checking for orphan process with PID: 3950
[task 2020-04-23T18:38:09.870Z] 18:38:09 INFO - zombiecheck | Checking for orphan process with PID: 4336
[task 2020-04-23T18:38:09.870Z] 18:38:09 INFO - zombiecheck | Checking for orphan process with PID: 4019
[task 2020-04-23T18:38:09.871Z] 18:38:09 INFO - zombiecheck | Checking for orphan process with PID: 4308
[task 2020-04-23T18:38:09.872Z] 18:38:09 INFO - zombiecheck | Checking for orphan process with PID: 4182
[task 2020-04-23T18:38:09.872Z] 18:38:09 INFO - zombiecheck | Checking for orphan process with PID: 4121
[task 2020-04-23T18:38:09.873Z] 18:38:09 INFO - zombiecheck | Checking for orphan process with PID: 4282
[task 2020-04-23T18:38:09.873Z] 18:38:09 INFO - zombiecheck | Checking for orphan process with PID: 4251
[task 2020-04-23T18:38:09.874Z] 18:38:09 INFO - zombiecheck | Checking for orphan process with PID: 4092
[task 2020-04-23T18:38:09.875Z] 18:38:09 INFO - zombiecheck | Checking for orphan process with PID: 3934
[task 2020-04-23T18:38:09.876Z] 18:38:09 INFO - zombiecheck | Checking for orphan process with PID: 4223
[task 2020-04-23T18:38:09.876Z] 18:38:09 INFO - mozcrash Copy/paste: /builds/worker/fetches/minidump_stackwalk/minidump_stackwalk /tmp/tmpTP_Cjj.mozrunner/minidumps/6ec15456-b422-7d87-979f-d039b0c07c2f.dmp /builds/worker/workspace/build/symbols
[task 2020-04-23T18:38:15.215Z] 18:38:15 INFO - mozcrash Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/6ec15456-b422-7d87-979f-d039b0c07c2f.dmp
[task 2020-04-23T18:38:15.216Z] 18:38:15 INFO - mozcrash Saved app info as /builds/worker/workspace/build/blobber_upload_dir/6ec15456-b422-7d87-979f-d039b0c07c2f.extra
[task 2020-04-23T18:38:15.379Z] 18:38:15 INFO - PROCESS-CRASH | Last test finished | application crashed [@ mozilla::MozPromise<unsigned long, nsresult, true>::ThenValueBase::AssertIsDead()]
[task 2020-04-23T18:38:15.380Z] 18:38:15 INFO - Mozilla crash reason: MOZ_DIAGNOSTIC_ASSERT(Request::mDisconnected)
[task 2020-04-23T18:38:15.380Z] 18:38:15 INFO - Crash dump filename: /tmp/tmpTP_Cjj.mozrunner/minidumps/6ec15456-b422-7d87-979f-d039b0c07c2f.dmp
[task 2020-04-23T18:38:15.381Z] 18:38:15 INFO - Operating system: Linux
[task 2020-04-23T18:38:15.381Z] 18:38:15 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-23T18:38:15.382Z] 18:38:15 INFO - CPU: amd64
[task 2020-04-23T18:38:15.383Z] 18:38:15 INFO - family 6 model 85 stepping 7
[task 2020-04-23T18:38:15.383Z] 18:38:15 INFO - 2 CPUs
[task 2020-04-23T18:38:15.384Z] 18:38:15 INFO -
[task 2020-04-23T18:38:15.384Z] 18:38:15 INFO - GPU: UNKNOWN
[task 2020-04-23T18:38:15.385Z] 18:38:15 INFO -
[task 2020-04-23T18:38:15.385Z] 18:38:15 INFO - Crash reason: SIGSEGV /SEGV_MAPERR
[task 2020-04-23T18:38:15.386Z] 18:38:15 INFO - Crash address: 0x0
[task 2020-04-23T18:38:15.387Z] 18:38:15 INFO - Process uptime: not available
[task 2020-04-23T18:38:15.387Z] 18:38:15 INFO -
[task 2020-04-23T18:38:15.388Z] 18:38:15 INFO - Thread 0 (crashed)
[task 2020-04-23T18:38:15.388Z] 18:38:15 INFO - 0 libxul.so!mozilla::MozPromise<unsigned long, nsresult, true>::ThenValueBase::AssertIsDead() [MozPromise.h:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 442 + 0x29]
[task 2020-04-23T18:38:15.389Z] 18:38:15 INFO - rax = 0x00007fe68336f0bc rdx = 0x0000000000000000
[task 2020-04-23T18:38:15.390Z] 18:38:15 INFO - rcx = 0x00005608cb539e30 rbx = 0x00007fe65f0d9640
[task 2020-04-23T18:38:15.390Z] 18:38:15 INFO - rsi = 0x00007fe6901308b0 rdi = 0x00007fe69012f680
[task 2020-04-23T18:38:15.391Z] 18:38:15 INFO - rbp = 0x00007ffc28eb9810 rsp = 0x00007ffc28eb9800
[task 2020-04-23T18:38:15.391Z] 18:38:15 INFO - r8 = 0x00007fe6901308b0 r9 = 0x00007fe69129f780
[task 2020-04-23T18:38:15.392Z] 18:38:15 INFO - r10 = 0x0000000000000000 r11 = 0x0000000000000000
[task 2020-04-23T18:38:15.392Z] 18:38:15 INFO - r12 = 0x0000000000000008 r13 = 0x00007fe65cfb0000
[task 2020-04-23T18:38:15.393Z] 18:38:15 INFO - r14 = 0x00007fe65ce03710 r15 = 0x0000000000000000
[task 2020-04-23T18:38:15.394Z] 18:38:15 INFO - rip = 0x00007fe67d63da8f
[task 2020-04-23T18:38:15.394Z] 18:38:15 INFO - Found by: given as instruction pointer in context
[task 2020-04-23T18:38:15.395Z] 18:38:15 INFO - 1 libxul.so!mozilla::MozPromise<unsigned long, nsresult, true>::AssertIsDead() [MozPromise.h:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 981 + 0x10]
[task 2020-04-23T18:38:15.395Z] 18:38:15 INFO - rbx = 0x0000000000000000 rbp = 0x00007ffc28eb9870
[task 2020-04-23T18:38:15.396Z] 18:38:15 INFO - rsp = 0x00007ffc28eb9820 r12 = 0x0000000000000008
[task 2020-04-23T18:38:15.396Z] 18:38:15 INFO - r13 = 0x00007fe65cfb0000 r14 = 0x00007fe65ce03710
[task 2020-04-23T18:38:15.397Z] 18:38:15 INFO - r15 = 0x0000000000000000 rip = 0x00007fe67d63d46f
[task 2020-04-23T18:38:15.398Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.398Z] 18:38:15 INFO - 2 libxul.so!mozilla::MozPromise<unsigned long, nsresult, true>::~MozPromise() [MozPromise.h:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 1022 + 0x9]
[task 2020-04-23T18:38:15.399Z] 18:38:15 INFO - rbx = 0x00007fe65ce03710 rbp = 0x00007ffc28eb9890
[task 2020-04-23T18:38:15.399Z] 18:38:15 INFO - rsp = 0x00007ffc28eb9880 r12 = 0x00007ffc28eb9970
[task 2020-04-23T18:38:15.400Z] 18:38:15 INFO - r13 = 0x00007fe65cfb0000 r14 = 0x00007fe65ce03710
[task 2020-04-23T18:38:15.400Z] 18:38:15 INFO - r15 = 0x00007ffc28eb9a08 rip = 0x00007fe67d63d57d
[task 2020-04-23T18:38:15.401Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.402Z] 18:38:15 INFO - 3 libxul.so!mozilla::MozPromise<unsigned long, nsresult, true>::Private::~Private() [MozPromise.h:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 253 + 0xe]
[task 2020-04-23T18:38:15.402Z] 18:38:15 INFO - rbx = 0x00007fe65ce03710 rbp = 0x00007ffc28eb98b0
[task 2020-04-23T18:38:15.403Z] 18:38:15 INFO - rsp = 0x00007ffc28eb98a0 r12 = 0x00007ffc28eb9970
[task 2020-04-23T18:38:15.403Z] 18:38:15 INFO - r13 = 0x00007fe65cfb0000 r14 = 0x00007fe65ce03710
[task 2020-04-23T18:38:15.404Z] 18:38:15 INFO - r15 = 0x00007ffc28eb9a08 rip = 0x00007fe67d63d3b2
[task 2020-04-23T18:38:15.404Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.405Z] 18:38:15 INFO - 4 libxul.so!mozilla::MozPromiseRefcountable::Release() [MozPromise.h:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 148 + 0x3b]
[task 2020-04-23T18:38:15.406Z] 18:38:15 INFO - rbx = 0x0000000000000000 rbp = 0x00007ffc28eb98d0
[task 2020-04-23T18:38:15.406Z] 18:38:15 INFO - rsp = 0x00007ffc28eb98c0 r12 = 0x00007ffc28eb9970
[task 2020-04-23T18:38:15.407Z] 18:38:15 INFO - r13 = 0x00007fe65cfb0000 r14 = 0x00007fe65ce03710
[task 2020-04-23T18:38:15.407Z] 18:38:15 INFO - r15 = 0x00007ffc28eb9a08 rip = 0x00007fe67d07c7b8
[task 2020-04-23T18:38:15.408Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.408Z] 18:38:15 INFO - 5 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_destroy(std::_Any_data&, std::integral_constant<bool, false>) [std_function.h : 207 + 0x15]
[task 2020-04-23T18:38:15.409Z] 18:38:15 INFO - rbx = 0x00007fe65fce35a8 rbp = 0x00007ffc28eb98f0
[task 2020-04-23T18:38:15.409Z] 18:38:15 INFO - rsp = 0x00007ffc28eb98e0 r12 = 0x00007ffc28eb9970
[task 2020-04-23T18:38:15.409Z] 18:38:15 INFO - r13 = 0x00007fe65cfb0000 r14 = 0x0000000000000000
[task 2020-04-23T18:38:15.410Z] 18:38:15 INFO - r15 = 0x00007ffc28eb9a08 rip = 0x00007fe67d63edd7
[task 2020-04-23T18:38:15.410Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.411Z] 18:38:15 INFO - 6 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 + 0x5]
[task 2020-04-23T18:38:15.411Z] 18:38:15 INFO - rbx = 0x00007fe65fb6ae40 rbp = 0x00007ffc28eb9900
[task 2020-04-23T18:38:15.411Z] 18:38:15 INFO - rsp = 0x00007ffc28eb9900 r12 = 0x00007ffc28eb9970
[task 2020-04-23T18:38:15.412Z] 18:38:15 INFO - r13 = 0x00007fe65cfb0000 r14 = 0x0000000000000000
[task 2020-04-23T18:38:15.412Z] 18:38:15 INFO - r15 = 0x00007ffc28eb9a08 rip = 0x00007fe67d63dbde
[task 2020-04-23T18:38:15.412Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.413Z] 18:38:15 INFO - 7 libxul.so!mozilla::dom::DomPromiseListener::~DomPromiseListener() [PromiseNativeHandler.h:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 54 + 0xe]
[task 2020-04-23T18:38:15.413Z] 18:38:15 INFO - rbx = 0x00007fe65fb6ae40 rbp = 0x00007ffc28eb9920
[task 2020-04-23T18:38:15.413Z] 18:38:15 INFO - rsp = 0x00007ffc28eb9910 r12 = 0x00007ffc28eb9970
[task 2020-04-23T18:38:15.414Z] 18:38:15 INFO - r13 = 0x00007fe65cfb0000 r14 = 0x0000000000000000
[task 2020-04-23T18:38:15.414Z] 18:38:15 INFO - r15 = 0x00007ffc28eb9a08 rip = 0x00007fe67fbe0c08
[task 2020-04-23T18:38:15.414Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.415Z] 18:38:15 INFO - 8 libxul.so!mozilla::dom::DomPromiseListener::Release() [PromiseNativeHandler.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 14 + 0x41]
[task 2020-04-23T18:38:15.415Z] 18:38:15 INFO - rbx = 0x00007fe65fb6ae40 rbp = 0x00007ffc28eb9940
[task 2020-04-23T18:38:15.415Z] 18:38:15 INFO - rsp = 0x00007ffc28eb9930 r12 = 0x00007ffc28eb9970
[task 2020-04-23T18:38:15.415Z] 18:38:15 INFO - r13 = 0x00007fe65cfb0000 r14 = 0x0000000000000000
[task 2020-04-23T18:38:15.415Z] 18:38:15 INFO - r15 = 0x00007ffc28eb9a08 rip = 0x00007fe67fbe0845
[task 2020-04-23T18:38:15.416Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.416Z] 18:38:15 INFO - 9 libxul.so!mozilla::dom::(anonymous namespace)::PromiseNativeHandlerShim::~PromiseNativeHandlerShim() [Promise.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 374 + 0x22]
[task 2020-04-23T18:38:15.416Z] 18:38:15 INFO - rbx = 0x00007fe65cf92b00 rbp = 0x00007ffc28eb9960
[task 2020-04-23T18:38:15.416Z] 18:38:15 INFO - rsp = 0x00007ffc28eb9950 r12 = 0x00007ffc28eb9970
[task 2020-04-23T18:38:15.416Z] 18:38:15 INFO - r13 = 0x00007fe65cfb0000 r14 = 0x00007ffc28eb9a00
[task 2020-04-23T18:38:15.416Z] 18:38:15 INFO - r15 = 0x00007ffc28eb9a08 rip = 0x00007fe67fbe1468
[task 2020-04-23T18:38:15.416Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.417Z] 18:38:15 INFO - 10 libxul.so!SnowWhiteKiller::MaybeKillObject(SnowWhiteKiller::SnowWhiteObject&) [nsCycleCollector.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 2432 + 0xd]
[task 2020-04-23T18:38:15.417Z] 18:38:15 INFO - rbx = 0x00007fe660df6de0 rbp = 0x00007ffc28eb99a0
[task 2020-04-23T18:38:15.417Z] 18:38:15 INFO - rsp = 0x00007ffc28eb9970 r12 = 0x00007ffc28eb9970
[task 2020-04-23T18:38:15.418Z] 18:38:15 INFO - r13 = 0x00007fe65cfb0000 r14 = 0x00007ffc28eb9a00
[task 2020-04-23T18:38:15.418Z] 18:38:15 INFO - r15 = 0x00007ffc28eb9a08 rip = 0x00007fe67d079ea6
[task 2020-04-23T18:38:15.418Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.419Z] 18:38:15 INFO - 11 libxul.so!SnowWhiteKiller::~SnowWhiteKiller() [nsCycleCollector.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 2419 + 0xb]
[task 2020-04-23T18:38:15.419Z] 18:38:15 INFO - rbx = 0x00007ffc28eb99b0 rbp = 0x00007ffc28eb99e0
[task 2020-04-23T18:38:15.419Z] 18:38:15 INFO - rsp = 0x00007ffc28eb99b0 r12 = 0x00007fe67b0e05e0
[task 2020-04-23T18:38:15.420Z] 18:38:15 INFO - r13 = 0x00007fe65cfb0000 r14 = 0x00007ffc28eb9a10
[task 2020-04-23T18:38:15.420Z] 18:38:15 INFO - r15 = 0x00007ffc28eb9a00 rip = 0x00007fe67d070961
[task 2020-04-23T18:38:15.420Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.421Z] 18:38:15 INFO - 12 libxul.so!nsCycleCollector::FreeSnowWhite(bool) [nsCycleCollector.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 2607 + 0x5]
[task 2020-04-23T18:38:15.421Z] 18:38:15 INFO - rbx = 0x00007ffc28eb9a01 rbp = 0x00007ffc28eb9a80
[task 2020-04-23T18:38:15.421Z] 18:38:15 INFO - rsp = 0x00007ffc28eb99f0 r12 = 0x00007fe67b0e05e0
[task 2020-04-23T18:38:15.422Z] 18:38:15 INFO - r13 = 0x00007fe65cfb0000 r14 = 0x00007fe67b0e0600
[task 2020-04-23T18:38:15.422Z] 18:38:15 INFO - r15 = 0x0000000000000001 rip = 0x00007fe67d0703d9
[task 2020-04-23T18:38:15.422Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.422Z] 18:38:15 INFO - 13 libxul.so!nsCycleCollector::BeginCollection(ccType, nsICycleCollectorListener*) [nsCycleCollector.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 3580 + 0xd]
[task 2020-04-23T18:38:15.422Z] 18:38:15 INFO - rbx = 0x00007fe67b0e05e0 rbp = 0x00007ffc28eb9b00
[task 2020-04-23T18:38:15.423Z] 18:38:15 INFO - rsp = 0x00007ffc28eb9a90 r12 = 0x0000000000000000
[task 2020-04-23T18:38:15.423Z] 18:38:15 INFO - r13 = 0x0000000000000001 r14 = 0x0000000000000001
[task 2020-04-23T18:38:15.423Z] 18:38:15 INFO - r15 = 0x00007fe67b0e0698 rip = 0x00007fe67d073719
[task 2020-04-23T18:38:15.423Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.423Z] 18:38:15 INFO - 14 libxul.so!nsCycleCollector::Collect(ccType, js::SliceBudget&, nsICycleCollectorListener*, bool) [nsCycleCollector.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 3409 + 0xf]
[task 2020-04-23T18:38:15.424Z] 18:38:15 INFO - rbx = 0x00007fe67b0e05e0 rbp = 0x00007ffc28eb9b80
[task 2020-04-23T18:38:15.424Z] 18:38:15 INFO - rsp = 0x00007ffc28eb9b10 r12 = 0x00007ffc28eb9b90
[task 2020-04-23T18:38:15.424Z] 18:38:15 INFO - r13 = 0x0000000000000001 r14 = 0x00007fe68336b668
[task 2020-04-23T18:38:15.425Z] 18:38:15 INFO - r15 = 0x0000000000000000 rip = 0x00007fe67d07333c
[task 2020-04-23T18:38:15.425Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.425Z] 18:38:15 INFO - 15 libxul.so!nsCycleCollector_collect(nsICycleCollectorListener*) [nsCycleCollector.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 3907 + 0x1e]
[task 2020-04-23T18:38:15.426Z] 18:38:15 INFO - rbx = 0x00007ffc28eb9b90 rbp = 0x00007ffc28eb9be0
[task 2020-04-23T18:38:15.426Z] 18:38:15 INFO - rsp = 0x00007ffc28eb9b90 r12 = 0x0000000000000000
[task 2020-04-23T18:38:15.426Z] 18:38:15 INFO - r13 = 0x00007fe66aabcce0 r14 = 0x0000000000000000
[task 2020-04-23T18:38:15.427Z] 18:38:15 INFO - r15 = 0x00007fe67b0d7410 rip = 0x00007fe67d0752e1
[task 2020-04-23T18:38:15.427Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.428Z] 18:38:15 INFO - 16 libxul.so!nsJSContext::CycleCollectNow(nsICycleCollectorListener*) [nsJSEnvironment.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 1538 + 0x8]
[task 2020-04-23T18:38:15.428Z] 18:38:15 INFO - rbx = 0x00007fe68636b248 rbp = 0x00007ffc28eb9c30
[task 2020-04-23T18:38:15.429Z] 18:38:15 INFO - rsp = 0x00007ffc28eb9bf0 r12 = 0x0000000000000000
[task 2020-04-23T18:38:15.429Z] 18:38:15 INFO - r13 = 0x00007fe66aabcce0 r14 = 0x0000000000000000
[task 2020-04-23T18:38:15.430Z] 18:38:15 INFO - r15 = 0x00007fe65cd137c0 rip = 0x00007fe67e447860
[task 2020-04-23T18:38:15.430Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.431Z] 18:38:15 INFO - 17 libxul.so!nsJSEnvironmentObserver::Observe(nsISupports*, char const*, char16_t const*) [nsJSEnvironment.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 403 + 0x7]
[task 2020-04-23T18:38:15.431Z] 18:38:15 INFO - rbx = 0x00007ffc28eb9c40 rbp = 0x00007ffc28eb9cb0
[task 2020-04-23T18:38:15.432Z] 18:38:15 INFO - rsp = 0x00007ffc28eb9c40 r12 = 0x0000000000000000
[task 2020-04-23T18:38:15.432Z] 18:38:15 INFO - r13 = 0x00007fe66aabcce0 r14 = 0x00007fe65cd0cac0
[task 2020-04-23T18:38:15.432Z] 18:38:15 INFO - r15 = 0x00007fe65cd137c0 rip = 0x00007fe67e447409
[task 2020-04-23T18:38:15.433Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.433Z] 18:38:15 INFO - 18 libxul.so!nsObserverList::NotifyObservers(nsISupports*, char const*, char16_t const*) [nsObserverList.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 65 + 0x1b]
[task 2020-04-23T18:38:15.434Z] 18:38:15 INFO - rbx = 0x00000000000000ac rbp = 0x00007ffc28eb9d00
[task 2020-04-23T18:38:15.434Z] 18:38:15 INFO - rsp = 0x00007ffc28eb9cc0 r12 = 0x0000000000000000
[task 2020-04-23T18:38:15.435Z] 18:38:15 INFO - r13 = 0x00007fe66aabcce0 r14 = 0x00007fe65cd0cac0
[task 2020-04-23T18:38:15.435Z] 18:38:15 INFO - r15 = 0x00007fe65cd137c0 rip = 0x00007fe67d0a61cf
[task 2020-04-23T18:38:15.436Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.436Z] 18:38:15 INFO - 19 libxul.so!nsObserverService::NotifyObservers(nsISupports*, char const*, char16_t const*) [nsObserverService.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 292 + 0x19]
[task 2020-04-23T18:38:15.436Z] 18:38:15 INFO - rbx = 0x00007fe67b05c1c8 rbp = 0x00007ffc28eb9e00
[task 2020-04-23T18:38:15.437Z] 18:38:15 INFO - rsp = 0x00007ffc28eb9d10 r12 = 0x00007ffc28eb9d48
[task 2020-04-23T18:38:15.437Z] 18:38:15 INFO - r13 = 0x00007ffc28eb9d40 r14 = 0x00007ffc28eb9d50
[task 2020-04-23T18:38:15.438Z] 18:38:15 INFO - r15 = 0x00007fe65cd137c0 rip = 0x00007fe67d0a9710
[task 2020-04-23T18:38:15.438Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.439Z] 18:38:15 INFO - 20 libxul.so!NS_InvokeByIndex + 0x8e
[task 2020-04-23T18:38:15.439Z] 18:38:15 INFO - rbx = 0x0000000000000002 rbp = 0x00007ffc28eb9e40
[task 2020-04-23T18:38:15.440Z] 18:38:15 INFO - rsp = 0x00007ffc28eb9e10 r12 = 0x00007fe67b05c1a0
[task 2020-04-23T18:38:15.440Z] 18:38:15 INFO - r13 = 0x0000000000000005 r14 = 0x00007ffc28eb9ea8
[task 2020-04-23T18:38:15.441Z] 18:38:15 INFO - r15 = 0x00007ffc28eba002 rip = 0x00007fe67d1463c6
[task 2020-04-23T18:38:15.441Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.442Z] 18:38:15 INFO - 21 libxul.so!CallMethodHelper::Call() [XPCWrappedNative.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 1175 + 0x18]
[task 2020-04-23T18:38:15.442Z] 18:38:15 INFO - rbx = 0x0000000000000002 rbp = 0x00007ffc28eb9e80
[task 2020-04-23T18:38:15.442Z] 18:38:15 INFO - rsp = 0x00007ffc28eb9e50 r12 = 0x0000000000000002
[task 2020-04-23T18:38:15.443Z] 18:38:15 INFO - r13 = 0x0000000000000000 r14 = 0x00007ffc28eb9ea8
[task 2020-04-23T18:38:15.443Z] 18:38:15 INFO - r15 = 0x00007ffc28eba002 rip = 0x00007fe67db9969a
[task 2020-04-23T18:38:15.444Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.444Z] 18:38:15 INFO - 22 libxul.so!XPCWrappedNative::CallMethod(XPCCallContext&, XPCWrappedNative::CallMode) [XPCWrappedNative.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 1141 + 0xc]
[task 2020-04-23T18:38:15.445Z] 18:38:15 INFO - rbx = 0x00007ffc28eba068 rbp = 0x00007ffc28eb9fe0
[task 2020-04-23T18:38:15.445Z] 18:38:15 INFO - rsp = 0x00007ffc28eb9e90 r12 = 0x000017fd1e781310
[task 2020-04-23T18:38:15.446Z] 18:38:15 INFO - r13 = 0x00007ffc28eba040 r14 = 0x00007ffc28eb9e90
[task 2020-04-23T18:38:15.446Z] 18:38:15 INFO - r15 = 0x00007ffc28eba020 rip = 0x00007fe67db99492
[task 2020-04-23T18:38:15.446Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.447Z] 18:38:15 INFO - 23 libxul.so!XPC_WN_CallMethod(JSContext*, unsigned int, JS::Value*) [XPCWrappedNativeJSOps.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 947 + 0x8]
[task 2020-04-23T18:38:15.447Z] 18:38:15 INFO - rbx = 0x00007ffc28eba068 rbp = 0x00007ffc28eba130
[task 2020-04-23T18:38:15.448Z] 18:38:15 INFO - rsp = 0x00007ffc28eb9ff0 r12 = 0x000017fd1e781310
[task 2020-04-23T18:38:15.448Z] 18:38:15 INFO - r13 = 0x00007ffc28eba040 r14 = 0x00007ffc28eba008
[task 2020-04-23T18:38:15.449Z] 18:38:15 INFO - r15 = 0x00007ffc28eba020 rip = 0x00007fe67db9ab5b
[task 2020-04-23T18:38:15.449Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.450Z] 18:38:15 INFO - 24 libxul.so!CallJSNative(JSContext*, bool ()(JSContext, unsigned int, JS::Value*), js::CallReason, JS::CallArgs const&) [Interpreter.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 492 + 0x12]
[task 2020-04-23T18:38:15.450Z] 18:38:15 INFO - rbx = 0x00007fe665f8e0c0 rbp = 0x00007ffc28eba1c0
[task 2020-04-23T18:38:15.450Z] 18:38:15 INFO - rsp = 0x00007ffc28eba140 r12 = 0x00007fe670058800
[task 2020-04-23T18:38:15.451Z] 18:38:15 INFO - r13 = 0x00007ffc28eba380 r14 = 0x00007fe67471b000
[task 2020-04-23T18:38:15.451Z] 18:38:15 INFO - r15 = 0x00007fe67db9a887 rip = 0x00007fe6810e7d52
[task 2020-04-23T18:38:15.452Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.452Z] 18:38:15 INFO - 25 libxul.so!js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) [Interpreter.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 584 + 0x12]
[task 2020-04-23T18:38:15.453Z] 18:38:15 INFO - rbx = 0x00007ffc28eba1f0 rbp = 0x00007ffc28eba270
[task 2020-04-23T18:38:15.453Z] 18:38:15 INFO - rsp = 0x00007ffc28eba1d0 r12 = 0x00007ffc28eba380
[task 2020-04-23T18:38:15.453Z] 18:38:15 INFO - r13 = 0xfffdffffffffffff r14 = 0x0000000000000000
[task 2020-04-23T18:38:15.454Z] 18:38:15 INFO - r15 = 0x00007fe67db9a887 rip = 0x00007fe6810e7629
[task 2020-04-23T18:38:15.454Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.455Z] 18:38:15 INFO - 26 libxul.so!Interpret(JSContext*, js::RunState&) [Interpreter.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 651 + 0x7]
[task 2020-04-23T18:38:15.455Z] 18:38:15 INFO - rbx = 0x0000374e1ca58c40 rbp = 0x00007ffc28eba770
[task 2020-04-23T18:38:15.456Z] 18:38:15 INFO - rsp = 0x00007ffc28eba280 r12 = 0x00000000000000f5
[task 2020-04-23T18:38:15.456Z] 18:38:15 INFO - r13 = 0x00000000000000aa r14 = 0x0000000000000000
[task 2020-04-23T18:38:15.456Z] 18:38:15 INFO - r15 = 0x00007fe6861b74c0 rip = 0x00007fe6810dc2b9
[task 2020-04-23T18:38:15.457Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.457Z] 18:38:15 INFO - 27 libxul.so!js::RunScript(JSContext*, js::RunState&) [Interpreter.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 464 + 0xb]
[task 2020-04-23T18:38:15.458Z] 18:38:15 INFO - rbx = 0x00007fe663d6b051 rbp = 0x00007ffc28eba7c0
[task 2020-04-23T18:38:15.458Z] 18:38:15 INFO - rsp = 0x00007ffc28eba780 r12 = 0x00007fe68fb20460
[task 2020-04-23T18:38:15.458Z] 18:38:15 INFO - r13 = 0x00007fe67471b000 r14 = 0x00007ffc28eba810
[task 2020-04-23T18:38:15.459Z] 18:38:15 INFO - r15 = 0x000033784e2c7f60 rip = 0x00007fe6810d2acc
[task 2020-04-23T18:38:15.459Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.459Z] 18:38:15 INFO - 28 libxul.so!js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) [Interpreter.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 619 + 0xd]
[task 2020-04-23T18:38:15.459Z] 18:38:15 INFO - rbx = 0x00007ffc28eba7f0 rbp = 0x00007ffc28eba870
[task 2020-04-23T18:38:15.459Z] 18:38:15 INFO - rsp = 0x00007ffc28eba7d0 r12 = 0x00007ffc28eba8a0
[task 2020-04-23T18:38:15.460Z] 18:38:15 INFO - r13 = 0xfffdffffffffffff r14 = 0x0000000000000000
[task 2020-04-23T18:38:15.460Z] 18:38:15 INFO - r15 = 0x00007ffc28eba7f0 rip = 0x00007fe6810e753f
[task 2020-04-23T18:38:15.460Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.461Z] 18:38:15 INFO - 29 libxul.so!<name omitted> [Interpreter.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 664 + 0xb]
[task 2020-04-23T18:38:15.461Z] 18:38:15 INFO - rbx = 0x00007ffc28eba8a0 rbp = 0x00007ffc28eba890
[task 2020-04-23T18:38:15.461Z] 18:38:15 INFO - rsp = 0x00007ffc28eba880 r12 = 0x00007ffc28ebaa00
[task 2020-04-23T18:38:15.461Z] 18:38:15 INFO - r13 = 0x00007ffc28eba9e0 r14 = 0x00007ffc28eba9c0
[task 2020-04-23T18:38:15.462Z] 18:38:15 INFO - r15 = 0x00007fe6849c0740 rip = 0x00007fe6810e92e0
[task 2020-04-23T18:38:15.462Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.462Z] 18:38:15 INFO - 30 libxul.so!js::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, JS::Handle<JS::Value>, JS::MutableHandle<JS::Value>) [Interpreter.h:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 106 + 0x14]
[task 2020-04-23T18:38:15.462Z] 18:38:15 INFO - rbx = 0x00007fe67471b000 rbp = 0x00007ffc28eba920
[task 2020-04-23T18:38:15.462Z] 18:38:15 INFO - rsp = 0x00007ffc28eba8a0 r12 = 0x00007ffc28ebaa00
[task 2020-04-23T18:38:15.463Z] 18:38:15 INFO - r13 = 0x00007ffc28eba9e0 r14 = 0x00007ffc28eba9c0
[task 2020-04-23T18:38:15.463Z] 18:38:15 INFO - r15 = 0x00007fe6849c0740 rip = 0x00007fe68116ce2a
[task 2020-04-23T18:38:15.463Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.463Z] 18:38:15 INFO - 31 libxul.so!PromiseReactionJob(JSContext*, unsigned int, JS::Value*) [Promise.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 1871 + 0x19]
[task 2020-04-23T18:38:15.463Z] 18:38:15 INFO - rbx = 0x00007ffc28ebaa00 rbp = 0x00007ffc28ebaa60
[task 2020-04-23T18:38:15.463Z] 18:38:15 INFO - rsp = 0x00007ffc28eba930 r12 = 0xaaaaaaaaaaaaaaaa
[task 2020-04-23T18:38:15.464Z] 18:38:15 INFO - r13 = 0x00007fe67471b018 r14 = 0x00007ffc28eba9c0
[task 2020-04-23T18:38:15.464Z] 18:38:15 INFO - r15 = 0x00007fe67471b000 rip = 0x00007fe68133bb8d
[task 2020-04-23T18:38:15.464Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.464Z] 18:38:15 INFO - 32 libxul.so!CallJSNative(JSContext*, bool ()(JSContext, unsigned int, JS::Value*), js::CallReason, JS::CallArgs const&) [Interpreter.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 492 + 0x12]
[task 2020-04-23T18:38:15.464Z] 18:38:15 INFO - rbx = 0x00007ffc28ebac40 rbp = 0x00007ffc28ebaaf0
[task 2020-04-23T18:38:15.465Z] 18:38:15 INFO - rsp = 0x00007ffc28ebaa70 r12 = 0x00007fe670058800
[task 2020-04-23T18:38:15.465Z] 18:38:15 INFO - r13 = 0x00007ffc28ebabe0 r14 = 0x00007fe67471b000
[task 2020-04-23T18:38:15.465Z] 18:38:15 INFO - r15 = 0x00007fe68133abe0 rip = 0x00007fe6810e7d52
[task 2020-04-23T18:38:15.465Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.466Z] 18:38:15 INFO - 33 libxul.so!js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) [Interpreter.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 584 + 0x12]
[task 2020-04-23T18:38:15.466Z] 18:38:15 INFO - rbx = 0x00007ffc28ebab20 rbp = 0x00007ffc28ebaba0
[task 2020-04-23T18:38:15.466Z] 18:38:15 INFO - rsp = 0x00007ffc28ebab00 r12 = 0x00007ffc28ebabe0
[task 2020-04-23T18:38:15.467Z] 18:38:15 INFO - r13 = 0xfffdffffffffffff r14 = 0x0000000000000000
[task 2020-04-23T18:38:15.467Z] 18:38:15 INFO - r15 = 0x00007fe68133abe0 rip = 0x00007fe6810e7629
[task 2020-04-23T18:38:15.467Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.467Z] 18:38:15 INFO - 34 libxul.so!<name omitted> [Interpreter.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 664 + 0xb]
[task 2020-04-23T18:38:15.468Z] 18:38:15 INFO - rbx = 0x00007ffc28ebabe0 rbp = 0x00007ffc28ebabc0
[task 2020-04-23T18:38:15.468Z] 18:38:15 INFO - rsp = 0x00007ffc28ebabb0 r12 = 0x00007ffc28ebabf0
[task 2020-04-23T18:38:15.468Z] 18:38:15 INFO - r13 = 0x00007ffc28ebad60 r14 = 0x00007ffc28ebad80
[task 2020-04-23T18:38:15.469Z] 18:38:15 INFO - r15 = 0x00007ffc28ebad40 rip = 0x00007fe6810e92e0
[task 2020-04-23T18:38:15.469Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.470Z] 18:38:15 INFO - 35 libxul.so!JS::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, JS::HandleValueArray const&, JS::MutableHandle<JS::Value>) [jsapi.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 2807 + 0x23]
[task 2020-04-23T18:38:15.470Z] 18:38:15 INFO - rbx = 0x00007fe67471b000 rbp = 0x00007ffc28ebad10
[task 2020-04-23T18:38:15.470Z] 18:38:15 INFO - rsp = 0x00007ffc28ebabd0 r12 = 0x00007ffc28ebabf0
[task 2020-04-23T18:38:15.471Z] 18:38:15 INFO - r13 = 0x00007ffc28ebad60 r14 = 0x00007ffc28ebad80
[task 2020-04-23T18:38:15.471Z] 18:38:15 INFO - r15 = 0x00007ffc28ebad40 rip = 0x00007fe6811df2d6
[task 2020-04-23T18:38:15.472Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.472Z] 18:38:15 INFO - 36 libxul.so!mozilla::dom::PromiseJobCallback::Call(mozilla::dom::BindingCallContext&, JS::Handle<JS::Value>, mozilla::ErrorResult&) [PromiseBinding.cpp: : 28 + 0xf]
[task 2020-04-23T18:38:15.472Z] 18:38:15 INFO - rbx = 0x00007ffc28ebad40 rbp = 0x00007ffc28ebadc0
[task 2020-04-23T18:38:15.472Z] 18:38:15 INFO - rsp = 0x00007ffc28ebad20 r12 = 0x00007ffc28ebafa8
[task 2020-04-23T18:38:15.473Z] 18:38:15 INFO - r13 = 0x00007ffc28ebad60 r14 = 0x00007fe65cf30b18
[task 2020-04-23T18:38:15.473Z] 18:38:15 INFO - r15 = 0x00007ffc28ebad50 rip = 0x00007fe67e80b1be
[task 2020-04-23T18:38:15.474Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.474Z] 18:38:15 INFO - 37 libxul.so!mozilla::dom::PromiseJobCallback::Call(mozilla::ErrorResult&, char const*, mozilla::dom::CallbackObject::ExceptionHandling, JS::Realm*) [PromiseBinding.h: : 91 + 0x1e]
[task 2020-04-23T18:38:15.474Z] 18:38:15 INFO - rbx = 0x00007fe683364441 rbp = 0x00007ffc28ebb000
[task 2020-04-23T18:38:15.475Z] 18:38:15 INFO - rsp = 0x00007ffc28ebadd0 r12 = 0x0000000000000000
[task 2020-04-23T18:38:15.475Z] 18:38:15 INFO - r13 = 0x00007ffc28ebb020 r14 = 0x00007fe65cf30b00
[task 2020-04-23T18:38:15.476Z] 18:38:15 INFO - r15 = 0x00007ffc28ebade0 rip = 0x00007fe67d05ce3a
[task 2020-04-23T18:38:15.476Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.476Z] 18:38:15 INFO - 38 libxul.so!mozilla::PromiseJobRunnable::Run(mozilla::AutoSlowOperation&) [CycleCollectedJSContext.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 213 + 0x41]
[task 2020-04-23T18:38:15.476Z] 18:38:15 INFO - rbx = 0x00007fe65cf30b00 rbp = 0x00007ffc28ebb070
[task 2020-04-23T18:38:15.477Z] 18:38:15 INFO - rsp = 0x00007ffc28ebb010 r12 = 0x00007fe65ce300e8
[task 2020-04-23T18:38:15.477Z] 18:38:15 INFO - r13 = 0x00007ffc28ebb020 r14 = 0x00007ffc28ebb0f8
[task 2020-04-23T18:38:15.477Z] 18:38:15 INFO - r15 = 0x00007fe67b045000 rip = 0x00007fe67d05be16
[task 2020-04-23T18:38:15.477Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.477Z] 18:38:15 INFO - 39 libxul.so!mozilla::CycleCollectedJSContext::PerformMicroTaskCheckPoint(bool) [CycleCollectedJSContext.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 644 + 0x14]
[task 2020-04-23T18:38:15.477Z] 18:38:15 INFO - rbx = 0x00007ffc28ebb001 rbp = 0x00007ffc28ebb140
[task 2020-04-23T18:38:15.477Z] 18:38:15 INFO - rsp = 0x00007ffc28ebb080 r12 = 0x00007fe6745a2be8
[task 2020-04-23T18:38:15.477Z] 18:38:15 INFO - r13 = 0x00007ffc28ebb098 r14 = 0x00007fe67459d000
[task 2020-04-23T18:38:15.477Z] 18:38:15 INFO - r15 = 0x00007fe6745a2b98 rip = 0x00007fe67d04d1cd
[task 2020-04-23T18:38:15.478Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.478Z] 18:38:15 INFO - 40 libxul.so!mozilla::CycleCollectedJSContext::AfterProcessTask(unsigned int) [CycleCollectedJSContext.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 463 + 0x7]
[task 2020-04-23T18:38:15.478Z] 18:38:15 INFO - rbx = 0x00007fe67459d000 rbp = 0x00007ffc28ebb160
[task 2020-04-23T18:38:15.478Z] 18:38:15 INFO - rsp = 0x00007ffc28ebb150 r12 = 0x00007fe67b06cc98
[task 2020-04-23T18:38:15.479Z] 18:38:15 INFO - r13 = 0x000011bec6dd9330 r14 = 0x0000000000000001
[task 2020-04-23T18:38:15.480Z] 18:38:15 INFO - r15 = 0x0000000000000000 rip = 0x00007fe67d04dd8a
[task 2020-04-23T18:38:15.480Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.481Z] 18:38:15 INFO - 41 libxul.so!XPCJSContext::AfterProcessTask(unsigned int) [XPCJSContext.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 1331 + 0xb]
[task 2020-04-23T18:38:15.481Z] 18:38:15 INFO - rbx = 0x00007fe67459d000 rbp = 0x00007ffc28ebb180
[task 2020-04-23T18:38:15.482Z] 18:38:15 INFO - rsp = 0x00007ffc28ebb170 r12 = 0x00007fe67b06cc98
[task 2020-04-23T18:38:15.482Z] 18:38:15 INFO - r13 = 0x000011bec6dd9330 r14 = 0x0000000000000001
[task 2020-04-23T18:38:15.482Z] 18:38:15 INFO - r15 = 0x0000000000000000 rip = 0x00007fe67db6908e
[task 2020-04-23T18:38:15.483Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.483Z] 18:38:15 INFO - 42 libxul.so!nsThread::ProcessNextEvent(bool, bool*) [nsThread.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 1225 + 0xa]
[task 2020-04-23T18:38:15.484Z] 18:38:15 INFO - rbx = 0xaaaaaaaaaaaaaaaa rbp = 0x00007ffc28ebb6e0
[task 2020-04-23T18:38:15.484Z] 18:38:15 INFO - rsp = 0x00007ffc28ebb190 r12 = 0x00007fe67b06cc98
[task 2020-04-23T18:38:15.484Z] 18:38:15 INFO - r13 = 0x000011bec6dd9330 r14 = 0x00007fe67b06cbd0
[task 2020-04-23T18:38:15.485Z] 18:38:15 INFO - r15 = 0x0000000000000000 rip = 0x00007fe67d126f93
[task 2020-04-23T18:38:15.485Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.486Z] 18:38:15 INFO - 43 libxul.so!NS_ProcessNextEvent(nsIThread*, bool) [nsThreadUtils.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 481 + 0xc]
[task 2020-04-23T18:38:15.486Z] 18:38:15 INFO - rbx = 0x0000000000000001 rbp = 0x00007ffc28ebb710
[task 2020-04-23T18:38:15.486Z] 18:38:15 INFO - rsp = 0x00007ffc28ebb6f0 r12 = 0x0000000000000000
[task 2020-04-23T18:38:15.487Z] 18:38:15 INFO - r13 = 0x00007fe67b0e5ea0 r14 = 0x00007ffc28ebb6f7
[task 2020-04-23T18:38:15.487Z] 18:38:15 INFO - r15 = 0x0000000000000000 rip = 0x00007fe67d12aa23
[task 2020-04-23T18:38:15.487Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.488Z] 18:38:15 INFO - 44 libxul.so!mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate*) [MessagePump.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 109 + 0xa]
[task 2020-04-23T18:38:15.488Z] 18:38:15 INFO - rbx = 0x00007fe67b0e5e80 rbp = 0x00007ffc28ebb770
[task 2020-04-23T18:38:15.489Z] 18:38:15 INFO - rsp = 0x00007ffc28ebb720 r12 = 0x0000000000000000
[task 2020-04-23T18:38:15.489Z] 18:38:15 INFO - r13 = 0x00007fe67b0e5ea0 r14 = 0x00007fe68fb97180
[task 2020-04-23T18:38:15.489Z] 18:38:15 INFO - r15 = 0x0000000000000000 rip = 0x00007fe67d715635
[task 2020-04-23T18:38:15.490Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.490Z] 18:38:15 INFO - 45 libxul.so!MessageLoop::RunInternal() [message_loop.cc:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 315 + 0x17]
[task 2020-04-23T18:38:15.491Z] 18:38:15 INFO - rbx = 0x00007fe68fb97180 rbp = 0x00007ffc28ebb7b0
[task 2020-04-23T18:38:15.491Z] 18:38:15 INFO - rsp = 0x00007ffc28ebb780 r12 = 0x0000000000000001
[task 2020-04-23T18:38:15.491Z] 18:38:15 INFO - r13 = 0xaaaaaaaaaaaaaaaa r14 = 0x00007ffc28ebb7c0
[task 2020-04-23T18:38:15.492Z] 18:38:15 INFO - r15 = 0x00007ffc28ebb8a0 rip = 0x00007fe67d6c3745
[task 2020-04-23T18:38:15.492Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.493Z] 18:38:15 INFO - 46 libxul.so!MessageLoop::Run() [message_loop.cc:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 290 + 0x8]
[task 2020-04-23T18:38:15.493Z] 18:38:15 INFO - rbx = 0x00007fe68fb97180 rbp = 0x00007ffc28ebb7f0
[task 2020-04-23T18:38:15.493Z] 18:38:15 INFO - rsp = 0x00007ffc28ebb7c0 r12 = 0x0000000000000001
[task 2020-04-23T18:38:15.493Z] 18:38:15 INFO - r13 = 0xaaaaaaaaaaaaaaaa r14 = 0x00007ffc28ebb7c0
[task 2020-04-23T18:38:15.494Z] 18:38:15 INFO - r15 = 0x00007ffc28ebb8a0 rip = 0x00007fe67d6c369f
[task 2020-04-23T18:38:15.495Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.495Z] 18:38:15 INFO - 47 libxul.so!nsBaseAppShell::Run() [nsBaseAppShell.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 137 + 0xd]
[task 2020-04-23T18:38:15.495Z] 18:38:15 INFO - rbx = 0x00007fe67b089f20 rbp = 0x00007ffc28ebb810
[task 2020-04-23T18:38:15.496Z] 18:38:15 INFO - rsp = 0x00007ffc28ebb800 r12 = 0x0000000000000001
[task 2020-04-23T18:38:15.496Z] 18:38:15 INFO - r13 = 0xaaaaaaaaaaaaaaaa r14 = 0x00007fe67b06cbd0
[task 2020-04-23T18:38:15.496Z] 18:38:15 INFO - r15 = 0x00007ffc28ebb8a0 rip = 0x00007fe67fe98dd3
[task 2020-04-23T18:38:15.496Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.496Z] 18:38:15 INFO - 48 libxul.so!nsAppStartup::Run() [nsAppStartup.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 271 + 0x12]
[task 2020-04-23T18:38:15.497Z] 18:38:15 INFO - rbx = 0x00007fe67b099560 rbp = 0x00007ffc28ebb830
[task 2020-04-23T18:38:15.497Z] 18:38:15 INFO - rsp = 0x00007ffc28ebb820 r12 = 0x0000000000000001
[task 2020-04-23T18:38:15.497Z] 18:38:15 INFO - r13 = 0xaaaaaaaaaaaaaaaa r14 = 0x00007ffc28ebbaa8
[task 2020-04-23T18:38:15.498Z] 18:38:15 INFO - r15 = 0x00007ffc28ebb8a0 rip = 0x00007fe680f086b3
[task 2020-04-23T18:38:15.498Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.498Z] 18:38:15 INFO - 49 libxul.so!XREMain::XRE_mainRun() [nsAppRunner.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 4608 + 0xe]
[task 2020-04-23T18:38:15.498Z] 18:38:15 INFO - rbx = 0x0000000000000077 rbp = 0x00007ffc28ebb9d0
[task 2020-04-23T18:38:15.499Z] 18:38:15 INFO - rsp = 0x00007ffc28ebb840 r12 = 0x0000000000000001
[task 2020-04-23T18:38:15.499Z] 18:38:15 INFO - r13 = 0xaaaaaaaaaaaaaaaa r14 = 0x00007ffc28ebbaa8
[task 2020-04-23T18:38:15.499Z] 18:38:15 INFO - r15 = 0x00007ffc28ebb8a0 rip = 0x00007fe680fe92be
[task 2020-04-23T18:38:15.500Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.500Z] 18:38:15 INFO - 50 libxul.so!XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) [nsAppRunner.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 4743 + 0x8]
[task 2020-04-23T18:38:15.501Z] 18:38:15 INFO - rbx = 0x00007ffc28ebbaa0 rbp = 0x00007ffc28ebba90
[task 2020-04-23T18:38:15.501Z] 18:38:15 INFO - rsp = 0x00007ffc28ebb9e0 r12 = 0x0000000000000001
[task 2020-04-23T18:38:15.502Z] 18:38:15 INFO - r13 = 0x00007ffc28ebbad8 r14 = 0x0000000000000000
[task 2020-04-23T18:38:15.502Z] 18:38:15 INFO - r15 = 0x00007ffc28ebbad0 rip = 0x00007fe680fe9ec4
[task 2020-04-23T18:38:15.502Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.503Z] 18:38:15 INFO - 51 libxul.so!XRE_main(int, char**, mozilla::BootstrapConfig const&) [nsAppRunner.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 4797 + 0x10]
[task 2020-04-23T18:38:15.503Z] 18:38:15 INFO - rbx = 0x0000000000000005 rbp = 0x00007ffc28ebbbe0
[task 2020-04-23T18:38:15.504Z] 18:38:15 INFO - rsp = 0x00007ffc28ebbaa0 r12 = 0x00007ffc28ebbaa0
[task 2020-04-23T18:38:15.504Z] 18:38:15 INFO - r13 = 0x00007ffc28ebde26 r14 = 0x00007ffc28ebbc40
[task 2020-04-23T18:38:15.504Z] 18:38:15 INFO - r15 = 0x00007ffc28ebcd58 rip = 0x00007fe680fea4e0
[task 2020-04-23T18:38:15.505Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.505Z] 18:38:15 INFO - 52 firefox-bin!main [nsBrowserApp.cpp:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 217 + 0x1d]
[task 2020-04-23T18:38:15.505Z] 18:38:15 INFO - rbx = 0x00007fe6861b3790 rbp = 0x00007ffc28ebcc70
[task 2020-04-23T18:38:15.506Z] 18:38:15 INFO - rsp = 0x00007ffc28ebbbf0 r12 = 0x00007ffc28ebcd58
[task 2020-04-23T18:38:15.507Z] 18:38:15 INFO - r13 = 0x00007ffc28ebde26 r14 = 0x00007ffc28ebbc40
[task 2020-04-23T18:38:15.507Z] 18:38:15 INFO - r15 = 0x00005608cb50b243 rip = 0x00005608cb475eeb
[task 2020-04-23T18:38:15.507Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.508Z] 18:38:15 INFO - 53 libc.so.6 + 0x21b97
[task 2020-04-23T18:38:15.508Z] 18:38:15 INFO - rbx = 0x0000000000000000 rbp = 0x00005608cb50a730
[task 2020-04-23T18:38:15.509Z] 18:38:15 INFO - rsp = 0x00007ffc28ebcc80 r12 = 0x00005608cb475a70
[task 2020-04-23T18:38:15.509Z] 18:38:15 INFO - r13 = 0x00007ffc28ebcd50 r14 = 0x0000000000000000
[task 2020-04-23T18:38:15.509Z] 18:38:15 INFO - r15 = 0x0000000000000000 rip = 0x00007fe68fd64b97
[task 2020-04-23T18:38:15.509Z] 18:38:15 INFO - Found by: call frame info
[task 2020-04-23T18:38:15.510Z] 18:38:15 INFO - 54 firefox-bin!<name omitted> [UniquePtr.h:72894e6dc3a9780b6c2b5d2fc87dcf7cea7d6dc6 : 253 + 0x17]
[task 2020-04-23T18:38:15.510Z] 18:38:15 INFO - rsp = 0x00007ffc28ebcca0 rip = 0x00005608cb475c31
[task 2020-04-23T18:38:15.511Z] 18:38:15 INFO - Found by: stack scanning

Summary: Intermittent GECKO(3803) | Assertion failure: Request::mDisconnected, at /builds/worker/workspace/obj-build/dist/include/mozilla/MozPromise.h:442 → Intermittent Assertion failure: Request::mDisconnected, at /builds/worker/workspace/obj-build/dist/include/mozilla/MozPromise.h:442
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

In the last 7 days there have been 17 occurrences on linux1804-64, windows7-32 and windows10-64, build types debug and opt.

Recent failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=314381746&repo=mozilla-central&lineNumber=2221

Nathan, could you, please, direct this to someone who can take a look?

Flags: needinfo?(nfroyd)
Whiteboard: [stockwell needswork:owner]

I think this is not necessarily a problem with MozPromise, but it looks like something in JS is not getting promises resolved at the proper time?

Flags: needinfo?(nfroyd) → needinfo?(continuation)

These failures look to all be in telemetry-tests-client-e10s, specifically in the test telemetry/marionette/tests/client/test_event_ping.py.

The Windows failures have crummy stacks, but the ASan ones seem to be reasonable. I'll try to take a further look tomorrow.

Well, I'm not really sure what is going on here. The crashes are happening very late in shut down when we're shutting down TaskController and there are a bunch of tasks remaining. Is that expected? Anyways, I guess one of those is some kind of promise runnable that was never resolved and so we assert.

It looks like the failure is happening when we're on line 51 of the .py test, which is:
payload = self.wait_for_ping(self.restart_browser, EVENT_PING)["payload"]

Presumably the restart_browser is causing the browser to shut down, which is causing the other issues. There are a lot of JS promises in this test, so maybe those are related to the MozPromise. Anyways, I've reached my limit of understanding here.

The good news is that it looks like there was just a big spike earlier in the week, and it hasn't happened as much since then, so maybe there's no issue here? I still don't entirely understand what the assertion is supposed to be.

Flags: needinfo?(continuation)

Update:
There have been 26 failures within the last 7 days:

  • 3 failures on Linux 18.04 x64 debug
  • 3 failures on Linux 18.04 x64 asan opt
  • 5 failures on Windows 10 x64 debug
  • 1 failure on Windows 10 x64 MinGW debug
  • 1 failure on Windows 7 MinGW debug
  • 13 failures on Windows 7 debug

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=315515640&repo=autoland&lineNumber=1983

Whiteboard: [stockwell unknown] → [stockwell needswork]
Status: REOPENED → RESOLVED
Closed: 4 years ago4 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 4 years ago3 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 3 years ago3 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
See Also: → 1763853
Crash Signature: [@ mozilla::MozPromise<class nsRefCountedHashtable<class nsIntegralHashKey<uint64_t,0>,class RefPtr<struct mozilla::gfx::RecordedDependentSurface> >,enum nsresult,1>::ThenValueBase::AssertIsDead + 0xd6]
Severity: normal → S3

There have been 54 total failures in the last 7 days, recent failure log.
Affected platforms are:

  • linux1804-64-asan-qr
  • windows10-32-2004-qr
task 2022-10-21T22:42:51.480Z] 22:42:51     INFO - TEST-START | dom/media/webrtc/tests/mochitests/test_getUserMedia_basicTabshare.html
[task 2022-10-21T22:42:51.576Z] 22:42:51     INFO - GECKO(8528) | TEST DEVICES: No test device found in media.audio_loopback_dev, using fake audio streams.
[task 2022-10-21T22:42:51.582Z] 22:42:51     INFO - GECKO(8528) | TEST DEVICES: No test device found in media.video_loopback_dev, using fake video streams.
[task 2022-10-21T22:42:51.707Z] 22:42:51     INFO - GECKO(8528) | [Child 7056, Main Thread] WARNING: Can't add a range if the end is older that the start.: file /builds/worker/checkouts/gecko/dom/html/TimeRanges.cpp:72
[task 2022-10-21T22:42:51.716Z] 22:42:51     INFO - GECKO(8528) | [Child 7056, Main Thread] WARNING: Can't add a range if the end is older that the start.: file /builds/worker/checkouts/gecko/dom/html/TimeRanges.cpp:72
[task 2022-10-21T22:42:51.720Z] 22:42:51     INFO - GECKO(8528) | [Child 7056, Main Thread] WARNING: Can't add a range if the end is older that the start.: file /builds/worker/checkouts/gecko/dom/html/TimeRanges.cpp:72
[task 2022-10-21T22:42:51.776Z] 22:42:51     INFO - GECKO(8528) | TEST DEVICES: No test device found in media.audio_loopback_dev, using fake audio streams.
[task 2022-10-21T22:42:51.787Z] 22:42:51     INFO - GECKO(8528) | TEST DEVICES: No test device found in media.video_loopback_dev, using fake video streams.
[task 2022-10-21T22:42:51.939Z] 22:42:51     INFO - GECKO(8528) | [Parent 7880, Main Thread] WARNING: NS_ENSURE_TRUE(root) failed: file /builds/worker/checkouts/gecko/layout/base/nsDocumentViewer.cpp:2629
[task 2022-10-21T22:42:52.016Z] 22:42:52     INFO - GECKO(8528) | Assertion failure: Request::mDisconnected, at /builds/worker/workspace/obj-build/dist/include/mozilla/MozPromise.h:524
[task 2022-10-21T22:42:52.022Z] 22:42:52     INFO - GECKO(8528) | TEST DEVICES: No test device found in media.audio_loopback_dev, using fake audio streams.
[task 2022-10-21T22:42:52.023Z] 22:42:52     INFO - GECKO(8528) | TEST DEVICES: No test device found in media.video_loopback_dev, using fake video streams.
[task 2022-10-21T22:42:52.219Z] 22:42:52     INFO -  Initializing stack-fixing for the first stack frame, this may take a while...
Flags: needinfo?(nika)
Whiteboard: [stockwell unknown] → [stockwell needswork:owner]

This appears to have been made worse by bug 1793496, which added the promise failing in the log in comment 104. In this specific case, what is happening is that:

  1. a TaskQueue is created over the main thread, which will be used as the target for the MozPromise (https://searchfox.org/mozilla-central/rev/88f285c5163f73abd209d4f73cfa476660351982/dom/media/systemservices/video_engine/tab_capturer.cc#50-52).
  2. CaptureFrame is called, which dispatches async tasks to run on this task queue [^note] (https://searchfox.org/mozilla-central/rev/88f285c5163f73abd209d4f73cfa476660351982/dom/media/systemservices/video_engine/tab_capturer.cc#89-115)
  • FWIW the [&] captures in the CaptureFrame method are very buggy, and only happen to not cause stack UAFs because the only captured variable is this. You should never use [&] captures for async operations, as they capture references into the stack frame.
  1. The TabCapturerWebrtc is destroyed, which tries to block until the TaskQueue is destroyed (https://searchfox.org/mozilla-central/rev/88f285c5163f73abd209d4f73cfa476660351982/dom/media/systemservices/video_engine/tab_capturer.cc#55-58). This likely completes and returns fairly quickly, as there are no tasks in the queue.
  2. At some point the DOM promise which was being waited on is resolved, and it tries to resolve (or reject) the promise from a microtask: https://searchfox.org/mozilla-central/rev/88f285c5163f73abd209d4f73cfa476660351982/dom/media/systemservices/video_engine/tab_capturer.cc#153
  3. This tries to dispatch an event to the mMainThreadWorker event target, which has been shut down. Because it's been shut down, the dispatch fails. TaskQueues don't leak the events dispatched to them on shutdown (though that would lead to a different error, due to leaked objects), so the runnable is instead destroyed, and we hit this assertion because the resolve runnable was destroyed without the promise ever being resolved.

The mechanism of using a custom TaskQueue to try to ensure the capture completes before the TabCapturerWebrtc is destroyed doesn't look like it works, and it instead causes this other issue. A different mechanism will need to be used.

In addition, the [&] captures should be removed from CaptureFrame(), and there should be more detailed comments justifying why this stays alive long enough.

Flags: needinfo?(nika) → needinfo?(apehrson)

I've come to much of the same conclusion and am fixing this in bug 1795542. Sorry you had to spend all that time investigating and thanks for the thorough analysis!

Flags: needinfo?(apehrson)
See Also: → 1800752
Status: REOPENED → RESOLVED
Closed: 3 years ago1 year ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.