Closed Bug 1715523 Opened 3 years ago Closed 3 years ago

Perma valgrind-test | 32,896 (256 direct, 32,640 indirect) bytes in 8 blocks are definitely lost at malloc / moz_xmalloc / operator / MakeNotNull

Categories

(Core :: Graphics: WebRender, defect, P5)

defect

Tracking

()

RESOLVED FIXED
91 Branch
Tracking Status
firefox-esr78 --- unaffected
firefox89 --- unaffected
firefox90 --- unaffected
firefox91 --- fixed

People

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

References

(Regression)

Details

(Keywords: intermittent-failure, regression)

Attachments

(1 file)

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


[task 2021-06-09T12:03:58.269Z] 12:03:58     INFO -  25:21.23 --12492-- REDIR: 0x52b45d0 (libstdc++.so.6:operator delete[](void*)) redirected to 0x4c2aeb0 (operator delete[](void*))
[task 2021-06-09T12:03:58.670Z] 12:03:58     INFO -  25:21.63 ==12492==
[task 2021-06-09T12:03:58.671Z] 12:03:58     INFO -  25:21.63 ==12492== HEAP SUMMARY:
[task 2021-06-09T12:03:58.671Z] 12:03:58     INFO -  25:21.63 ==12492==     in use at exit: 194,634 bytes in 1,023 blocks
[task 2021-06-09T12:03:58.671Z] 12:03:58     INFO -  25:21.63 ==12492==   total heap usage: 6,635 allocs, 5,612 frees, 791,123 bytes allocated
[task 2021-06-09T12:03:58.671Z] 12:03:58     INFO -  25:21.63 ==12492==
[task 2021-06-09T12:03:58.671Z] 12:03:58     INFO -  25:21.63 ==12492== Searching for pointers to 1,023 not-freed blocks
[task 2021-06-09T12:03:58.680Z] 12:03:58     INFO -  25:21.64 ==12492== Checked 2,091,888 bytes
[task 2021-06-09T12:03:58.680Z] 12:03:58     INFO -  25:21.64 ==12492==
[task 2021-06-09T12:03:58.711Z] 12:03:58     INFO -  25:21.67 ==12492== LEAK SUMMARY:
[task 2021-06-09T12:03:58.711Z] 12:03:58     INFO -  25:21.67 ==12492==    definitely lost: 0 bytes in 0 blocks
[task 2021-06-09T12:03:58.711Z] 12:03:58     INFO -  25:21.67 ==12492==    indirectly lost: 0 bytes in 0 blocks
[task 2021-06-09T12:03:58.711Z] 12:03:58     INFO -  25:21.67 ==12492==      possibly lost: 1,352 bytes in 18 blocks
[task 2021-06-09T12:03:58.711Z] 12:03:58     INFO -  25:21.67 ==12492==    still reachable: 193,058 bytes in 1,001 blocks
[task 2021-06-09T12:03:58.712Z] 12:03:58     INFO -  25:21.67 ==12492==                       of which reachable via heuristic:
[task 2021-06-09T12:03:58.712Z] 12:03:58     INFO -  25:21.67 ==12492==                         newarray           : 1,536 bytes in 16 blocks
[task 2021-06-09T12:03:58.712Z] 12:03:58     INFO -  25:21.67 ==12492==         suppressed: 224 bytes in 4 blocks
[task 2021-06-09T12:03:58.712Z] 12:03:58     INFO -  25:21.67 ==12492== Reachable blocks (those to which a pointer was found) are not shown.
[task 2021-06-09T12:03:58.712Z] 12:03:58     INFO -  25:21.67 ==12492== To see them, rerun with: --leak-check=full --show-leak-kinds=all
[task 2021-06-09T12:03:58.712Z] 12:03:58     INFO -  25:21.67 ==12492==
[task 2021-06-09T12:03:58.713Z] 12:03:58     INFO -  25:21.67 ==12492== ERROR SUMMARY: 18 errors from 18 contexts (suppressed: 2 from 2)
[task 2021-06-09T12:03:58.713Z] 12:03:58     INFO -  25:21.67 --12492--
[task 2021-06-09T12:03:58.713Z] 12:03:58     INFO -  25:21.67 --12492-- used_suppression:      1 dlopen leak triggered by bug 1492121 /builds/worker/checkouts/gecko/build/valgrind/x86_64-pc-linux-gnu.sup:213 suppressed: 192 bytes in 3 blocks
[task 2021-06-09T12:03:58.713Z] 12:03:58     INFO -  25:21.67 --12492-- used_suppression:      1 Bug 794372 /builds/worker/checkouts/gecko/build/valgrind/cross-architecture.sup:121 suppressed: 32 bytes in 1 blocks
[task 2021-06-09T12:03:58.713Z] 12:03:58     INFO -  25:21.67 ==12492==
[task 2021-06-09T12:03:58.713Z] 12:03:58     INFO -  25:21.67 ==12492== ERROR SUMMARY: 18 errors from 18 contexts (suppressed: 2 from 2)
[task 2021-06-09T12:04:04.585Z] 12:04:04     INFO -  25:27.54
[task 2021-06-09T12:04:04.585Z] 12:04:04     INFO -  25:27.54 (firefox:12463): GLib-GObject-CRITICAL **: g_object_ref: assertion 'object->ref_count > 0' failed
[task 2021-06-09T12:04:04.585Z] 12:04:04     INFO -  25:27.54
[task 2021-06-09T12:04:04.585Z] 12:04:04     INFO -  25:27.54 (firefox:12463): GLib-GObject-CRITICAL **: g_object_unref: assertion 'object->ref_count > 0' failed
[task 2021-06-09T12:04:13.424Z] 12:04:13     INFO -  25:36.38 ==12463== Warning: set address range perms: large range [0x2fc0f3381000, 0x2fc172f81000) (noaccess)
[task 2021-06-09T12:04:18.589Z] 12:04:18     INFO -  25:41.55 --12463-- Archiving syms at 0x1cbfa280-0x1cc8cb60 in /builds/worker/workspace/obj-build/security/nss/lib/freebl/freebl_freeblpriv3/libfreeblpriv3.so (have_dinfo 1)
[task 2021-06-09T12:04:18.589Z] 12:04:18     INFO -  25:41.55 --12463-- Scanning and archiving ExeContexts ...
[task 2021-06-09T12:04:19.021Z] 12:04:19     INFO -  25:41.98 --12463-- Scanned 3,054,233 ExeContexts, archived 1,416 ExeContexts
[task 2021-06-09T12:04:19.031Z] 12:04:19     INFO -  25:41.99 --12463-- Archiving syms at 0x1cb8f560-0x1cbc2e86 in /builds/worker/workspace/obj-build/security/nss/lib/softoken/softoken_softokn3/libsoftokn3.so (have_dinfo 1)
[task 2021-06-09T12:04:19.031Z] 12:04:19     INFO -  25:41.99 --12463-- Scanning and archiving ExeContexts ...
[task 2021-06-09T12:04:19.460Z] 12:04:19     INFO -  25:42.42 --12463-- Scanned 3,054,288 ExeContexts, archived 5,408 ExeContexts
[task 2021-06-09T12:04:19.831Z] 12:04:19     INFO -  25:42.79 --12463-- Archiving syms at 0x17c372a0-0x17c3dba3 in /lib/x86_64-linux-gnu/libnss_files-2.19.so (have_dinfo 1)
[task 2021-06-09T12:04:19.831Z] 12:04:19     INFO -  25:42.79 --12463-- Scanning and archiving ExeContexts ...
[task 2021-06-09T12:04:20.274Z] 12:04:20     INFO -  25:43.23 --12463-- Scanned 3,057,401 ExeContexts, archived 88 ExeContexts
[task 2021-06-09T12:04:20.285Z] 12:04:20     INFO -  25:43.24 --12463-- Archiving syms at 0x23c75100-0x23c77ff0 in /lib/x86_64-linux-gnu/libnss_dns-2.19.so (have_dinfo 1)
[task 2021-06-09T12:04:20.285Z] 12:04:20     INFO -  25:43.24 --12463-- Scanning and archiving ExeContexts ...
[task 2021-06-09T12:04:20.708Z] 12:04:20     INFO -  25:43.67 --12463-- Scanned 3,057,412 ExeContexts, archived 71 ExeContexts
[task 2021-06-09T12:04:20.718Z] 12:04:20     INFO -  25:43.68 ==12463==
[task 2021-06-09T12:04:20.720Z] 12:04:20     INFO -  25:43.68 ==12463== HEAP SUMMARY:
[task 2021-06-09T12:04:20.720Z] 12:04:20     INFO -  25:43.68 ==12463==     in use at exit: 2,923,714 bytes in 25,376 blocks
[task 2021-06-09T12:04:20.720Z] 12:04:20     INFO -  25:43.68 ==12463==   total heap usage: 3,852,071 allocs, 3,826,695 frees, 2,408,021,702 bytes allocated
[task 2021-06-09T12:04:20.720Z] 12:04:20     INFO -  25:43.68 ==12463==
[task 2021-06-09T12:04:20.733Z] 12:04:20     INFO -  25:43.69 ==12463== Searching for pointers to 24,470 not-freed blocks
[task 2021-06-09T12:04:20.876Z] 12:04:20     INFO -  25:43.83 ==12463== Checked 72,347,992 bytes
[task 2021-06-09T12:04:20.876Z] 12:04:20     INFO -  25:43.83 ==12463==
[task 2021-06-09T12:04:21.896Z] 12:04:21     INFO -  25:44.85 TEST-UNEXPECTED-FAIL | valgrind-test | 32,896 (256 direct, 32,640 indirect) bytes in 8 blocks are definitely lost at malloc / moz_xmalloc / operator / MakeNotNull
[task 2021-06-09T12:04:21.896Z] 12:04:21     INFO -  25:44.85 ==12463== 32,896 (256 direct, 32,640 indirect) bytes in 8 blocks are definitely lost in loss record 10,870 of 10,878
[task 2021-06-09T12:04:21.896Z] 12:04:21     INFO -  25:44.85 ==12463==    at 0x4C28FC0: malloc+112 (vg_replace_malloc.c:307)
[task 2021-06-09T12:04:21.897Z] 12:04:21     INFO -  25:44.85 ==12463==    by 0x12C840: moz_xmalloc+16 (checkouts/gecko/memory/mozalloc/mozalloc.cpp:52)
[task 2021-06-09T12:04:21.897Z] 12:04:21     INFO -  25:44.85 ==12463==    by 0x1492F7D6: operator new (dist/include/mozilla/cxxalloc.h:33)
[task 2021-06-09T12:04:21.897Z] 12:04:21     INFO -  25:44.85 ==12463==    by 0x1492F7D6: MakeNotNull<RefPtr<mozilla::ProfilingStackOwner>> (dist/include/mozilla/NotNull.h:378)
[task 2021-06-09T12:04:21.897Z] 12:04:21     INFO -  25:44.85 ==12463==    by 0x1492F7D6: RacyRegisteredThread (checkouts/gecko/tools/profiler/core/RegisteredThread.cpp:15)
[task 2021-06-09T12:04:21.897Z] 12:04:21     INFO -  25:44.85 ==12463==    by 0x1492F7D6: RegisteredThread::RegisteredThread(ThreadInfo*, nsIThread*, void*)+54 (checkouts/gecko/tools/profiler/core/RegisteredThread.cpp:24)
[task 2021-06-09T12:04:21.898Z] 12:04:21     INFO -  25:44.85 ==12463==    by 0x1493814C: MakeUnique<RegisteredThread, RefPtr<ThreadInfo> &, nsIThread *, void *&> (dist/include/mozilla/UniquePtr.h:609)
[task 2021-06-09T12:04:21.898Z] 12:04:21     INFO -  25:44.85 ==12463==    by 0x1493814C: locked_register_thread(PSAutoLock const&, char const*, void*)+252 (checkouts/gecko/tools/profiler/core/platform.cpp:4211)
[task 2021-06-09T12:04:21.898Z] 12:04:21     INFO -  25:44.85 ==12463==    by 0x1493E738: profiler_register_thread(char const*, void*)+296 (checkouts/gecko/tools/profiler/core/platform.cpp:5509)
[task 2021-06-09T12:04:21.898Z] 12:04:21     INFO -  25:44.85 ==12463==    by 0x1104EB0A: mozilla::TaskController::RunPoolThread()+154 (checkouts/gecko/xpcom/threads/TaskController.cpp:227)
[task 2021-06-09T12:04:21.898Z] 12:04:21     INFO -  25:44.85 ==12463==    by 0x40685F4: _pt_root+564 (checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:201)
[task 2021-06-09T12:04:21.899Z] 12:04:21     INFO -  25:44.85 ==12463==    by 0x4E3E063: start_thread+195 (pthread_create.c:309)
[task 2021-06-09T12:04:21.899Z] 12:04:21     INFO -  25:44.85 ==12463==    by 0x5B6162C: clone+108 (clone.S:111)
[task 2021-06-09T12:04:21.899Z] 12:04:21     INFO -  25:44.85 ==12463==
[task 2021-06-09T12:04:21.899Z] 12:04:21     INFO -  25:44.85 {
[task 2021-06-09T12:04:21.899Z] 12:04:21     INFO -  25:44.85    <insert_a_suppression_name_here>
[task 2021-06-09T12:04:21.899Z] 12:04:21     INFO -  25:44.85    Memcheck:Leak
[task 2021-06-09T12:04:21.899Z] 12:04:21     INFO -  25:44.85    match-leak-kinds: definite
[task 2021-06-09T12:04:21.899Z] 12:04:21     INFO -  25:44.85    fun:malloc
[task 2021-06-09T12:04:21.900Z] 12:04:21     INFO -  25:44.85    fun:moz_xmalloc
[task 2021-06-09T12:04:21.900Z] 12:04:21     INFO -  25:44.85    fun:operator new
[task 2021-06-09T12:04:21.900Z] 12:04:21     INFO -  25:44.85    fun:MakeNotNull<RefPtr<mozilla::ProfilingStackOwner>>
[task 2021-06-09T12:04:21.900Z] 12:04:21     INFO -  25:44.85    fun:RacyRegisteredThread
[task 2021-06-09T12:04:21.900Z] 12:04:21     INFO -  25:44.86    fun:_ZN16RegisteredThreadC1EP10ThreadInfoP9nsIThreadPv
[task 2021-06-09T12:04:21.900Z] 12:04:21     INFO -  25:44.86    fun:MakeUnique<RegisteredThread, RefPtr<ThreadInfo> &, nsIThread *, void *&>
[task 2021-06-09T12:04:21.900Z] 12:04:21     INFO -  25:44.86    fun:_ZL22locked_register_threadRK10PSAutoLockPKcPv
[task 2021-06-09T12:04:21.900Z] 12:04:21     INFO -  25:44.86    fun:_Z24profiler_register_threadPKcPv
[task 2021-06-09T12:04:21.901Z] 12:04:21     INFO -  25:44.86    fun:_ZN7mozilla14TaskController13RunPoolThreadEv
[task 2021-06-09T12:04:21.901Z] 12:04:21     INFO -  25:44.86    fun:_pt_root
[task 2021-06-09T12:04:21.901Z] 12:04:21     INFO -  25:44.86    fun:start_thread
[task 2021-06-09T12:04:21.901Z] 12:04:21     INFO -  25:44.86    fun:clone
[task 2021-06-09T12:04:21.901Z] 12:04:21     INFO -  25:44.86 }
[task 2021-06-09T12:04:21.901Z] 12:04:21     INFO -  25:44.86 ==12463== LEAK SUMMARY:
[task 2021-06-09T12:04:21.901Z] 12:04:21     INFO -  25:44.86 ==12463==    definitely lost: 256 bytes in 8 blocks
[task 2021-06-09T12:04:21.901Z] 12:04:21     INFO -  25:44.86 ==12463==    indirectly lost: 46,259 bytes in 560 blocks
[task 2021-06-09T12:04:21.902Z] 12:04:21     INFO -  25:44.86 ==12463==      possibly lost: 75,498 bytes in 577 blocks
[task 2021-06-09T12:04:21.902Z] 12:04:21     INFO -  25:44.86 ==12463==    still reachable: 2,595,202 bytes in 23,243 blocks
[task 2021-06-09T12:04:21.902Z] 12:04:21     INFO -  25:44.86 ==12463==                       of which reachable via heuristic:
[task 2021-06-09T12:04:21.902Z] 12:04:21     INFO -  25:44.86 ==12463==                         length64           : 7,144 bytes in 112 blocks
[task 2021-06-09T12:04:21.902Z] 12:04:21     INFO -  25:44.86 ==12463==                         newarray           : 2,144 bytes in 54 blocks
[task 2021-06-09T12:04:21.902Z] 12:04:21     INFO -  25:44.86 ==12463==         suppressed: 66,827 bytes in 82 blocks
[task 2021-06-09T12:04:21.902Z] 12:04:21     INFO -  25:44.86 ==12463== Reachable blocks (those to which a pointer was found) are not shown.
[task 2021-06-09T12:04:21.903Z] 12:04:21     INFO -  25:44.86 ==12463== To see them, rerun with: --leak-check=full --show-leak-kinds=all
[task 2021-06-09T12:04:21.903Z] 12:04:21     INFO -  25:44.86 ==12463==
[task 2021-06-09T12:04:21.903Z] 12:04:21     INFO -  25:44.86 ==12463== ERROR SUMMARY: 541 errors from 541 contexts (suppressed: 11907 from 43)

Andrew, please take a look.

Flags: needinfo?(aosmond)

I will add a suppression against this bug and clone this for a future investigation.

Flags: needinfo?(aosmond)
Blocks: 1715533
Assignee: nobody → aosmond
Status: NEW → ASSIGNED
Pushed by aosmond@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/eaae035bbfe0 Suppress likely false positive valgrind leak for RacyRegisteredThread. r=jrmuizel
Status: ASSIGNED → RESOLVED
Closed: 3 years ago
Resolution: --- → FIXED
Target Milestone: --- → 91 Branch

Set release status flags based on info from the regressing bug 1715306

Has Regression Range: --- → yes
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: