Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=341572369&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/VAsOw0TYQmCOjuQrql0GTw/runs/0/artifacts/public/logs/live_backing.log
[task 2021-06-02T22:26:07.341Z] 22:26:07 INFO - TEST-OK | toolkit/components/reader/test/browser_readerMode_remoteType.js | took 4825ms
[task 2021-06-02T22:26:07.438Z] 22:26:07 INFO - checking window state
[task 2021-06-02T22:26:07.672Z] 22:26:07 INFO - TEST-START | toolkit/components/reader/test/browser_readerMode_with_anchor.js
[task 2021-06-02T22:26:08.225Z] 22:26:08 INFO - GECKO(13643) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-06-02T22:26:08.227Z] 22:26:08 INFO - GECKO(13643) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-06-02T22:26:09.469Z] 22:26:09 INFO - GECKO(13643) | -----------------------------------------------------
[task 2021-06-02T22:26:09.469Z] 22:26:09 INFO - GECKO(13643) | Suppressions used:
[task 2021-06-02T22:26:09.469Z] 22:26:09 INFO - GECKO(13643) | count bytes template
[task 2021-06-02T22:26:09.469Z] 22:26:09 INFO - GECKO(13643) | 14 448 nsComponentManagerImpl
[task 2021-06-02T22:26:09.469Z] 22:26:09 INFO - GECKO(13643) | 2 288 libfontconfig.so
[task 2021-06-02T22:26:09.469Z] 22:26:09 INFO - GECKO(13643) | -----------------------------------------------------
[task 2021-06-02T22:26:10.013Z] 22:26:10 INFO - GECKO(13643) | =================================================================
[task 2021-06-02T22:26:10.015Z] 22:26:10 ERROR - GECKO(13643) | ==14612==ERROR: LeakSanitizer: detected memory leaks
[task 2021-06-02T22:26:10.016Z] 22:26:10 INFO - GECKO(13643) | Indirect leak of 80 byte(s) in 1 object(s) allocated from:
[task 2021-06-02T22:26:10.018Z] 22:26:10 INFO - GECKO(13643) | #0 0x55952fd217ad in malloc /builds/worker/fetches/llvm-project/llvm/projects/compiler-rt/lib/asan/asan_malloc_linux.cpp:145:3
[task 2021-06-02T22:26:10.020Z] 22:26:10 INFO - GECKO(13643) | #1 0x55952fd5bacd in moz_xmalloc /builds/worker/checkouts/gecko/memory/mozalloc/mozalloc.cpp:52:15
[task 2021-06-02T22:26:10.024Z] 22:26:10 INFO - GECKO(13643) | #2 0x7fe62e39ebe5 in operator new /builds/worker/workspace/obj-build/dist/include/mozilla/cxxalloc.h:33:10
[task 2021-06-02T22:26:10.026Z] 22:26:10 INFO - GECKO(13643) | #3 0x7fe62e39ebe5 in mozilla::dom::WeakWorkerRef::Create(mozilla::dom::WorkerPrivate*, std::function<void ()>&&) /builds/worker/checkouts/gecko/dom/workers/WorkerRef.cpp:110:31
[task 2021-06-02T22:26:10.027Z] 22:26:10 INFO - GECKO(13643) | #4 0x7fe62e6371f2 in mozilla::dom::PerformanceStorageWorker::Create(mozilla::dom::WorkerPrivate*) /builds/worker/checkouts/gecko/dom/performance/PerformanceStorageWorker.cpp:76:25
[task 2021-06-02T22:26:10.029Z] 22:26:10 INFO - GECKO(13643) | #5 0x7fe62e38c6ad in mozilla::dom::WorkerPrivate::EnsurePerformanceStorage() /builds/worker/checkouts/gecko/dom/workers/WorkerPrivate.cpp:3248:27
[task 2021-06-02T22:26:10.030Z] 22:26:10 INFO - GECKO(13643) | #6 0x7fe62e351b82 in mozilla::dom::workerinternals::(anonymous namespace)::WorkerThreadPrimaryRunnable::Run() /builds/worker/checkouts/gecko/dom/workers/RuntimeService.cpp:2197:21
[task 2021-06-02T22:26:10.035Z] 22:26:10 INFO - GECKO(13643) | #7 0x7fe627a84f7a in nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1153:16
[task 2021-06-02T22:26:10.036Z] 22:26:10 INFO - GECKO(13643) | #8 0x7fe627a8f48c in NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:548:10
[task 2021-06-02T22:26:10.038Z] 22:26:10 INFO - GECKO(13643) | #9 0x7fe628b1e438 in mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate*) /builds/worker/checkouts/gecko/ipc/glue/MessagePump.cpp:300:20
[task 2021-06-02T22:26:10.039Z] 22:26:10 INFO - GECKO(13643) | #10 0x7fe628a47901 in RunInternal /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:335:10
[task 2021-06-02T22:26:10.041Z] 22:26:10 INFO - GECKO(13643) | #11 0x7fe628a47901 in RunHandler /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:328:3
[task 2021-06-02T22:26:10.042Z] 22:26:10 INFO - GECKO(13643) | #12 0x7fe628a47901 in MessageLoop::Run() /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:310:3
[task 2021-06-02T22:26:10.044Z] 22:26:10 INFO - GECKO(13643) | #13 0x7fe627a7ea42 in nsThread::ThreadFunc(void*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:395:10
[task 2021-06-02T22:26:10.047Z] 22:26:10 INFO - GECKO(13643) | #14 0x7fe64938645e in _pt_root /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:201:5
[task 2021-06-02T22:26:10.051Z] 22:26:10 INFO - GECKO(13643) | #15 0x7fe64d0446da in start_thread /build/glibc-2ORdQG/glibc-2.27/nptl/pthread_create.c:463
[task 2021-06-02T22:26:10.053Z] 22:26:10 INFO - GECKO(13643) | Indirect leak of 64 byte(s) in 1 object(s) allocated from:
[task 2021-06-02T22:26:10.056Z] 22:26:10 INFO - GECKO(13643) | #0 0x55952fd217ad in malloc /builds/worker/fetches/llvm-project/llvm/projects/compiler-rt/lib/asan/asan_malloc_linux.cpp:145:3
[task 2021-06-02T22:26:10.059Z] 22:26:10 INFO - GECKO(13643) | #1 0x55952fd5bacd in moz_xmalloc /builds/worker/checkouts/gecko/memory/mozalloc/mozalloc.cpp:52:15
[task 2021-06-02T22:26:10.060Z] 22:26:10 INFO - GECKO(13643) | #2 0x7fe62e6370ac in operator new /builds/worker/workspace/obj-build/dist/include/mozilla/cxxalloc.h:33:10
[task 2021-06-02T22:26:10.066Z] 22:26:10 INFO - GECKO(13643) | #3 0x7fe62e6370ac in mozilla::dom::PerformanceStorageWorker::Create(mozilla::dom::WorkerPrivate*) /builds/worker/checkouts/gecko/dom/performance/PerformanceStorageWorker.cpp:74:46
[task 2021-06-02T22:26:10.067Z] 22:26:10 INFO - GECKO(13643) | #4 0x7fe62e38c6ad in mozilla::dom::WorkerPrivate::EnsurePerformanceStorage() /builds/worker/checkouts/gecko/dom/workers/WorkerPrivate.cpp:3248:27
[task 2021-06-02T22:26:10.072Z] 22:26:10 INFO - GECKO(13643) | #5 0x7fe62e351b82 in mozilla::dom::workerinternals::(anonymous namespace)::WorkerThreadPrimaryRunnable::Run() /builds/worker/checkouts/gecko/dom/workers/RuntimeService.cpp:2197:21
[task 2021-06-02T22:26:10.077Z] 22:26:10 INFO - GECKO(13643) | #6 0x7fe627a84f7a in nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1153:16
[task 2021-06-02T22:26:10.078Z] 22:26:10 INFO - GECKO(13643) | #7 0x7fe627a8f48c in NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:548:10
[task 2021-06-02T22:26:10.079Z] 22:26:10 INFO - GECKO(13643) | #8 0x7fe628b1e438 in mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate*) /builds/worker/checkouts/gecko/ipc/glue/MessagePump.cpp:300:20
[task 2021-06-02T22:26:10.082Z] 22:26:10 INFO - GECKO(13643) | #9 0x7fe628a47901 in RunInternal /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:335:10
[task 2021-06-02T22:26:10.085Z] 22:26:10 INFO - GECKO(13643) | #10 0x7fe628a47901 in RunHandler /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:328:3
[task 2021-06-02T22:26:10.087Z] 22:26:10 INFO - GECKO(13643) | #11 0x7fe628a47901 in MessageLoop::Run() /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:310:3
[task 2021-06-02T22:26:10.107Z] 22:26:10 INFO - GECKO(13643) | #12 0x7fe627a7ea42 in nsThread::ThreadFunc(void*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:395:10
[task 2021-06-02T22:26:10.108Z] 22:26:10 INFO - GECKO(13643) | #13 0x7fe64938645e in _pt_root /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:201:5
[task 2021-06-02T22:26:10.109Z] 22:26:10 INFO - GECKO(13643) | #14 0x7fe64d0446da in start_thread /build/glibc-2ORdQG/glibc-2.27/nptl/pthread_create.c:463
[task 2021-06-02T22:26:10.112Z] 22:26:10 INFO - GECKO(13643) | Indirect leak of 8 byte(s) in 1 object(s) allocated from:
[task 2021-06-02T22:26:10.118Z] 22:26:10 INFO - GECKO(13643) | #0 0x55952fd217ad in malloc /builds/worker/fetches/llvm-project/llvm/projects/compiler-rt/lib/asan/asan_malloc_linux.cpp:145:3
[task 2021-06-02T22:26:10.122Z] 22:26:10 INFO - GECKO(13643) | #1 0x55952fd5bacd in moz_xmalloc /builds/worker/checkouts/gecko/memory/mozalloc/mozalloc.cpp:52:15
[task 2021-06-02T22:26:10.123Z] 22:26:10 INFO - GECKO(13643) | #2 0x7fe62e637168 in operator new /builds/worker/workspace/obj-build/dist/include/mozilla/cxxalloc.h:33:10
[task 2021-06-02T22:26:10.123Z] 22:26:10 INFO - GECKO(13643) | #3 0x7fe62e637168 in _M_init_functor /builds/worker/fetches/clang/bin/../lib/gcc/x86_64-unknown-linux-gnu/7.5.0/../../../../include/c++/7.5.0/bits/std_function.h:268:39
[task 2021-06-02T22:26:10.125Z] 22:26:10 INFO - GECKO(13643) | #4 0x7fe62e637168 in _M_init_functor /builds/worker/fetches/clang/bin/../lib/gcc/x86_64-unknown-linux-gnu/7.5.0/../../../../include/c++/7.5.0/bits/std_function.h:239:4
[task 2021-06-02T22:26:10.130Z] 22:26:10 INFO - GECKO(13643) | #5 0x7fe62e637168 in function<(lambda at /builds/worker/checkouts/gecko/dom/performance/PerformanceStorageWorker.cpp:77:23), void, void> /builds/worker/fetches/clang/bin/../lib/gcc/x86_64-unknown-linux-gnu/7.5.0/../../../../include/c++/7.5.0/bits/std_function.h:693:6
[task 2021-06-02T22:26:10.131Z] 22:26:10 INFO - GECKO(13643) | #6 0x7fe62e637168 in mozilla::dom::PerformanceStorageWorker::Create(mozilla::dom::WorkerPrivate*) /builds/worker/checkouts/gecko/dom/performance/PerformanceStorageWorker.cpp:77:23
[task 2021-06-02T22:26:10.132Z] 22:26:10 INFO - GECKO(13643) | #7 0x7fe62e38c6ad in mozilla::dom::WorkerPrivate::EnsurePerformanceStorage() /builds/worker/checkouts/gecko/dom/workers/WorkerPrivate.cpp:3248:27
[task 2021-06-02T22:26:10.142Z] 22:26:10 INFO - GECKO(13643) | #8 0x7fe62e351b82 in mozilla::dom::workerinternals::(anonymous namespace)::WorkerThreadPrimaryRunnable::Run() /builds/worker/checkouts/gecko/dom/workers/RuntimeService.cpp:2197:21
[task 2021-06-02T22:26:10.143Z] 22:26:10 INFO - GECKO(13643) | #9 0x7fe627a84f7a in nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1153:16
[task 2021-06-02T22:26:10.146Z] 22:26:10 INFO - GECKO(13643) | #10 0x7fe627a8f48c in NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:548:10
[task 2021-06-02T22:26:10.147Z] 22:26:10 INFO - GECKO(13643) | #11 0x7fe628b1e438 in mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate*) /builds/worker/checkouts/gecko/ipc/glue/MessagePump.cpp:300:20
[task 2021-06-02T22:26:10.147Z] 22:26:10 INFO - GECKO(13643) | #12 0x7fe628a47901 in RunInternal /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:335:10
[task 2021-06-02T22:26:10.148Z] 22:26:10 INFO - GECKO(13643) | #13 0x7fe628a47901 in RunHandler /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:328:3
[task 2021-06-02T22:26:10.149Z] 22:26:10 INFO - GECKO(13643) | #14 0x7fe628a47901 in MessageLoop::Run() /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:310:3
[task 2021-06-02T22:26:10.150Z] 22:26:10 INFO - GECKO(13643) | #15 0x7fe627a7ea42 in nsThread::ThreadFunc(void*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:395:10
[task 2021-06-02T22:26:10.151Z] 22:26:10 INFO - GECKO(13643) | #16 0x7fe64938645e in _pt_root /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:201:5
[task 2021-06-02T22:26:10.154Z] 22:26:10 INFO - GECKO(13643) | #17 0x7fe64d0446da in start_thread /build/glibc-2ORdQG/glibc-2.27/nptl/pthread_create.c:463
[task 2021-06-02T22:26:10.158Z] 22:26:10 INFO - GECKO(13643) | -----------------------------------------------------
[task 2021-06-02T22:26:10.162Z] 22:26:10 INFO - GECKO(13643) | Suppressions used:
[task 2021-06-02T22:26:10.163Z] 22:26:10 INFO - GECKO(13643) | count bytes template
[task 2021-06-02T22:26:10.166Z] 22:26:10 INFO - GECKO(13643) | 14 448 nsComponentManagerImpl
[task 2021-06-02T22:26:10.167Z] 22:26:10 INFO - GECKO(13643) | 2 288 libfontconfig.so
[task 2021-06-02T22:26:10.168Z] 22:26:10 INFO - GECKO(13643) | -----------------------------------------------------
[task 2021-06-02T22:26:10.169Z] 22:26:10 INFO - GECKO(13643) | SUMMARY: AddressSanitizer: 152 byte(s) leaked in 3 allocation(s).
[task 2021-06-02T22:26:10.620Z] 22:26:10 INFO - GECKO(13643) | -----------------------------------------------------
[task 2021-06-02T22:26:10.620Z] 22:26:10 INFO - GECKO(13643) | Suppressions used:
[task 2021-06-02T22:26:10.621Z] 22:26:10 INFO - GECKO(13643) | count bytes template
[task 2021-06-02T22:26:10.621Z] 22:26:10 INFO - GECKO(13643) | 14 448 nsComponentManagerImpl
[task 2021-06-02T22:26:10.621Z] 22:26:10 INFO - GECKO(13643) | 2 288 libfontconfig.so
[task 2021-06-02T22:26:10.621Z] 22:26:10 INFO - GECKO(13643) | -----------------------------------------------------
[task 2021-06-02T22:26:10.903Z] 22:26:10 INFO - GECKO(13643) | -----------------------------------------------------
[task 2021-06-02T22:26:10.904Z] 22:26:10 INFO - GECKO(13643) | Suppressions used:
[task 2021-06-02T22:26:10.905Z] 22:26:10 INFO - GECKO(13643) | count bytes template
[task 2021-06-02T22:26:10.906Z] 22:26:10 INFO - GECKO(13643) | 14 448 nsComponentManagerImpl
[task 2021-06-02T22:26:10.907Z] 22:26:10 INFO - GECKO(13643) | 2 288 libfontconfig.so
[task 2021-06-02T22:26:10.908Z] 22:26:10 INFO - GECKO(13643) | -----------------------------------------------------
[task 2021-06-02T22:26:12.520Z] 22:26:12 INFO - GECKO(13643) | -----------------------------------------------------
[task 2021-06-02T22:26:12.521Z] 22:26:12 INFO - GECKO(13643) | Suppressions used:
[task 2021-06-02T22:26:12.522Z] 22:26:12 INFO - GECKO(13643) | count bytes template
[task 2021-06-02T22:26:12.523Z] 22:26:12 INFO - GECKO(13643) | 14 448 nsComponentManagerImpl
[task 2021-06-02T22:26:12.524Z] 22:26:12 INFO - GECKO(13643) | 2 288 libfontconfig.so
[task 2021-06-02T22:26:12.525Z] 22:26:12 INFO - GECKO(13643) | -----------------------------------------------------
[task 2021-06-02T22:26:12.666Z] 22:26:12 INFO - GECKO(13643) | JavaScript error: resource://gre/modules/AboutReader.jsm, line 377: TypeError: can't access property "value", target.attributes.getNamedItem(...) is null
[task 2021-06-02T22:26:12.798Z] 22:26:12 INFO - GECKO(13643) | MEMORY STAT | vsize 20975188MB | residentFast 1089MB
[task 2021-06-02T22:26:12.803Z] 22:26:12 INFO - TEST-OK | toolkit/components/reader/test/browser_readerMode_with_anchor.js | took 5132ms
[task 2021-06-02T22:26:12.859Z] 22:26:12 INFO - checking window state
[task 2021-06-02T22:26:13.467Z] 22:26:13 INFO - GECKO(13643) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-06-02T22:26:13.486Z] 22:26:13 INFO - GECKO(13643) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-06-02T22:26:13.504Z] 22:26:13 INFO - GECKO(13643) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-06-02T22:26:13.505Z] 22:26:13 INFO - GECKO(13643) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-06-02T22:26:13.602Z] 22:26:13 INFO - GECKO(13643) | ###!!! [Child][MessageChannel] Error: (msgtype=0x6B0018,name=PNecko::Msg_CancelHTMLDNSPrefetch) Channel closing: too late to send/recv, messages will be lost
[task 2021-06-02T22:26:13.820Z] 22:26:13 INFO - GECKO(13643) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-06-02T22:26:14.571Z] 22:26:14 INFO - GECKO(13643) | Completed ShutdownLeaks collections in process 13805
[task 2021-06-02T22:26:14.607Z] 22:26:14 INFO - GECKO(13643) | Completed ShutdownLeaks collections in process 13802
[task 2021-06-02T22:26:14.626Z] 22:26:14 INFO - GECKO(13643) | Completed ShutdownLeaks collections in process 13709
[task 2021-06-02T22:26:14.848Z] 22:26:14 INFO - GECKO(13643) | Completed ShutdownLeaks collections in process 13730
[task 2021-06-02T22:26:15.807Z] 22:26:15 INFO - GECKO(13643) | -----------------------------------------------------
[task 2021-06-02T22:26:15.808Z] 22:26:15 INFO - GECKO(13643) | Suppressions used:
[task 2021-06-02T22:26:15.808Z] 22:26:15 INFO - GECKO(13643) | count bytes template
[task 2021-06-02T22:26:15.808Z] 22:26:15 INFO - GECKO(13643) | 14 448 nsComponentManagerImpl
[task 2021-06-02T22:26:15.808Z] 22:26:15 INFO - GECKO(13643) | 2 288 libfontconfig.so
[task 2021-06-02T22:26:15.808Z] 22:26:15 INFO - GECKO(13643) | -----------------------------------------------------
[task 2021-06-02T22:26:15.922Z] 22:26:15 INFO - GECKO(13643) | -----------------------------------------------------
[task 2021-06-02T22:26:15.922Z] 22:26:15 INFO - GECKO(13643) | Suppressions used:
[task 2021-06-02T22:26:15.922Z] 22:26:15 INFO - GECKO(13643) | count bytes template
[task 2021-06-02T22:26:15.923Z] 22:26:15 INFO - GECKO(13643) | 14 448 nsComponentManagerImpl
[task 2021-06-02T22:26:15.923Z] 22:26:15 INFO - GECKO(13643) | 2 288 libfontconfig.so
[task 2021-06-02T22:26:15.923Z] 22:26:15 INFO - GECKO(13643) | -----------------------------------------------------
[task 2021-06-02T22:26:16.005Z] 22:26:16 INFO - GECKO(13643) | -----------------------------------------------------
[task 2021-06-02T22:26:16.005Z] 22:26:16 INFO - GECKO(13643) | Suppressions used:
[task 2021-06-02T22:26:16.005Z] 22:26:16 INFO - GECKO(13643) | count bytes template
[task 2021-06-02T22:26:16.005Z] 22:26:16 INFO - GECKO(13643) | 14 448 nsComponentManagerImpl
[task 2021-06-02T22:26:16.005Z] 22:26:16 INFO - GECKO(13643) | 2 288 libfontconfig.so
[task 2021-06-02T22:26:16.005Z] 22:26:16 INFO - GECKO(13643) | -----------------------------------------------------
[task 2021-06-02T22:26:16.181Z] 22:26:16 INFO - GECKO(13643) | -----------------------------------------------------
[task 2021-06-02T22:26:16.182Z] 22:26:16 INFO - GECKO(13643) | Suppressions used:
[task 2021-06-02T22:26:16.183Z] 22:26:16 INFO - GECKO(13643) | count bytes template
[task 2021-06-02T22:26:16.184Z] 22:26:16 INFO - GECKO(13643) | 14 448 nsComponentManagerImpl
[task 2021-06-02T22:26:16.185Z] 22:26:16 INFO - GECKO(13643) | 2 288 libfontconfig.so
[task 2021-06-02T22:26:16.186Z] 22:26:16 INFO - GECKO(13643) | -----------------------------------------------------
[task 2021-06-02T22:26:16.334Z] 22:26:16 INFO - GECKO(13643) | Completed ShutdownLeaks collections in process 13643
[task 2021-06-02T22:26:16.335Z] 22:26:16 INFO - TEST-START | Shutdown
[task 2021-06-02T22:26:16.336Z] 22:26:16 INFO - Browser Chrome Test Summary
[task 2021-06-02T22:26:16.337Z] 22:26:16 INFO - Passed: 97
[task 2021-06-02T22:26:16.337Z] 22:26:16 INFO - Failed: 0
[task 2021-06-02T22:26:16.338Z] 22:26:16 INFO - Todo: 0
[task 2021-06-02T22:26:16.339Z] 22:26:16 INFO - Mode: e10s
[task 2021-06-02T22:26:16.339Z] 22:26:16 INFO - *** End BrowserChrome Test Results ***
[task 2021-06-02T22:26:16.605Z] 22:26:16 INFO - GECKO(13643) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-06-02T22:26:16.609Z] 22:26:16 INFO - GECKO(13643) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-06-02T22:26:16.610Z] 22:26:16 INFO - GECKO(13643) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-06-02T22:26:16.790Z] 22:26:16 INFO - GECKO(13643) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-06-02T22:26:16.791Z] 22:26:16 INFO - GECKO(13643) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-06-02T22:26:16.806Z] 22:26:16 INFO - GECKO(13643) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-06-02T22:26:16.808Z] 22:26:16 INFO - GECKO(13643) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-06-02T22:26:17.870Z] 22:26:17 INFO - GECKO(13643) | 1622672777869 Marionette TRACE Received observer notification xpcom-will-shutdown
[task 2021-06-02T22:26:17.871Z] 22:26:17 INFO - GECKO(13643) | 1622672777869 Marionette INFO Stopped listening on port 2828
[task 2021-06-02T22:26:17.872Z] 22:26:17 INFO - GECKO(13643) | 1622672777869 Marionette DEBUG Marionette stopped listening
[task 2021-06-02T22:26:19.437Z] 22:26:19 INFO - GECKO(13643) | -----------------------------------------------------
[task 2021-06-02T22:26:19.437Z] 22:26:19 INFO - GECKO(13643) | Suppressions used:
[task 2021-06-02T22:26:19.437Z] 22:26:19 INFO - GECKO(13643) | count bytes template
[task 2021-06-02T22:26:19.437Z] 22:26:19 INFO - GECKO(13643) | 14 448 nsComponentManagerImpl
[task 2021-06-02T22:26:19.437Z] 22:26:19 INFO - GECKO(13643) | 2 288 libfontconfig.so
[task 2021-06-02T22:26:19.437Z] 22:26:19 INFO - GECKO(13643) | -----------------------------------------------------
[task 2021-06-02T22:26:19.444Z] 22:26:19 INFO - GECKO(13643) | -----------------------------------------------------
[task 2021-06-02T22:26:19.444Z] 22:26:19 INFO - GECKO(13643) | Suppressions used:
[task 2021-06-02T22:26:19.445Z] 22:26:19 INFO - GECKO(13643) | count bytes template
[task 2021-06-02T22:26:19.445Z] 22:26:19 INFO - GECKO(13643) | 14 448 nsComponentManagerImpl
[task 2021-06-02T22:26:19.446Z] 22:26:19 INFO - GECKO(13643) | 2 288 libfontconfig.so
[task 2021-06-02T22:26:19.446Z] 22:26:19 INFO - GECKO(13643) | -----------------------------------------------------
[task 2021-06-02T22:26:19.635Z] 22:26:19 INFO - GECKO(13643) | -----------------------------------------------------
[task 2021-06-02T22:26:19.635Z] 22:26:19 INFO - GECKO(13643) | Suppressions used:
[task 2021-06-02T22:26:19.635Z] 22:26:19 INFO - GECKO(13643) | count bytes template
[task 2021-06-02T22:26:19.635Z] 22:26:19 INFO - GECKO(13643) | 14 448 nsComponentManagerImpl
[task 2021-06-02T22:26:19.635Z] 22:26:19 INFO - GECKO(13643) | 2 288 libfontconfig.so
[task 2021-06-02T22:26:19.635Z] 22:26:19 INFO - GECKO(13643) | -----------------------------------------------------
[task 2021-06-02T22:26:19.804Z] 22:26:19 INFO - GECKO(13643) | -----------------------------------------------------
[task 2021-06-02T22:26:19.806Z] 22:26:19 INFO - GECKO(13643) | Suppressions used:
[task 2021-06-02T22:26:19.808Z] 22:26:19 INFO - GECKO(13643) | count bytes template
[task 2021-06-02T22:26:19.809Z] 22:26:19 INFO - GECKO(13643) | 14 448 nsComponentManagerImpl
[task 2021-06-02T22:26:19.810Z] 22:26:19 INFO - GECKO(13643) | 2 288 libfontconfig.so
[task 2021-06-02T22:26:19.812Z] 22:26:19 INFO - GECKO(13643) | -----------------------------------------------------
[task 2021-06-02T22:26:21.019Z] 22:26:21 INFO - GECKO(13643) | -----------------------------------------------------
[task 2021-06-02T22:26:21.019Z] 22:26:21 INFO - GECKO(13643) | Suppressions used:
[task 2021-06-02T22:26:21.020Z] 22:26:21 INFO - GECKO(13643) | count bytes template
[task 2021-06-02T22:26:21.021Z] 22:26:21 INFO - GECKO(13643) | 14 432 nsComponentManagerImpl
[task 2021-06-02T22:26:21.022Z] 22:26:21 INFO - GECKO(13643) | 633 18083 libfontconfig.so
[task 2021-06-02T22:26:21.022Z] 22:26:21 INFO - GECKO(13643) | 3 624 mozJSComponentLoader
[task 2021-06-02T22:26:21.023Z] 22:26:21 INFO - GECKO(13643) | -----------------------------------------------------
[task 2021-06-02T22:26:21.108Z] 22:26:21 INFO - TEST-INFO | Main app process: exit 0
[task 2021-06-02T22:26:21.109Z] 22:26:21 INFO - TEST-INFO | LeakSanitizer | To show the addresses of leaked objects add report_objects=1 to LSAN_OPTIONS
[task 2021-06-02T22:26:21.109Z] 22:26:21 INFO - TEST-INFO | LeakSanitizer | This can be done in testing/mozbase/mozrunner/mozrunner/utils.py
[task 2021-06-02T22:26:21.109Z] 22:26:21 ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at _M_init_functor, _M_init_functor, function, mozilla::dom::PerformanceStorageWorker::Create
[task 2021-06-02T22:26:21.109Z] 22:26:21 ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at mozilla::dom::WeakWorkerRef::Create, mozilla::dom::PerformanceStorageWorker::Create, mozilla::dom::WorkerPrivate::EnsurePerformanceStorage, mozilla::dom::workerinternals::
[task 2021-06-02T22:26:21.112Z] 22:26:21 ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at mozilla::dom::PerformanceStorageWorker::Create, mozilla::dom::WorkerPrivate::EnsurePerformanceStorage, mozilla::dom::workerinternals::, nsThread::ProcessNextEvent
[task 2021-06-02T22:26:21.112Z] 22:26:21 INFO - runtests.py | Application ran for: 0:01:06.292289
[task 2021-06-02T22:26:21.113Z] 22:26:21 INFO - zombiecheck | Reading PID log: /tmp/tmpvrw9dsvfpidlog
<...>
[task 2021-06-02T22:26:21.147Z] 22:26:21 INFO - zombiecheck | Checking for orphan process with PID: 13813
[task 2021-06-02T22:26:21.148Z] 22:26:21 INFO - Stopping web server
[task 2021-06-02T22:26:21.149Z] 22:26:21 INFO - Server shut down.
[task 2021-06-02T22:26:21.150Z] 22:26:21 INFO - Web server killed.
[task 2021-06-02T22:26:21.150Z] 22:26:21 INFO - Stopping web socket server
[task 2021-06-02T22:26:21.169Z] 22:26:21 INFO - Stopping ssltunnel
[task 2021-06-02T22:26:21.191Z] 22:26:21 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2021-06-02T22:26:21.191Z] 22:26:21 INFO - runtests.py | Running tests: end.
[task 2021-06-02T22:26:21.213Z] 22:26:21 INFO - Buffered messages finished
[task 2021-06-02T22:26:21.213Z] 22:26:21 INFO - Running manifest: toolkit/mozapps/extensions/test/xpinstall/browser.ini
[task 2021-06-02T22:26:21.234Z] 22:26:21 INFO - Setting pipeline to PAUSED ...
[task 2021-06-02T22:26:21.234Z] 22:26:21 INFO - Pipeline is PREROLLING ...
[task 2021-06-02T22:26:21.237Z] 22:26:21 INFO - Pipeline is PREROLLED ...
[task 2021-06-02T22:26:21.237Z] 22:26:21 INFO - Setting pipeline to PLAYING ...
[task 2021-06-02T22:26:21.237Z] 22:26:21 INFO - New clock: GstSystemClock
[task 2021-06-02T22:26:21.270Z] 22:26:21 INFO - Got EOS from element "pipeline0".
[task 2021-06-02T22:26:21.270Z] 22:26:21 INFO - Execution ended after 0:00:00.033382392
[task 2021-06-02T22:26:21.270Z] 22:26:21 INFO - Setting pipeline to PAUSED ...
[task 2021-06-02T22:26:21.270Z] 22:26:21 INFO - Setting pipeline to READY ...
[task 2021-06-02T22:26:21.271Z] 22:26:21 INFO - (gst-launch-1.0:14910): GStreamer-CRITICAL **: 22:26:21.270: gst_object_unref: assertion '((GObject *) object)->ref_count > 0' failed
[task 2021-06-02T22:26:21.271Z] 22:26:21 INFO - Setting pipeline to NULL ...
[task 2021-06-02T22:26:21.272Z] 22:26:21 INFO - Freeing pipeline ...
[task 2021-06-02T22:26:21.306Z] 22:26:21 INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2021-06-02T22:26:21.309Z] 22:26:21 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2021-06-02T22:26:21.310Z] 22:26:21 INFO - UBSan enabled.
[task 2021-06-02T22:26:21.512Z] 22:26:21 INFO - PID 14919 | pk12util: PKCS12 IMPORT SUCCESSFUL
[task 2021-06-02T22:26:21.738Z] 22:26:21 INFO - Increasing default timeout to 90 seconds
[task 2021-06-02T22:26:21.740Z] 22:26:21 INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2021-06-02T22:26:21.745Z] 22:26:21 INFO - LSan enabled.
[task 2021-06-02T22:26:21.745Z] 22:26:21 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2021-06-02T22:26:21.745Z] 22:26:21 INFO - UBSan enabled.
[task 2021-06-02T22:26:21.745Z] 22:26:21 INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2021-06-02T22:26:21.748Z] 22:26:21 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2021-06-02T22:26:21.748Z] 22:26:21 INFO - UBSan enabled.
[task 2021-06-02T22:26:21.754Z] 22:26:21 INFO - MochitestServer : launching ['/builds/worker/workspace/build/tests/bin/xpcshell', '-g', '/builds/worker/workspace/build/application/firefox', '-f', '/builds/worker/workspace/build/tests/bin/components/httpd.js', '-e', "const _PROFILE_PATH = '/tmp/tmpkubbtdxm.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false;", '-f', '/builds/worker/workspace/build/tests/mochitest/server.js']
[task 2021-06-02T22:26:21.754Z] 22:26:21 INFO - runtests.py | Server pid: 14935
[task 2021-06-02T22:26:21.771Z] 22:26:21 INFO - runtests.py | Websocket server pid: 14938
[task 2021-06-02T22:26:21.772Z] 22:26:21 INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2021-06-02T22:26:21.795Z] 22:26:21 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2021-06-02T22:26:21.795Z] 22:26:21 INFO - UBSan enabled.
[task 2021-06-02T22:26:21.815Z] 22:26:21 INFO - runtests.py | SSL tunnel pid: 14944
[task 2021-06-02T22:26:22.072Z] 22:26:22 INFO - runtests.py | Running with scheme: http
[task 2021-06-02T22:26:22.072Z] 22:26:22 INFO - runtests.py | Running with e10s: True
[task 2021-06-02T22:26:22.072Z] 22:26:22 INFO - runtests.py | Running with fission: True
[task 2021-06-02T22:26:22.072Z] 22:26:22 INFO - runtests.py | Running with cross-origin iframes: False
[task 2021-06-02T22:26:22.072Z] 22:26:22 INFO - runtests.py | Running with serviceworker_e10s: True
[task 2021-06-02T22:26:22.072Z] 22:26:22 INFO - runtests.py | Running with socketprocess_e10s: False
[task 2021-06-02T22:26:22.072Z] 22:26:22 INFO - runtests.py | Running tests: start.
[task 2021-06-02T22:26:22.072Z] 22:26:22 INFO -
[task 2021-06-02T22:26:22.155Z] 22:26:22 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -foreground -profile /tmp/tmpkubbtdxm.mozrunner
[task 2021-06-02T22:26:22.167Z] 22:26:22 INFO - runtests.py | Application pid: 14959
[task 2021-06-02T22:26:22.168Z] 22:26:22 INFO - TEST-INFO | started process GECKO(14959)
[task 2021-06-02T22:26:23.309Z] 22:26:23 INFO - GECKO(14959) | 1622672783307 Marionette INFO Marionette enabled
[task 2021-06-02T22:26:23.534Z] 22:26:23 INFO - GECKO(14959) | 1622672783533 Marionette TRACE Received observer notification toplevel-window-ready
[task 2021-06-02T22:26:26.361Z] 22:26:26 INFO - GECKO(14959) | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/tmpkubbtdxm.mozrunner/search.json.mozlz4", (void 0)))
[task 2021-06-02T22:26:28.395Z] 22:26:28 INFO - GECKO(14959) | 1622672788394 Marionette TRACE Received observer notification marionette-startup-requested
[task 2021-06-02T22:26:28.399Z] 22:26:28 INFO - GECKO(14959) | 1622672788398 Marionette TRACE Waiting until startup recorder finished recording startup scripts...
[task 2021-06-02T22:26:28.552Z] 22:26:28 INFO - GECKO(14959) | 1622672788551 Marionette TRACE All scripts recorded.
[task 2021-06-02T22:26:28.564Z] 22:26:28 INFO - GECKO(14959) | 1622672788564 Marionette INFO Listening on port 2828
[task 2021-06-02T22:26:28.577Z] 22:26:28 INFO - GECKO(14959) | 1622672788576 Marionette DEBUG Marionette is listening
[task 2021-06-02T22:26:28.713Z] 22:26:28 INFO - GECKO(14959) | 1622672788712 Marionette DEBUG Accepted connection 0 from 127.0.0.1:36962
[task 2021-06-02T22:26:28.767Z] 22:26:28 INFO - GECKO(14959) | 1622672788766 Marionette DEBUG Accepted connection 1 from 127.0.0.1:36964
[task 2021-06-02T22:26:28.771Z] 22:26:28 INFO - GECKO(14959) | 1622672788770 Marionette DEBUG Closed connection 0
[task 2021-06-02T22:26:28.826Z] 22:26:28 INFO - GECKO(14959) | 1622672788825 Marionette DEBUG 1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2021-06-02T22:26:28.895Z] 22:26:28 INFO - GECKO(14959) | 1622672788893 Marionette DEBUG 1 <- [1,1,null,{"sessionId":"fb47bb8e-7802-4785-a1fe-848bb2c2a5c6","capabilities":{"browserName":"firefox","browserVersion":"91.0a ... .mozrunner","moz:shutdownTimeout":300000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"proxy":{}}}]
[task 2021-06-02T22:26:28.942Z] 22:26:28 INFO - GECKO(14959) | 1622672788940 Marionette DEBUG 1 -> [0,2,"Addon:Install",{"path":"/tmp/tmpcq7i1u5x.zip","temporary":false}]
[task 2021-06-02T22:26:29.194Z] 22:26:29 INFO - GECKO(14959) | 1622672789191 Marionette TRACE [39] MarionetteEvents actor created for window id 22
[task 2021-06-02T22:26:29.412Z] 22:26:29 INFO - GECKO(14959) | 1622672789411 Marionette DEBUG 1 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
[task 2021-06-02T22:26:29.435Z] 22:26:29 INFO - GECKO(14959) | 1622672789434 Marionette DEBUG 1 -> [0,3,"Addon:Install",{"path":"/tmp/tmp8q4rovmo.zip","temporary":false}]
[task 2021-06-02T22:26:29.489Z] 22:26:29 INFO - GECKO(14959) | 1622672789488 Marionette DEBUG 1 <- [1,3,null,{"value":"mochikit@mozilla.org"}]
[task 2021-06-02T22:26:29.494Z] 22:26:29 INFO - GECKO(14959) | 1622672789493 Marionette DEBUG 1 -> [0,4,"Marionette:GetContext",{}]
[task 2021-06-02T22:26:29.495Z] 22:26:29 INFO - GECKO(14959) | 1622672789493 Marionette DEBUG 1 <- [1,4,null,{"value":"content"}]
[task 2021-06-02T22:26:29.496Z] 22:26:29 INFO - GECKO(14959) | 1622672789496 Marionette DEBUG 1 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2021-06-02T22:26:29.498Z] 22:26:29 INFO - GECKO(14959) | 1622672789496 Marionette DEBUG 1 <- [1,5,null,{"value":null}]
[task 2021-06-02T22:26:29.500Z] 22:26:29 INFO - GECKO(14959) | 1622672789499 Marionette DEBUG 1 -> [0,6,"WebDriver:ExecuteScript",{"script":"/* This Source Code Form is subject to the terms of the Mozilla Public\n * License, ... hrome","testUrl":"about:blank"}],"newSandbox":true,"sandbox":"default","line":1935,"filename":"tests/mochitest/runtests.py"}]
[task 2021-06-02T22:26:29.509Z] 22:26:29 INFO - GECKO(14959) | 1622672789508 Marionette TRACE [7] MarionetteCommands actor created for window id 2
[task 2021-06-02T22:26:29.523Z] 22:26:29 INFO - GECKO(14959) | 1622672789521 Marionette TRACE [19] MarionetteEvents actor created for window id 2147483649
[task 2021-06-02T22:26:29.578Z] 22:26:29 INFO - GECKO(14959) | 1622672789577 Marionette TRACE Received observer notification toplevel-window-ready
[task 2021-06-02T22:26:29.620Z] 22:26:29 INFO - GECKO(14959) | 1622672789619 Marionette DEBUG 1 <- [1,6,null,{"value":null}]
[task 2021-06-02T22:26:29.629Z] 22:26:29 INFO - GECKO(14959) | 1622672789628 Marionette DEBUG 1 -> [0,7,"Marionette:SetContext",{"value":"content"}]
[task 2021-06-02T22:26:29.631Z] 22:26:29 INFO - GECKO(14959) | 1622672789629 Marionette DEBUG 1 <- [1,7,null,{"value":null}]
[task 2021-06-02T22:26:29.677Z] 22:26:29 INFO - GECKO(14959) | 1622672789676 Marionette TRACE [19] MarionetteEvents actor created for window id 2147483650
[task 2021-06-02T22:26:29.677Z] 22:26:29 INFO - GECKO(14959) | 1622672789676 Marionette DEBUG 1 -> [0,8,"WebDriver:DeleteSession",{}]
[task 2021-06-02T22:26:29.682Z] 22:26:29 INFO - GECKO(14959) | 1622672789681 Marionette DEBUG 1 <- [1,8,null,{"value":null}]
[task 2021-06-02T22:26:29.740Z] 22:26:29 INFO - GECKO(14959) | JavaScript error: , line 0: NotFoundError: No such JSWindowActor 'MarionetteEvents'
[task 2021-06-02T22:26:29.741Z] 22:26:29 INFO - runtests.py | Waiting for browser...
[task 2021-06-02T22:26:29.742Z] 22:26:29 INFO - GECKO(14959) | JavaScript error: , line 0: NotFoundError: No such JSWindowActor 'MarionetteEvents'
[task 2021-06-02T22:26:29.776Z] 22:26:29 INFO - GECKO(14959) | 1622672789775 Marionette DEBUG Closed connection 1
[task 2021-06-02T22:26:30.173Z] 22:26:30 INFO - GECKO(14959) | 1622672790172 Marionette TRACE [39] MarionetteEvents actor created for window id 6442450945
[task 2021-06-02T22:26:30.177Z] 22:26:30 INFO - GECKO(14959) | JavaScript error: , line 0: NotFoundError: No such JSWindowActor 'MarionetteEvents'
[task 2021-06-02T22:26:30.484Z] 22:26:30 INFO - *** Start BrowserChrome Test Results ***
[task 2021-06-02T22:26:30.631Z] 22:26:30 INFO - checking window state
[task 2021-06-02T22:26:30.751Z] 22:26:30 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_amosigned_trigger.js
Description
•