Closed Bug 1468397 Opened 6 years ago Closed 5 years ago

Intermittent LeakSanitizer | leak at PLDHashTable::ChangeTable, ShrinkIfAppropriate, PLDHashTable::RemoveEntry, RemoveEntry

Categories

(Core :: Audio/Video: Playback, defect, P5)

defect

Tracking

()

RESOLVED DUPLICATE of bug 1474443

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

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

Filed by: apavel [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=182939551&repo=autoland

https://queue.taskcluster.net/v1/task/XT3ZD8hhRY-Mqb4quJo5Ng/runs/0/artifacts/public/logs/live_backing.log

[task 2018-06-12T20:04:15.106Z] 20:04:15     INFO - TEST-START | dom/media/test/test_video_dimensions.html
[task 2018-06-12T20:04:16.476Z] 20:04:16     INFO - GECKO(1657) | MEMORY STAT | vsize 20973884MB | residentFast 1028MB
[task 2018-06-12T20:04:16.497Z] 20:04:16     INFO - TEST-OK | dom/media/test/test_video_dimensions.html | took 1392ms
[task 2018-06-12T20:04:16.574Z] 20:04:16     INFO - TEST-START | Shutdown
[task 2018-06-12T20:04:16.574Z] 20:04:16     INFO - Passed:  8112
[task 2018-06-12T20:04:16.574Z] 20:04:16     INFO - Failed:  0
[task 2018-06-12T20:04:16.576Z] 20:04:16     INFO - Todo:    46
[task 2018-06-12T20:04:16.577Z] 20:04:16     INFO - Mode:    e10s
[task 2018-06-12T20:04:16.578Z] 20:04:16     INFO - Slowest: 109259ms - /tests/dom/media/test/test_playback.html
[task 2018-06-12T20:04:16.593Z] 20:04:16     INFO - SimpleTest FINISHED
[task 2018-06-12T20:04:16.595Z] 20:04:16     INFO - TEST-INFO | Ran 1 Loops
[task 2018-06-12T20:04:16.597Z] 20:04:16     INFO - SimpleTest FINISHED
[task 2018-06-12T20:04:17.124Z] 20:04:17     INFO - GECKO(1657) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2018-06-12T20:04:17.148Z] 20:04:17     INFO - GECKO(1657) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2018-06-12T20:04:17.219Z] 20:04:17     INFO - GECKO(1657) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2018-06-12T20:04:17.220Z] 20:04:17     INFO - GECKO(1657) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2018-06-12T20:04:17.886Z] 20:04:17     INFO - GECKO(1657) | 1528833857878	Marionette	DEBUG	Received observer notification xpcom-will-shutdown
[task 2018-06-12T20:04:17.886Z] 20:04:17     INFO - GECKO(1657) | 1528833857879	Marionette	INFO	Stopped listening on port 2828
[task 2018-06-12T20:04:17.891Z] 20:04:17     INFO - GECKO(1657) | 1528833857880	Marionette	DEBUG	Remote service is inactive
[task 2018-06-12T20:04:18.659Z] 20:04:18     INFO - GECKO(1657) | -----------------------------------------------------
[task 2018-06-12T20:04:18.659Z] 20:04:18     INFO - GECKO(1657) | Suppressions used:
[task 2018-06-12T20:04:18.659Z] 20:04:18     INFO - GECKO(1657) |   count      bytes template
[task 2018-06-12T20:04:18.661Z] 20:04:18     INFO - GECKO(1657) |     637      20320 nsComponentManagerImpl
[task 2018-06-12T20:04:18.661Z] 20:04:18     INFO - GECKO(1657) |       3        624 mozJSComponentLoader::LoadModule
[task 2018-06-12T20:04:18.661Z] 20:04:18     INFO - GECKO(1657) |       2        288 libfontconfig.so
[task 2018-06-12T20:04:18.662Z] 20:04:18     INFO - GECKO(1657) | -----------------------------------------------------
[task 2018-06-12T20:04:19.874Z] 20:04:19     INFO - GECKO(1657) | =================================================================
[task 2018-06-12T20:04:19.875Z] 20:04:19    ERROR - GECKO(1657) | ==1706==ERROR: LeakSanitizer: detected memory leaks
[task 2018-06-12T20:04:19.877Z] 20:04:19     INFO - GECKO(1657) | Indirect leak of 20968 byte(s) in 1 object(s) allocated from:
[task 2018-06-12T20:04:19.878Z] 20:04:19     INFO - GECKO(1657) |     #0 0x4c1833 in malloc /builds/worker/workspace/moz-toolchain/src/llvm/projects/compiler-rt/lib/asan/asan_malloc_linux.cc:88:3
[task 2018-06-12T20:04:19.879Z] 20:04:19     INFO - GECKO(1657) |     #1 0x7fae2b739a78 in Malloc /builds/worker/workspace/build/src/obj-firefox/dist/include/nsTArray.h:197:46
[task 2018-06-12T20:04:19.880Z] 20:04:19     INFO - GECKO(1657) |     #2 0x7fae2b739a78 in nsTArrayFallibleAllocator::ResultTypeProxy nsTArray_base<nsTArrayFallibleAllocator, nsTArray_CopyWithMemutils>::EnsureCapacity<nsTArrayFallibleAllocator>(unsigned long, unsigned long) /builds/worker/workspace/build/src/obj-firefox/dist/include/nsTArray-inl.h:136
[task 2018-06-12T20:04:19.881Z] 20:04:19     INFO - GECKO(1657) |     #3 0x7fae32393bfe in SetCapacity<nsTArrayFallibleAllocator> /builds/worker/workspace/build/src/obj-firefox/dist/include/nsTArray.h:1883:47
[task 2018-06-12T20:04:19.882Z] 20:04:19     INFO - GECKO(1657) |     #4 0x7fae32393bfe in SetCapacity /builds/worker/workspace/build/src/obj-firefox/dist/include/nsTArray.h:1891
[task 2018-06-12T20:04:19.883Z] 20:04:19     INFO - GECKO(1657) |     #5 0x7fae32393bfe in mozilla::Index::Index(mozilla::IndiceWrapper const&, mozilla::ByteStream*, unsigned int, bool) /builds/worker/workspace/build/src/dom/media/mp4/Index.cpp:335
[task 2018-06-12T20:04:19.884Z] 20:04:19     INFO - GECKO(1657) |     #6 0x7fae32384417 in mozilla::MP4TrackDemuxer::MP4TrackDemuxer(mozilla::MP4Demuxer*, mozilla::UniquePtr<mozilla::TrackInfo, mozilla::DefaultDelete<mozilla::TrackInfo> >&&, mozilla::IndiceWrapper const&) /builds/worker/workspace/build/src/dom/media/mp4/MP4Demuxer.cpp:372:16
[task 2018-06-12T20:04:19.884Z] 20:04:19     INFO - GECKO(1657) |     #7 0x7fae3237f033 in mozilla::MP4Demuxer::Init() /builds/worker/workspace/build/src/dom/media/mp4/MP4Demuxer.cpp:238:13
[task 2018-06-12T20:04:19.885Z] 20:04:19     INFO - GECKO(1657) |     #8 0x7fae31bc17a1 in mozilla::detail::ProxyFunctionRunnable<mozilla::MediaFormatReader::DemuxerProxy::Init()::$_35, mozilla::MozPromise<mozilla::MediaResult, mozilla::MediaResult, true> >::Run() /builds/worker/workspace/build/src/dom/media/MediaFormatReader.cpp:1230:47
[task 2018-06-12T20:04:19.886Z] 20:04:19     INFO - GECKO(1657) |     #9 0x7fae2b7c0a4d in mozilla::TaskQueue::Runner::Run() /builds/worker/workspace/build/src/xpcom/threads/TaskQueue.cpp:243:12
[task 2018-06-12T20:04:19.887Z] 20:04:19     INFO - GECKO(1657) |     #10 0x7fae2b7f08b4 in nsThreadPool::Run() /builds/worker/workspace/build/src/xpcom/threads/nsThreadPool.cpp:231:14
[task 2018-06-12T20:04:19.888Z] 20:04:19     INFO - GECKO(1657) |     #11 0x7fae2b7f12dc in non-virtual thunk to nsThreadPool::Run() /builds/worker/workspace/build/src/xpcom/threads/nsThreadPool.cpp
[task 2018-06-12T20:04:19.890Z] 20:04:19     INFO - GECKO(1657) |     #12 0x7fae2b7d9c9a in nsThread::ProcessNextEvent(bool, bool*) /builds/worker/workspace/build/src/xpcom/threads/nsThread.cpp:1088:14
[task 2018-06-12T20:04:19.891Z] 20:04:19     INFO - GECKO(1657) |     #13 0x7fae2b7f5ef4 in NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/workspace/build/src/xpcom/threads/nsThreadUtils.cpp:519:10
[task 2018-06-12T20:04:19.892Z] 20:04:19     INFO - GECKO(1657) |     #14 0x7fae2c6d1c3c in mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate*) /builds/worker/workspace/build/src/ipc/glue/MessagePump.cpp:364:5
[task 2018-06-12T20:04:19.896Z] 20:04:19     INFO - GECKO(1657) |     #15 0x7fae2c625bfc in RunInternal /builds/worker/workspace/build/src/ipc/chromium/src/base/message_loop.cc:325:10
[task 2018-06-12T20:04:19.898Z] 20:04:19     INFO - GECKO(1657) |     #16 0x7fae2c625bfc in RunHandler /builds/worker/workspace/build/src/ipc/chromium/src/base/message_loop.cc:318
[task 2018-06-12T20:04:19.899Z] 20:04:19     INFO - GECKO(1657) |     #17 0x7fae2c625bfc in MessageLoop::Run() /builds/worker/workspace/build/src/ipc/chromium/src/base/message_loop.cc:298
[task 2018-06-12T20:04:19.900Z] 20:04:19     INFO - GECKO(1657) |     #18 0x7fae2b7d460b in nsThread::ThreadFunc(void*) /builds/worker/workspace/build/src/xpcom/threads/nsThread.cpp:423:11
[task 2018-06-12T20:04:19.901Z] 20:04:19     INFO - GECKO(1657) |     #19 0x7fae48bd6dc8 in _pt_root /builds/worker/workspace/build/src/nsprpub/pr/src/pthreads/ptthread.c:201:5
[task 2018-06-12T20:04:19.902Z] 20:04:19     INFO - GECKO(1657) |     #20 0x7fae4cf436b9 in start_thread (/lib/x86_64-linux-gnu/libpthread.so.0+0x76b9)
There have been 31 failures in the last 7 days, all of them on Linux x64 asan.

Failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=195876357&repo=autoland&lineNumber=2963

[task 2018-08-25T01:12:23.204Z] 01:12:23     INFO - GECKO(1744) | Suppressions used:
[task 2018-08-25T01:12:23.205Z] 01:12:23     INFO - GECKO(1744) |   count      bytes template
[task 2018-08-25T01:12:23.206Z] 01:12:23     INFO - GECKO(1744) |      10      42008 libc.so
[task 2018-08-25T01:12:23.207Z] 01:12:23     INFO - GECKO(1744) |     615      19632 nsComponentManagerImpl
[task 2018-08-25T01:12:23.208Z] 01:12:23     INFO - GECKO(1744) |       8       1664 mozJSComponentLoader::LoadModule
[task 2018-08-25T01:12:23.209Z] 01:12:23     INFO - GECKO(1744) |     611      17713 libfontconfig.so
[task 2018-08-25T01:12:23.210Z] 01:12:23     INFO - GECKO(1744) |       1         29 libglib-2.0.so
[task 2018-08-25T01:12:23.211Z] 01:12:23     INFO - GECKO(1744) | -----------------------------------------------------
[task 2018-08-25T01:12:23.212Z] 01:12:23     INFO - GECKO(1744) | SUMMARY: AddressSanitizer: 23527 byte(s) leaked in 20 allocation(s).
[task 2018-08-25T01:12:24.376Z] 01:12:24     INFO - GECKO(1744) | -----------------------------------------------------
[task 2018-08-25T01:12:24.377Z] 01:12:24     INFO - GECKO(1744) | Suppressions used:
[task 2018-08-25T01:12:24.378Z] 01:12:24     INFO - GECKO(1744) |   count      bytes template
[task 2018-08-25T01:12:24.379Z] 01:12:24     INFO - GECKO(1744) |     620      19800 nsComponentManagerImpl
[task 2018-08-25T01:12:24.379Z] 01:12:24     INFO - GECKO(1744) |      41       8528 mozJSComponentLoader::LoadModule
[task 2018-08-25T01:12:24.380Z] 01:12:24     INFO - GECKO(1744) |     611      17509 libfontconfig.so
[task 2018-08-25T01:12:24.382Z] 01:12:24     INFO - GECKO(1744) |       6        264 _PR_Getfd
[task 2018-08-25T01:12:24.383Z] 01:12:24     INFO - GECKO(1744) |       1         29 libglib-2.0.so
[task 2018-08-25T01:12:24.383Z] 01:12:24     INFO - GECKO(1744) | -----------------------------------------------------
[task 2018-08-25T01:12:24.570Z] 01:12:24     INFO - TEST-INFO | Main app process: exit 0
[task 2018-08-25T01:12:24.571Z] 01:12:24     INFO - TEST-INFO | LeakSanitizer | To show the addresses of leaked objects add report_objects=1 to LSAN_OPTIONS
[task 2018-08-25T01:12:24.572Z] 01:12:24     INFO - TEST-INFO | LeakSanitizer | This can be done in testing/mozbase/mozrunner/mozrunner/utils.py
[task 2018-08-25T01:12:24.572Z] 01:12:24    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at PLDHashTable::ChangeTable, ShrinkIfAppropriate, PLDHashTable::RemoveEntry, RemoveEntry
[task 2018-08-25T01:12:24.574Z] 01:12:24    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at Malloc, nsTArray_base, AppendElement, RegisterIterator
[task 2018-08-25T01:12:24.575Z] 01:12:24    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at Malloc, nsTArray_base, AppendElements, mozilla::MP4VideoInfo::Update
[task 2018-08-25T01:12:24.576Z] 01:12:24    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at CreateInner, Create, nsAtomTable::Atomize, mozilla::BasePrincipal::FinishInit
[task 2018-08-25T01:12:24.577Z] 01:12:24    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at Malloc, nsTArray_base, SetCapacity, SetCapacity
[task 2018-08-25T01:12:24.578Z] 01:12:24    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at CreateEventTargetWrapper, mozilla::SchedulerGroup::AbstractMainThreadForImpl, mozilla::dom::HTMLMediaElement::HTMLMediaElement, mozilla::dom::HTMLVideoElement::HTMLVideoElement
[task 2018-08-25T01:12:24.580Z] 01:12:24    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at mozilla::MP4TrackDemuxer::MP4TrackDemuxer, mozilla::MP4Demuxer::Init, mozilla::detail::ProxyFunctionRunnable, mozilla::TaskQueue::Runner::Run
[task 2018-08-25T01:12:24.581Z] 01:12:24    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at MakeUnique, mozilla::MediaResourceIndex::MediaResourceIndex, mozilla::ResourceStream::ResourceStream, mozilla::MP4TrackDemuxer::MP4TrackDemuxer
[task 2018-08-25T01:12:24.583Z] 01:12:24    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at MakeUnique, mozilla::MP4TrackDemuxer::MP4TrackDemuxer, mozilla::MP4Demuxer::Init, mozilla::detail::ProxyFunctionRunnable
[task 2018-08-25T01:12:24.584Z] 01:12:24    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at Realloc, nsTArray_base, AppendElement, AppendElement
[task 2018-08-25T01:12:24.584Z] 01:12:24    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at PLDHashTable::Add, PLDHashTable::Add, PutEntry, mozilla::MediaCacheStream::BlockList::AddFirstBlock
[task 2018-08-25T01:12:24.585Z] 01:12:24    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at mozilla::VideoInfo::VideoInfo, VideoInfo, VideoInfo, MP4VideoInfo
[task 2018-08-25T01:12:24.586Z] 01:12:24    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at mozilla::SchedulerGroup::CreateEventTargetFor, mozilla::SchedulerGroup::CreateEventTargets, mozilla::dom::TabGroup::TabGroup, mozilla::dom::nsIContentChild::GetConstructedEventTarget
[task 2018-08-25T01:12:24.587Z] 01:12:24    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at MakeUnique, mozilla::MP4Metadata::GetTrackInfo, mozilla::MP4Demuxer::Init, mozilla::detail::ProxyFunctionRunnable
[task 2018-08-25T01:12:24.588Z] 01:12:24    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at Realloc, nsTArray_base, AppendElement, mozilla::MediaCacheStream::InitAsCloneInternal
[task 2018-08-25T01:12:24.589Z] 01:12:24    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at mozilla::MP4Demuxer::Init, mozilla::detail::ProxyFunctionRunnable, mozilla::TaskQueue::Runner::Run, nsThreadPool::Run
[task 2018-08-25T01:12:24.590Z] 01:12:24    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at mozilla::BasePrincipal::CreateCodebasePrincipal, mozilla::BasePrincipal::CreateCodebasePrincipal, nsScriptSecurityManager::GetChannelURIPrincipal, nsScriptSecurityManager::GetChannelResultPrincipal
[task 2018-08-25T01:12:24.590Z] 01:12:24     INFO - runtests.py | Application ran for: 0:13:18.959740
Whiteboard: [stockwell needswork]
There have been 53 failures in the last week.
All the failures occur on linux64 / asan.

Recent relevat log file: https://treeherder.mozilla.org/logviewer.html#?job_id=197167143&repo=autoland&lineNumber=3079

Summary: Intermittent LeakSanitizer | leak at PLDHashTable::ChangeTable, ShrinkIfAppropriate, PLDHashTable::RemoveEntry, RemoveEntry

:drno would you please have a look?
Flags: needinfo?(drno)
Leak in the MP4 parser?
jya: since Gerald is not on the team any more who would be a good candidate to look at this?
Flags: needinfo?(drno) → needinfo?(jyavenard)
I don't see how that could leak seeing they are all from a nsTArray allocated on the stack of a MP4TrackDemuxer::Index::mIndex ; yet we see no leak of a MP4TrackDemuxer parent.
there's also plenty of other leaks occurring, not directly related to the MP4TrackDemuxer, looks like a task that got created was never dispatched and tracking of it got lost...
Flags: needinfo?(jyavenard)
Keywords: stalled
if someone could reproduce it in a rr recording, that would help
This bug has failed 55 times in the last 7 days. All failures seem to occur on linux64 asan.

Recent log:
https://treeherder.mozilla.org/logviewer.html#?job_id=201935672&repo=autoland

INFO - TEST-START | dom/media/test/test_video_dimensions.html
[task 2018-09-27T12:46:26.411Z] 12:46:26     INFO - GECKO(1763) | MEMORY STAT | vsize 20973774MB | residentFast 1038MB
[task 2018-09-27T12:46:26.433Z] 12:46:26     INFO - TEST-OK | dom/media/test/test_video_dimensions.html | took 1306ms
[task 2018-09-27T12:46:26.495Z] 12:46:26     INFO - TEST-START | Shutdown
[task 2018-09-27T12:46:26.496Z] 12:46:26     INFO - Passed:  9617
[task 2018-09-27T12:46:26.497Z] 12:46:26     INFO - Failed:  0
[task 2018-09-27T12:46:26.498Z] 12:46:26     INFO - Todo:    46
[task 2018-09-27T12:46:26.499Z] 12:46:26     INFO - Mode:    e10s
[task 2018-09-27T12:46:26.501Z] 12:46:26     INFO - Slowest: 127111ms - /tests/dom/media/test/test_playback.html
[task 2018-09-27T12:46:26.517Z] 12:46:26     INFO - SimpleTest FINISHED
[task 2018-09-27T12:46:26.519Z] 12:46:26     INFO - TEST-INFO | Ran 1 Loops
[task 2018-09-27T12:46:26.521Z] 12:46:26     INFO - SimpleTest FINISHED
[task 2018-09-27T12:46:26.982Z] 12:46:26     INFO - GECKO(1763) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2018-09-27T12:46:27.185Z] 12:46:27     INFO - GECKO(1763) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2018-09-27T12:46:27.185Z] 12:46:27     INFO - GECKO(1763) | ###!!! [Child][MessageChannel] Error: (msgtype=0x300115,name=PContent::Msg_StoreUserInteractionAsPermission) Closed channel: cannot send/recv
[task 2018-09-27T12:46:28.838Z] 12:46:28     INFO - GECKO(1763) | -----------------------------------------------------
[task 2018-09-27T12:46:28.838Z] 12:46:28     INFO - GECKO(1763) | Suppressions used:
[task 2018-09-27T12:46:28.838Z] 12:46:28     INFO - GECKO(1763) |   count      bytes template
[task 2018-09-27T12:46:28.838Z] 12:46:28     INFO - GECKO(1763) |     542      17304 nsComponentManagerImpl
[task 2018-09-27T12:46:28.839Z] 12:46:28     INFO - GECKO(1763) |       3        624 mozJSComponentLoader::LoadModule
[task 2018-09-27T12:46:28.839Z] 12:46:28     INFO - GECKO(1763) |     611      17713 libfontconfig.so
[task 2018-09-27T12:46:28.839Z] 12:46:28     INFO - GECKO(1763) |       1         29 libglib-2.0.so
[task 2018-09-27T12:46:28.839Z] 12:46:28     INFO - GECKO(1763) | -----------------------------------------------------
[task 2018-09-27T12:46:28.900Z] 12:46:28     INFO - GECKO(1763) | 1538052388892	Marionette	DEBUG	Received observer notification xpcom-will-shutdown
[task 2018-09-27T12:46:28.900Z] 12:46:28     INFO - GECKO(1763) | 1538052388893	Marionette	INFO	Stopped listening on port 2828
[task 2018-09-27T12:46:28.900Z] 12:46:28     INFO - GECKO(1763) | 1538052388894	Marionette	DEBUG	Remote service is inactive
[task 2018-09-27T12:46:29.023Z] 12:46:29     INFO - GECKO(1763) | -----------------------------------------------------
[task 2018-09-27T12:46:29.024Z] 12:46:29     INFO - GECKO(1763) | Suppressions used:
[task 2018-09-27T12:46:29.024Z] 12:46:29     INFO - GECKO(1763) |   count      bytes template
[task 2018-09-27T12:46:29.024Z] 12:46:29     INFO - GECKO(1763) |     542      17304 nsComponentManagerImpl
[task 2018-09-27T12:46:29.024Z] 12:46:29     INFO - GECKO(1763) |       3        624 mozJSComponentLoader::LoadModule
[task 2018-09-27T12:46:29.024Z] 12:46:29     INFO - GECKO(1763) |       2        288 libfontconfig.so
[task 2018-09-27T12:46:29.024Z] 12:46:29     INFO - GECKO(1763) | -----------------------------------------------------
[task 2018-09-27T12:46:30.840Z] 12:46:30     INFO - GECKO(1763) | =================================================================
[task 2018-09-27T12:46:30.840Z] 12:46:30    ERROR - GECKO(1763) | ==1836==ERROR: LeakSanitizer: detected memory leaks
[task 2018-09-27T12:46:30.842Z] 12:46:30     INFO - GECKO(1763) | Indirect leak of 20968 byte(s) in 1 object(s) allocated from:
[task 2018-09-27T12:46:30.842Z] 12:46:30     INFO - GECKO(1763) |     #0 0x5633b7fa0263 in malloc /builds/worker/workspace/moz-toolchain/src/llvm/projects/compiler-rt/lib/asan/asan_malloc_linux.cc:88:3
[task 2018-09-27T12:46:30.843Z] 12:46:30     INFO - GECKO(1763) |     #1 0x7f29f253e76d in Malloc /builds/worker/workspace/build/src/obj-firefox/dist/include/nsTArray.h:198:46
[task 2018-09-27T12:46:30.845Z] 12:46:30     INFO - GECKO(1763) |     #2 0x7f29f253e76d in nsTArrayFallibleAllocator::ResultTypeProxy nsTArray_base<nsTArrayFallibleAllocator, nsTArray_CopyWithMemutils>::EnsureCapacity<nsTArrayFallibleAllocator>(unsigned long, unsigned long) /builds/worker/workspace/build/src/obj-firefox/dist/include/nsTArray-inl.h:136
[task 2018-09-27T12:46:30.847Z] 12:46:30     INFO - GECKO(1763) |     #3 0x7f29f95dbcaf in SetCapacity<nsTArrayFallibleAllocator> /builds/worker/workspace/build/src/obj-firefox/dist/include/nsTArray.h:2145:47
[task 2018-09-27T12:46:30.848Z] 12:46:30     INFO - GECKO(1763) |     #4 0x7f29f95dbcaf in SetCapacity /builds/worker/workspace/build/src/obj-firefox/dist/include/nsTArray.h:2153
[task 2018-09-27T12:46:30.849Z] 12:46:30     INFO - GECKO(1763) |     #5 0x7f29f95dbcaf in mozilla::Index::Index(mozilla::IndiceWrapper const&, mozilla::ByteStream*, unsigned int, bool) /builds/worker/workspace/build/src/dom/media/mp4/Index.cpp:335
[task 2018-09-27T12:46:30.850Z] 12:46:30     INFO - GECKO(1763) |     #6 0x7f29f95cc437 in mozilla::MP4TrackDemuxer::MP4TrackDemuxer(mozilla::MP4Demuxer*, mozilla::UniquePtr<mozilla::TrackInfo, mozilla::DefaultDelete<mozilla::TrackInfo> >&&, mozilla::IndiceWrapper const&) /builds/worker/workspace/build/src/dom/media/mp4/MP4Demuxer.cpp:373:16
[task 2018-09-27T12:46:30.852Z] 12:46:30     INFO - GECKO(1763) |     #7 0x7f29f95c7183 in mozilla::MP4Demuxer::Init() /builds/worker/workspace/build/src/dom/media/mp4/MP4Demuxer.cpp:239:13
[task 2018-09-27T12:46:30.853Z] 12:46:30     INFO - GECKO(1763) |     #8 0x7f29f8e16311 in mozilla::detail::ProxyFunctionRunnable<mozilla::MediaFormatReader::DemuxerProxy::Init()::$_9, mozilla::MozPromise<mozilla::MediaResult, mozilla::MediaResult, true> >::Run() /builds/worker/workspace/build/src/dom/media/MediaFormatReader.cpp:1005:47
[task 2018-09-27T12:46:30.854Z] 12:46:30     INFO - GECKO(1763) |     #9 0x7f29f25c641c in mozilla::TaskQueue::Runner::Run() /builds/worker/workspace/build/src/xpcom/threads/TaskQueue.cpp:235:12
[task 2018-09-27T12:46:30.857Z] 12:46:30     INFO - GECKO(1763) |     #10 0x7f29f25ec0d2 in nsThreadPool::Run() /builds/worker/workspace/build/src/xpcom/threads/nsThreadPool.cpp:242:14
[task 2018-09-27T12:46:30.858Z] 12:46:30     INFO - GECKO(1763) |     #11 0x7f29f25ecb4c in non-virtual thunk to nsThreadPool::Run() /builds/worker/workspace/build/src/xpcom/threads/nsThreadPool.cpp
[task 2018-09-27T12:46:30.859Z] 12:46:30     INFO - GECKO(1763) |     #12 0x7f29f25e1e91 in nsThread::ProcessNextEvent(bool, bool*) /builds/worker/workspace/build/src/xpcom/threads/nsThread.cpp:1228:14
[task 2018-09-27T12:46:30.867Z] 12:46:30     INFO - GECKO(1763) |     #13 0x7f29f25e8ad8 in NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/workspace/build/src/xpcom/threads/nsThreadUtils.cpp:519:10
[task 2018-09-27T12:46:30.868Z] 12:46:30     INFO - GECKO(1763) |     #14 0x7f29f3538a60 in mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate*) /builds/worker/workspace/build/src/ipc/glue/MessagePump.cpp:334:20
[task 2018-09-27T12:46:30.870Z] 12:46:30     INFO - GECKO(1763) |     #15 0x7f29f348c61c in RunInternal /builds/worker/workspace/build/src/ipc/chromium/src/base/message_loop.cc:325:10
[task 2018-09-27T12:46:30.870Z] 12:46:30     INFO - GECKO(1763) |     #16 0x7f29f348c61c in RunHandler /builds/worker/workspace/build/src/ipc/chromium/src/base/message_loop.cc:318
[task 2018-09-27T12:46:30.871Z] 12:46:30     INFO - GECKO(1763) |     #17 0x7f29f348c61c in MessageLoop::Run() /builds/worker/workspace/build/src/ipc/chromium/src/base/message_loop.cc:298
[task 2018-09-27T12:46:30.872Z] 12:46:30     INFO - GECKO(1763) |     #18 0x7f29f25dc11a in nsThread::ThreadFunc(void*) /builds/worker/workspace/build/src/xpcom/threads/nsThread.cpp:502:11
[task 2018-09-27T12:46:30.873Z] 12:46:30     INFO - GECKO(1763) |     #19 0x7f2a0edd6008 in _pt_root /builds/worker/workspace/build/src/nsprpub/pr/src/pthreads/ptthread.c:201:5
[task 2018-09-27T12:46:30.879Z] 12:46:30     INFO - GECKO(1763) |     #20 0x7f2a122e86b9 in start_thread (/lib/x86_64-linux-gnu/libpthread.so.0+0x76b9)
[task 2018-09-27T12:46:30.880Z] 12:46:30     INFO - GECKO(1763) | Indirect leak of 13288 byte(s) in 1 object(s) allocated from:

drno: Can you please take a look at this bug?
Flags: needinfo?(drno)
Whiteboard: [stockwell disable-recommended] → [stockwell disable-recommended][stockwell needswork]
There are 34 total failures in the last 7 days. 

recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=204595760&repo=autoland&lineNumber=3051

[task 2018-10-10T20:24:51.343Z] 20:24:51     INFO - TEST-START | dom/media/test/test_video_dimensions.html
[task 2018-10-10T20:24:51.752Z] 20:24:51     INFO - GECKO(1739) | JavaScript error: http://mochi.test:8888/tests/dom/media/test/manifest.js, line 1634: AbortError: The fetching process for the media resource was aborted by the user agent at the user's request.
[task 2018-10-10T20:24:51.780Z] 20:24:51     INFO - GECKO(1739) | JavaScript error: http://mochi.test:8888/tests/dom/media/test/manifest.js, line 1634: AbortError: The fetching process for the media resource was aborted by the user agent at the user's request.
[task 2018-10-10T20:24:52.189Z] 20:24:52     INFO - GECKO(1739) | JavaScript error: http://mochi.test:8888/tests/dom/media/test/manifest.js, line 1634: AbortError: The fetching process for the media resource was aborted by the user agent at the user's request.
[task 2018-10-10T20:24:52.357Z] 20:24:52     INFO - GECKO(1739) | JavaScript error: http://mochi.test:8888/tests/dom/media/test/manifest.js, line 1634: AbortError: The fetching process for the media resource was aborted by the user agent at the user's request.
[task 2018-10-10T20:24:52.719Z] 20:24:52     INFO - GECKO(1739) | MEMORY STAT | vsize 20973755MB | residentFast 1039MB
[task 2018-10-10T20:24:52.736Z] 20:24:52     INFO - TEST-OK | dom/media/test/test_video_dimensions.html | took 1388ms
[task 2018-10-10T20:24:52.801Z] 20:24:52     INFO - TEST-START | Shutdown


[task 2018-10-10T20:24:57.507Z] 20:24:57    ERROR - GECKO(1739) | ==1834==ERROR: LeakSanitizer: detected memory leaks
[task 2018-10-10T20:24:57.508Z] 20:24:57     INFO - GECKO(1739) | Indirect leak of 13288 byte(s) in 1 object(s) allocated from:
[task 2018-10-10T20:24:57.509Z] 20:24:57     INFO - GECKO(1739) |     #0 0x56087dbf3263 in malloc /builds/worker/workspace/moz-toolchain/src/llvm/projects/compiler-rt/lib/asan/asan_malloc_linux.cc:88:3
[task 2018-10-10T20:24:57.510Z] 20:24:57     INFO - GECKO(1739) |     #1 0x7f9f7a08795d in Malloc /builds/worker/workspace/build/src/obj-firefox/dist/include/nsTArray.h:198:46
[task 2018-10-10T20:24:57.510Z] 20:24:57     INFO - GECKO(1739) |     #2 0x7f9f7a08795d in nsTArrayFallibleAllocator::ResultTypeProxy nsTArray_base<nsTArrayFallibleAllocator, nsTArray_CopyWithMemutils>::EnsureCapacity<nsTArrayFallibleAllocator>(unsigned long, unsigned long) /builds/worker/workspace/build/src/obj-firefox/dist/include/nsTArray-inl.h:153
[task 2018-10-10T20:24:57.512Z] 20:24:57     INFO - GECKO(1739) |     #3 0x7f9f8117db8f in SetCapacity<nsTArrayFallibleAllocator> /builds/worker/workspace/build/src/obj-firefox/dist/include/nsTArray.h:2157:47
[task 2018-10-10T20:24:57.513Z] 20:24:57     INFO - GECKO(1739) |     #4 0x7f9f8117db8f in SetCapacity /builds/worker/workspace/build/src/obj-firefox/dist/include/nsTArray.h:2165
[task 2018-10-10T20:24:57.513Z] 20:24:57     INFO - GECKO(1739) |     #5 0x7f9f8117db8f in mozilla::Index::Index(mozilla::IndiceWrapper const&, mozilla::ByteStream*, unsigned int, bool) /builds/worker/workspace/build/src/dom/media/mp4/Index.cpp:335
[task 2018-10-10T20:24:57.514Z] 20:24:57     INFO - GECKO(1739) |     #6 0x7f9f8116e187 in mozilla::MP4TrackDemuxer::MP4TrackDemuxer(mozilla::MP4Demuxer*, mozilla::UniquePtr<mozilla::TrackInfo, mozilla::DefaultDelete<mozilla::TrackInfo> >&&, mozilla::IndiceWrapper const&) /builds/worker/workspace/build/src/dom/media/mp4/MP4Demuxer.cpp:373:16
[task 2018-10-10T20:24:57.514Z] 20:24:57     INFO - GECKO(1739) |     #7 0x7f9f81169dc0 in mozilla::MP4Demuxer::Init() /builds/worker/workspace/build/src/dom/media/mp4/MP4Demuxer.cpp:275:13
[task 2018-10-10T20:24:57.516Z] 20:24:57     INFO - GECKO(1739) |     #8 0x7f9f80998671 in mozilla::detail::ProxyFunctionRunnable<mozilla::MediaFormatReader::DemuxerProxy::Init()::$_9, mozilla::MozPromise<mozilla::MediaResult, mozilla::MediaResult, true> >::Run() /builds/worker/workspace/build/src/dom/media/MediaFormatReader.cpp:1011:47
[task 2018-10-10T20:24:57.516Z] 20:24:57     INFO - GECKO(1739) |     #9 0x7f9f7a11025c in mozilla::TaskQueue::Runner::Run() /builds/worker/workspace/build/src/xpcom/threads/TaskQueue.cpp:235:12
[task 2018-10-10T20:24:57.516Z] 20:24:57     INFO - GECKO(1739) |     #10 0x7f9f7a136462 in nsThreadPool::Run() /builds/worker/workspace/build/src/xpcom/threads/nsThreadPool.cpp:242:14
[task 2018-10-10T20:24:57.517Z] 20:24:57     INFO - GECKO(1739) |     #11 0x7f9f7a136edc in non-virtual thunk to nsThreadPool::Run() /builds/worker/workspace/build/src/xpcom/threads/nsThreadPool.cpp
[task 2018-10-10T20:24:57.517Z] 20:24:57     INFO - GECKO(1739) |     #12 0x7f9f7a12c0a1 in nsThread::ProcessNextEvent(bool, bool*) /builds/worker/workspace/build/src/xpcom/threads/nsThread.cpp:1231:14
[task 2018-10-10T20:24:57.519Z] 20:24:57     INFO - GECKO(1739) |     #13 0x7f9f7a132e48 in NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/workspace/build/src/xpcom/threads/nsThreadUtils.cpp:530:10
[task 2018-10-10T20:24:57.519Z] 20:24:57     INFO - GECKO(1739) |     #14 0x7f9f7b08c0f0 in mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate*) /builds/worker/workspace/build/src/ipc/glue/MessagePump.cpp:334:20
[task 2018-10-10T20:24:57.521Z] 20:24:57     INFO - GECKO(1739) |     #15 0x7f9f7afdec0c in RunInternal /builds/worker/workspace/build/src/ipc/chromium/src/base/message_loop.cc:325:10
[task 2018-10-10T20:24:57.521Z] 20:24:57     INFO - GECKO(1739) |     #16 0x7f9f7afdec0c in RunHandler /builds/worker/workspace/build/src/ipc/chromium/src/base/message_loop.cc:318
[task 2018-10-10T20:24:57.522Z] 20:24:57     INFO - GECKO(1739) |     #17 0x7f9f7afdec0c in MessageLoop::Run() /builds/worker/workspace/build/src/ipc/chromium/src/base/message_loop.cc:298
[task 2018-10-10T20:24:57.522Z] 20:24:57     INFO - GECKO(1739) |     #18 0x7f9f7a12615a in nsThread::ThreadFunc(void*) /builds/worker/workspace/build/src/xpcom/threads/nsThread.cpp:502:11
[task 2018-10-10T20:24:57.522Z] 20:24:57     INFO - GECKO(1739) |     #19 0x7f9f96ad6008 in _pt_root /builds/worker/workspace/build/src/nsprpub/pr/src/pthreads/ptthread.c:201:5
[task 2018-10-10T20:24:57.522Z] 20:24:57     INFO - GECKO(1739) |     #20 0x7f9f9a0616b9 in start_thread (/lib/x86_64-linux-gnu/libpthread.so.0+0x76b9)

[task 2018-10-10T20:24:59.811Z] 20:24:59     INFO - TEST-INFO | Main app process: exit 0
[task 2018-10-10T20:24:59.812Z] 20:24:59     INFO - TEST-INFO | LeakSanitizer | To show the addresses of leaked objects add report_objects=1 to LSAN_OPTIONS
[task 2018-10-10T20:24:59.813Z] 20:24:59     INFO - TEST-INFO | LeakSanitizer | This can be done in testing/mozbase/mozrunner/mozrunner/utils.py
[task 2018-10-10T20:24:59.815Z] 20:24:59    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at PLDHashTable::ChangeTable, ShrinkIfAppropriate, PLDHashTable::RemoveEntry, RemoveEntry
[task 2018-10-10T20:24:59.817Z] 20:24:59    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at Malloc, nsTArray_base, AppendElement, RegisterIterator
[task 2018-10-10T20:24:59.818Z] 20:24:59    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at Malloc, nsTArray_base, AppendElements, mozilla::MP4VideoInfo::Update
[task 2018-10-10T20:24:59.821Z] 20:24:59    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at CreateInner, Create, nsAtomTable::Atomize, mozilla::BasePrincipal::FinishInit
[task 2018-10-10T20:24:59.822Z] 20:24:59    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at Malloc, nsTArray_base, SetCapacity, SetCapacity
[task 2018-10-10T20:24:59.823Z] 20:24:59    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at CreateEventTargetWrapper, mozilla::SchedulerGroup::AbstractMainThreadForImpl, mozilla::dom::HTMLMediaElement::HTMLMediaElement, mozilla::dom::HTMLVideoElement::HTMLVideoElement
[task 2018-10-10T20:24:59.824Z] 20:24:59    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at mozilla::MP4TrackDemuxer::MP4TrackDemuxer, mozilla::MP4Demuxer::Init, mozilla::detail::ProxyFunctionRunnable, mozilla::TaskQueue::Runner::Run
[task 2018-10-10T20:24:59.825Z] 20:24:59    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at MakeUnique, mozilla::MediaResourceIndex::MediaResourceIndex, mozilla::ResourceStream::ResourceStream, mozilla::MP4TrackDemuxer::MP4TrackDemuxer
[task 2018-10-10T20:24:59.826Z] 20:24:59    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at MakeUnique, mozilla::MP4TrackDemuxer::MP4TrackDemuxer, mozilla::MP4Demuxer::Init, mozilla::detail::ProxyFunctionRunnable
[task 2018-10-10T20:24:59.827Z] 20:24:59    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at Realloc, nsTArray_base, AppendElement, AppendElement
[task 2018-10-10T20:24:59.829Z] 20:24:59    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at PLDHashTable::Add, PLDHashTable::Add, PutEntry, mozilla::MediaCacheStream::BlockList::AddFirstBlock
[task 2018-10-10T20:24:59.830Z] 20:24:59    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at mozilla::VideoInfo::VideoInfo, VideoInfo, VideoInfo, MP4VideoInfo
[task 2018-10-10T20:24:59.831Z] 20:24:59    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at mozilla::SchedulerGroup::CreateEventTargetFor, mozilla::SchedulerGroup::CreateEventTargets, mozilla::dom::TabGroup::TabGroup, mozilla::dom::nsIContentChild::GetConstructedEventTarget
[task 2018-10-10T20:24:59.832Z] 20:24:59    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at MakeUnique, mozilla::MP4Metadata::GetTrackInfo, mozilla::MP4Demuxer::Init, mozilla::detail::ProxyFunctionRunnable
[task 2018-10-10T20:24:59.834Z] 20:24:59    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at Realloc, nsTArray_base, AppendElement, mozilla::MediaCacheStream::InitAsCloneInternal
[task 2018-10-10T20:24:59.835Z] 20:24:59    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at mozilla::MP4Demuxer::Init, mozilla::detail::ProxyFunctionRunnable, mozilla::TaskQueue::Runner::Run, nsThreadPool::Run
[task 2018-10-10T20:24:59.836Z] 20:24:59    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at mozilla::BasePrincipal::CreateCodebasePrincipal, mozilla::BasePrincipal::CreateCodebasePrincipal, nsScriptSecurityManager::GetChannelURIPrincipal, nsScriptSecurityManager::GetChannelResultPrincipal
[task 2018-10-10T20:24:59.840Z] 20:24:59     INFO - runtests.py | Application ran for: 0:14:02.917467
[task 2018-10-10T20:24:59.841Z] 20:24:59     INFO - zombiecheck | Reading PID log: /tmp/tmpt3QHx1pidlog
Nils, Jean-Yves is someone working on this or can you point to someone who can work on this?
Flags: needinfo?(drno) → needinfo?(jyavenard)
Flags: needinfo?(drno)
this to me seems like an IPC issue.
We always see errors about MessageChannel not sending a channel, after the test itself succeeded.

All the objects leaking are to be destroyed once the shutdown message come through, it seems to never come.

I'm going to re-assign components here.
Component: Audio/Video: Playback → IPC
Flags: needinfo?(jyavenard)
(In reply to Jean-Yves Avenard [:jya] from comment #30)
> All the objects leaking are to be destroyed once the shutdown message come
> through, it seems to never come.

What actor and message is this?  Are the ActorDestroy and Dealloc* methods not being called during shutdown?
Flags: needinfo?(jyavenard)
There are 61 failures in the lat 30 days and 1 failure in the last 7 days.
Whiteboard: [stockwell disable-recommended][stockwell needswork] → [stockwell needswork]
(In reply to Jed Davis [:jld] ⟨⏰|UTC-7⟩ ⟦he/him⟧ from comment #31)
> (In reply to Jean-Yves Avenard [:jya] from comment #30)
> > All the objects leaking are to be destroyed once the shutdown message come
> > through, it seems to never come.
> 
> What actor and message is this?  Are the ActorDestroy and Dealloc* methods
> not being called during shutdown?

tbh, I don't know. But from the symptoms it what it appears to be..
Flags: needinfo?(jyavenard)
should be fixed by bug 1474443
Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → DUPLICATE
Component: IPC → Audio/Video: Playback
Flags: needinfo?(drno)
You need to log in before you can comment on or make changes to this bug.