Closed Bug 1789535 Opened 5 months ago Closed 4 months ago

Intermittent LeakSanitizer | leak at mozilla::storage::Service::OpenDatabase, mozilla::dom::fs::data::, mozilla::dom::fs::data::FileSystemDataManager::BeginOpen, InvokeMethod

Categories

(Core :: DOM: Core & HTML, defect, P5)

defect

Tracking

()

RESOLVED FIXED
107 Branch
Tracking Status
firefox107 --- fixed

People

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

References

(Blocks 1 open bug)

Details

(Keywords: intermittent-failure)

Attachments

(1 file)

Filed by: nbeleuzu [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=389659647&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/VJdPL81HTuqLoHp3E8Wklw/runs/0/artifacts/public/logs/live_backing.log


[task 2022-09-06T22:05:53.654Z] 22:05:53     INFO - TEST-START | dom/fs/test/mochitest/test_basics_worker.html
[task 2022-09-06T22:05:54.345Z] 22:05:54     INFO - GECKO(3692) | MEMORY STAT | vsize 20974520MB | residentFast 448MB
[task 2022-09-06T22:05:54.361Z] 22:05:54     INFO - TEST-OK | dom/fs/test/mochitest/test_basics_worker.html | took 707ms
[task 2022-09-06T22:05:54.477Z] 22:05:54     INFO - TEST-START | Shutdown
[task 2022-09-06T22:05:54.481Z] 22:05:54     INFO - Passed:  40
[task 2022-09-06T22:05:54.502Z] 22:05:54     INFO - Failed:  0
[task 2022-09-06T22:05:54.506Z] 22:05:54     INFO - Todo:    0
[task 2022-09-06T22:05:54.510Z] 22:05:54     INFO - Mode:    e10s
[task 2022-09-06T22:05:54.511Z] 22:05:54     INFO - Slowest: 1819ms - /tests/dom/fs/test/mochitest/test_basics.html
[task 2022-09-06T22:05:54.516Z] 22:05:54     INFO - SimpleTest FINISHED
[task 2022-09-06T22:05:54.518Z] 22:05:54     INFO - TEST-INFO | Ran 1 Loops
[task 2022-09-06T22:05:54.519Z] 22:05:54     INFO - SimpleTest FINISHED
[task 2022-09-06T22:05:54.577Z] 22:05:54     INFO - GECKO(3692) | [Parent 3692, Main Thread] WARNING: ContentParent: id=619000f16380 - BlockShutdown: NotifyImpendingShutdown.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3627
[task 2022-09-06T22:05:54.578Z] 22:05:54     INFO - GECKO(3692) | [Parent 3692, Main Thread] WARNING: ContentParent: id=619000f16380 - BlockShutdown: RequestContentJSInterrupt.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3633
[task 2022-09-06T22:05:54.579Z] 22:05:54     INFO - GECKO(3692) | [Parent 3692, Main Thread] WARNING: ContentParent: id=619000ffa580 - BlockShutdown: NotifyImpendingShutdown.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3627
[task 2022-09-06T22:05:54.579Z] 22:05:54     INFO - GECKO(3692) | [Parent 3692, Main Thread] WARNING: ContentParent: id=619000ffa580 - BlockShutdown: RequestContentJSInterrupt.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3633
[task 2022-09-06T22:05:54.579Z] 22:05:54     INFO - GECKO(3692) | [Parent 3692, Main Thread] WARNING: ContentParent: id=6190011d8280 - BlockShutdown: NotifyImpendingShutdown.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3627
[task 2022-09-06T22:05:54.579Z] 22:05:54     INFO - GECKO(3692) | [Parent 3692, Main Thread] WARNING: ContentParent: id=6190011d8280 - BlockShutdown: RequestContentJSInterrupt.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3633
[task 2022-09-06T22:05:54.579Z] 22:05:54     INFO - GECKO(3692) | [Parent 3692, Main Thread] WARNING: ContentParent: id=619000f6a480 - BlockShutdown: NotifyImpendingShutdown.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3627
[task 2022-09-06T22:05:54.580Z] 22:05:54     INFO - GECKO(3692) | [Parent 3692, Main Thread] WARNING: ContentParent: id=619000f6a480 - BlockShutdown: RequestContentJSInterrupt.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3633
[task 2022-09-06T22:05:54.850Z] 22:05:54     INFO - GECKO(3692) | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 576: NotFoundError: No such JSWindowActor 'SpecialPowers'
[task 2022-09-06T22:05:54.883Z] 22:05:54     INFO - GECKO(3692) | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 576: NotFoundError: No such JSWindowActor 'AppTestDelegate'
[task 2022-09-06T22:05:54.885Z] 22:05:54     INFO - GECKO(3692) | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 576: NotFoundError: No such JSWindowActor 'AppTestDelegate'
[task 2022-09-06T22:05:54.943Z] 22:05:54     INFO - GECKO(3692) | 1662501954941	Marionette	TRACE	Received observer notification quit-application
[task 2022-09-06T22:05:54.946Z] 22:05:54     INFO - GECKO(3692) | 1662501954944	Marionette	INFO	Stopped listening on port 2828
[task 2022-09-06T22:05:54.949Z] 22:05:54     INFO - GECKO(3692) | 1662501954948	Marionette	DEBUG	Marionette stopped listening
[task 2022-09-06T22:05:55.379Z] 22:05:55     INFO - GECKO(3692) | [Parent 3692, Main Thread] WARNING: ContentParent: id=6190011d8280 - BlockShutdown: CanSend.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3672
[task 2022-09-06T22:05:55.383Z] 22:05:55     INFO - GECKO(3692) | [Parent 3692, Main Thread] WARNING: ContentParent: id=6190011d8280 - ShutDownProcess: Sent shutdown message.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1804
[task 2022-09-06T22:05:55.394Z] 22:05:55     INFO - GECKO(3692) | [Parent 3692, Main Thread] WARNING: ContentParent: id=619000f6a480 - BlockShutdown: CanSend.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3672
[task 2022-09-06T22:05:55.398Z] 22:05:55     INFO - GECKO(3692) | [Parent 3692, Main Thread] WARNING: ContentParent: id=619000f6a480 - ShutDownProcess: Sent shutdown message.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1804
[task 2022-09-06T22:05:55.435Z] 22:05:55     INFO - GECKO(3692) | [Parent 3692, Main Thread] WARNING: ContentParent: id=6190011d8280 - ShutDownProcess: Closing channel.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1844
[task 2022-09-06T22:05:55.437Z] 22:05:55     INFO - GECKO(3692) | [Parent 3692, Main Thread] WARNING: ContentParent: id=6190011d8280 - RemoveShutdownBlockers: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3787
[task 2022-09-06T22:05:55.440Z] 22:05:55     INFO - GECKO(3692) | [Parent 3692, Main Thread] WARNING: ContentParent: id=619000f6a480 - ShutDownProcess: Closing channel.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1844
[task 2022-09-06T22:05:55.444Z] 22:05:55     INFO - GECKO(3692) | [Parent 3692, Main Thread] WARNING: ContentParent: id=619000f6a480 - RemoveShutdownBlockers: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3787
[task 2022-09-06T22:05:55.872Z] 22:05:55     INFO - GECKO(3692) | -----------------------------------------------------
[task 2022-09-06T22:05:55.872Z] 22:05:55     INFO - GECKO(3692) | Suppressions used:
[task 2022-09-06T22:05:55.872Z] 22:05:55     INFO - GECKO(3692) |   count      bytes template
[task 2022-09-06T22:05:55.873Z] 22:05:55     INFO - GECKO(3692) |       2        288 libfontconfig.so
[task 2022-09-06T22:05:55.873Z] 22:05:55     INFO - GECKO(3692) | -----------------------------------------------------
[task 2022-09-06T22:05:55.900Z] 22:05:55     INFO - GECKO(3692) | -----------------------------------------------------
[task 2022-09-06T22:05:55.900Z] 22:05:55     INFO - GECKO(3692) | Suppressions used:
[task 2022-09-06T22:05:55.900Z] 22:05:55     INFO - GECKO(3692) |   count      bytes template
[task 2022-09-06T22:05:55.900Z] 22:05:55     INFO - GECKO(3692) |       2        288 libfontconfig.so
[task 2022-09-06T22:05:55.900Z] 22:05:55     INFO - GECKO(3692) | -----------------------------------------------------
[task 2022-09-06T22:05:55.944Z] 22:05:55     INFO - GECKO(3692) | -----------------------------------------------------
[task 2022-09-06T22:05:55.944Z] 22:05:55     INFO - GECKO(3692) | Suppressions used:
[task 2022-09-06T22:05:55.944Z] 22:05:55     INFO - GECKO(3692) |   count      bytes template
[task 2022-09-06T22:05:55.944Z] 22:05:55     INFO - GECKO(3692) |       2        288 libfontconfig.so
[task 2022-09-06T22:05:55.944Z] 22:05:55     INFO - GECKO(3692) | -----------------------------------------------------
[task 2022-09-06T22:05:56.128Z] 22:05:56     INFO - GECKO(3692) | -----------------------------------------------------
[task 2022-09-06T22:05:56.128Z] 22:05:56     INFO - GECKO(3692) | Suppressions used:
[task 2022-09-06T22:05:56.128Z] 22:05:56     INFO - GECKO(3692) |   count      bytes template
[task 2022-09-06T22:05:56.128Z] 22:05:56     INFO - GECKO(3692) |       2        288 libfontconfig.so
[task 2022-09-06T22:05:56.129Z] 22:05:56     INFO - GECKO(3692) | -----------------------------------------------------
[task 2022-09-06T22:05:58.756Z] 22:05:58     INFO - GECKO(3692) | =================================================================
[task 2022-09-06T22:05:58.758Z] 22:05:58    ERROR - GECKO(3692) | ==3692==ERROR: LeakSanitizer: detected memory leaks
[task 2022-09-06T22:05:58.758Z] 22:05:58     INFO - GECKO(3692) | Direct leak of 72 byte(s) in 1 object(s) allocated from:
[task 2022-09-06T22:05:58.760Z] 22:05:58     INFO - GECKO(3692) |     #0 0x562475e9f19e in malloc /builds/worker/fetches/llvm-project/compiler-rt/lib/asan/asan_malloc_linux.cpp:69:3
[task 2022-09-06T22:05:58.760Z] 22:05:58     INFO - GECKO(3692) |     #1 0x562475ee3cb5 in moz_xmalloc /builds/worker/checkouts/gecko/memory/mozalloc/mozalloc.cpp:52:15
[task 2022-09-06T22:05:58.761Z] 22:05:58     INFO - GECKO(3692) |     #2 0x7f5b00f51635 in operator new /builds/worker/workspace/obj-build/dist/include/mozilla/cxxalloc.h:33:10
[task 2022-09-06T22:05:58.766Z] 22:05:58     INFO - GECKO(3692) |     #3 0x7f5b00f51635 in NewRunnableMethod<mozilla::storage::Connection *, nsresult (mozilla::storage::Connection::*)()> /builds/worker/workspace/obj-build/dist/include/nsThreadUtils.h:1413:20
[task 2022-09-06T22:05:58.766Z] 22:05:58     INFO - GECKO(3692) |     #4 0x7f5b00f51635 in mozilla::storage::Connection::Release() /builds/worker/checkouts/gecko/storage/mozStorageConnection.cpp:504:13
[task 2022-09-06T22:05:58.767Z] 22:05:58     INFO - GECKO(3692) |     #5 0x7f5b04dd42ed in mozilla::dom::fs::data::FileSystemDatabaseManagerVersion001::~FileSystemDatabaseManagerVersion001() /builds/worker/checkouts/gecko/dom/fs/parent/datamodel/FileSystemDatabaseManagerVersion001.h:68:58
[task 2022-09-06T22:05:58.767Z] 22:05:58     INFO - GECKO(3692) |     #6 0x7f5b04de3b44 in operator() /builds/worker/workspace/obj-build/dist/include/mozilla/UniquePtr.h:459:5
[task 2022-09-06T22:05:58.773Z] 22:05:58     INFO - GECKO(3692) |     #7 0x7f5b04de3b44 in reset /builds/worker/workspace/obj-build/dist/include/mozilla/UniquePtr.h:301:7
[task 2022-09-06T22:05:58.773Z] 22:05:58     INFO - GECKO(3692) |     #8 0x7f5b04de3b44 in operator= /builds/worker/workspace/obj-build/dist/include/mozilla/UniquePtr.h:271:5
[task 2022-09-06T22:05:58.773Z] 22:05:58     INFO - GECKO(3692) |     #9 0x7f5b04de3b44 in operator() /builds/worker/checkouts/gecko/dom/fs/parent/datamodel/FileSystemDataManager.cpp:520:40
[task 2022-09-06T22:05:58.774Z] 22:05:58     INFO - GECKO(3692) |     #10 0x7f5b04de3b44 in mozilla::detail::ProxyFunctionRunnable<mozilla::dom::fs::data::FileSystemDataManager::BeginClose()::$_10, mozilla::MozPromise<bool, nsresult, false> >::Run() /builds/worker/workspace/obj-build/dist/include/mozilla/MozPromise.h:1645:29
[task 2022-09-06T22:05:58.774Z] 22:05:58     INFO - GECKO(3692) |     #11 0x7f5aff59b1b6 in mozilla::TaskQueue::Runner::Run() /builds/worker/checkouts/gecko/xpcom/threads/TaskQueue.cpp:259:20
[task 2022-09-06T22:05:58.775Z] 22:05:58     INFO - GECKO(3692) |     #12 0x7f5aff5c60b2 in nsThreadPool::Run() /builds/worker/checkouts/gecko/xpcom/threads/nsThreadPool.cpp:310:14
[task 2022-09-06T22:05:58.775Z] 22:05:58     INFO - GECKO(3692) |     #13 0x7f5aff5b896e in nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1199:16
[task 2022-09-06T22:05:58.775Z] 22:05:58     INFO - GECKO(3692) |     #14 0x7f5aff5c25c4 in NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:465:10
[task 2022-09-06T22:05:58.776Z] 22:05:58     INFO - GECKO(3692) |     #15 0x7f5b00bc2d78 in mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate*) /builds/worker/checkouts/gecko/ipc/glue/MessagePump.cpp:300:20
[task 2022-09-06T22:05:58.780Z] 22:05:58     INFO - GECKO(3692) |     #16 0x7f5b00a5ea01 in RunInternal /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:381:10
[task 2022-09-06T22:05:58.782Z] 22:05:58     INFO - GECKO(3692) |     #17 0x7f5b00a5ea01 in RunHandler /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:374:3
[task 2022-09-06T22:05:58.783Z] 22:05:58     INFO - GECKO(3692) |     #18 0x7f5b00a5ea01 in MessageLoop::Run() /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:356:3
[task 2022-09-06T22:05:58.784Z] 22:05:58     INFO - GECKO(3692) |     #19 0x7f5aff5afac4 in nsThread::ThreadFunc(void*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:384:10
[task 2022-09-06T22:05:58.785Z] 22:05:58     INFO - GECKO(3692) |     #20 0x7f5b27a83c0e in _pt_root /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:201:5
[task 2022-09-06T22:05:58.786Z] 22:05:58     INFO - GECKO(3692) |     #21 0x7f5b277b66da in start_thread /tmp/glibc/nptl/pthread_create.c:463

@jari FYI we're intermittently leaking

Flags: needinfo?(jjalkanen)

Looks like it's a runnable created in closing the connection from ~FileSystemDataManagerVersion001()

I suspect this is related to the fact that mozStorageConnection expects to be opened and closed on the same thread. In our case the connection is opened and closed using the same task queue, but the task queue is backed by a thread pool, so it can happen that the open and close operations occur on different threads.

In fact, there are many other leaks in the log.

Assignee: nobody → jjalkanen
Flags: needinfo?(jjalkanen)
Pushed by jjalkanen@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/37785ba4938c
Close FileSystemDatabaseManager manually. r=dom-storage-reviewers,janv
Status: NEW → RESOLVED
Closed: 4 months ago
Resolution: --- → FIXED
Target Milestone: --- → 107 Branch
You need to log in before you can comment on or make changes to this bug.