1777370 - Intermittent Assertion failure: exInfo.ref_count == topInfo.ref_count, at /builds/worker/checkouts/gecko/xpcom/base/nsMemoryReporterManager.cpp:539

Status: RESOLVED DUPLICATE of bug 1777220

(Core :: XPCOM, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2022-06-29T22:27:33.380Z] 22:27:33     INFO - GECKO(2892) | [Parent 2892, IPDL Background] WARNING: cache: '0.004883s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3792
[task 2022-06-29T22:27:33.380Z] 22:27:33     INFO - GECKO(2892) | [Parent 2892, IPDL Background] WARNING: sdb: '0.004895s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3792
[task 2022-06-29T22:27:33.381Z] 22:27:33     INFO - GECKO(2892) | [Parent 2892, IPDL Background] WARNING: ls: '0.004901s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3792
[task 2022-06-29T22:27:33.381Z] 22:27:33     INFO - GECKO(2892) | [Parent 2892, IPDL Background] WARNING: quota manager shutdown step: '0.004908s: shutdownAndJoinIOThread', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3792
[task 2022-06-29T22:27:33.381Z] 22:27:33     INFO - GECKO(2892) | [Parent 2892, IPDL Background] WARNING: quota manager shutdown step: '0.006926s: invalidatePendingDirectoryLocks', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3792
[task 2022-06-29T22:27:33.382Z] 22:27:33     INFO - GECKO(2892) | [Parent 2892, IPDL Background] WARNING: quota manager shutdown step: '0.006941s: stopCrashBrowserTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3792
[task 2022-06-29T22:27:33.464Z] 22:27:33     INFO - GECKO(2892) | [Child 2899, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3359
[task 2022-06-29T22:27:33.466Z] 22:27:33     INFO - GECKO(2892) | [Child 2899, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2022-06-29T22:27:33.472Z] 22:27:33     INFO - GECKO(2892) | [Child 2899, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4595
[task 2022-06-29T22:27:33.473Z] 22:27:33     INFO - GECKO(2892) | Assertion failure: exInfo.ref_count == topInfo.ref_count, at /builds/worker/checkouts/gecko/xpcom/base/nsMemoryReporterManager.cpp:539
[task 2022-06-29T22:27:33.476Z] 22:27:33     INFO -  Initializing stack-fixing for the first stack frame, this may take a while...
[task 2022-06-29T22:27:42.578Z] 22:27:42     INFO - GECKO(2892) | #01: ResidentUniqueDistinguishedAmount(long long*, unsigned int) [xpcom/base/nsMemoryReporterManager.cpp:539]
[task 2022-06-29T22:27:42.578Z] 22:27:42     INFO - GECKO(2892) | #02: mozilla::detail::RunnableFunction<mozilla::MemoryTelemetry::GatherReports(std::__1::function<void ()> const&)::$_1>::Run() [xpcom/threads/nsThreadUtils.h:531]
[task 2022-06-29T22:27:42.579Z] 22:27:42     INFO - GECKO(2892) | #03: nsThreadPool::Run() [xpcom/threads/nsThreadPool.cpp:312]
[task 2022-06-29T22:27:42.579Z] 22:27:42     INFO - GECKO(2892) | #04: nsThread::ProcessNextEvent(bool, bool*) [xpcom/threads/nsThread.cpp:1199]
[task 2022-06-29T22:27:42.579Z] 22:27:42     INFO - GECKO(2892) | #05: NS_ProcessNextEvent(nsIThread*, bool) [xpcom/threads/nsThreadUtils.cpp:465]
[task 2022-06-29T22:27:42.580Z] 22:27:42     INFO - GECKO(2892) | #06: mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate*) [ipc/glue/MessagePump.cpp:301]
[task 2022-06-29T22:27:42.580Z] 22:27:42     INFO - GECKO(2892) | #07: MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:356]
[task 2022-06-29T22:27:42.581Z] 22:27:42     INFO - GECKO(2892) | #08: nsThread::ThreadFunc(void*) [xpcom/threads/nsThread.cpp:386]
[task 2022-06-29T22:27:42.807Z] 22:27:42     INFO - GECKO(2892) | #09: _pt_root [nsprpub/pr/src/pthreads/ptthread.c:204]
[task 2022-06-29T22:27:42.809Z] 22:27:42     INFO - GECKO(2892) | #10: _pthread_start [/usr/lib/system/libsystem_pthread.dylib + 0x6109]
[task 2022-06-29T22:27:42.809Z] 22:27:42     INFO - TEST-INFO | Main app process: exit 1
[task 2022-06-29T22:27:42.809Z] 22:27:42     INFO - Buffered messages finished
[task 2022-06-29T22:27:42.809Z] 22:27:42    ERROR - TEST-UNEXPECTED-FAIL | Last test finished | application terminated with exit code 1
[task 2022-06-29T22:27:42.810Z] 22:27:42     INFO - runtests.py | Application ran for: 0:00:15.947735
[task 2022-06-29T22:27:42.810Z] 22:27:42     INFO - zombiecheck | Reading PID log: /var/folders/9h/4q8q1w1n3pdf32w4fvzb5c3w000014/T/tmpnyc_n6gmpidlog
[task 2022-06-29T22:27:42.810Z] 22:27:42     INFO - ==> process 2892 launched child process 2893
[task 2022-06-29T22:27:42.811Z] 22:27:42     INFO - ==> process 2892 launched child process 2894
[task 2022-06-29T22:27:42.811Z] 22:27:42     INFO - ==> process 2892 launched child process 2899
[task 2022-06-29T22:27:42.811Z] 22:27:42     INFO - ==> process 2892 launched child process 2900
[task 2022-06-29T22:27:42.811Z] 22:27:42     INFO - ==> process 2892 launched child process 2901
[task 2022-06-29T22:27:42.812Z] 22:27:42     INFO - ==> process 2892 launched child process 2902
[task 2022-06-29T22:27:42.812Z] 22:27:42     INFO - ==> process 2892 launched child process 2903
[task 2022-06-29T22:27:42.812Z] 22:27:42     INFO - zombiecheck | Checking for orphan process with PID: 2893
[task 2022-06-29T22:27:42.812Z] 22:27:42     INFO - zombiecheck | Checking for orphan process with PID: 2894
[task 2022-06-29T22:27:42.813Z] 22:27:42     INFO - zombiecheck | Checking for orphan process with PID: 2899
[task 2022-06-29T22:27:42.813Z] 22:27:42     INFO - zombiecheck | Checking for orphan process with PID: 2900
[task 2022-06-29T22:27:42.813Z] 22:27:42     INFO - zombiecheck | Checking for orphan process with PID: 2901
[task 2022-06-29T22:27:42.814Z] 22:27:42     INFO - zombiecheck | Checking for orphan process with PID: 2902
[task 2022-06-29T22:27:42.814Z] 22:27:42     INFO - zombiecheck | Checking for orphan process with PID: 2903
[task 2022-06-29T22:27:42.817Z] 22:27:42     INFO - mozcrash Copy/paste: /opt/worker/tasks/task_165653895590910/fetches/minidump-stackwalk/minidump-stackwalk --symbols-url=https://symbols.mozilla.org/ --human /var/folders/9h/4q8q1w1n3pdf32w4fvzb5c3w000014/T/tmprc7l2_f2.mozrunner/minidumps/2F47508B-E2D0-478E-BA5F-2B0D6B017CD8.dmp /opt/worker/tasks/task_165653895590910/build/symbols
[task 2022-06-29T22:27:45.521Z] 22:27:45     INFO - mozcrash Saved minidump as /opt/worker/tasks/task_165653895590910/build/blobber_upload_dir/2F47508B-E2D0-478E-BA5F-2B0D6B017CD8.dmp
[task 2022-06-29T22:27:45.521Z] 22:27:45     INFO - mozcrash Saved app info as /opt/worker/tasks/task_165653895590910/build/blobber_upload_dir/2F47508B-E2D0-478E-BA5F-2B0D6B017CD8.extra
[task 2022-06-29T22:27:45.829Z] 22:27:45     INFO - PROCESS-CRASH | Last test finished | application crashed [@ ResidentUniqueDistinguishedAmount(long long*, unsigned int)]
[task 2022-06-29T22:27:45.829Z] 22:27:45     INFO - Mozilla crash reason: MOZ_ASSERT(exInfo.ref_count == topInfo.ref_count)
[task 2022-06-29T22:27:45.829Z] 22:27:45     INFO - Crash dump filename: /var/folders/9h/4q8q1w1n3pdf32w4fvzb5c3w000014/T/tmprc7l2_f2.mozrunner/minidumps/2F47508B-E2D0-478E-BA5F-2B0D6B017CD8.dmp
[task 2022-06-29T22:27:45.829Z] 22:27:45     INFO - Operating system: Mac OS X
[task 2022-06-29T22:27:45.829Z] 22:27:45     INFO -                   10.15.7 19H524
[task 2022-06-29T22:27:45.829Z] 22:27:45     INFO - CPU: amd64
[task 2022-06-29T22:27:45.829Z] 22:27:45     INFO -      family 6 model 158 stepping 10
[task 2022-06-29T22:27:45.829Z] 22:27:45     INFO -      12 CPUs
[task 2022-06-29T22:27:45.829Z] 22:27:45     INFO - 
[task 2022-06-29T22:27:45.829Z] 22:27:45     INFO - Crash reason:  EXC_BAD_ACCESS / KERN_INVALID_ADDRESS
[task 2022-06-29T22:27:45.829Z] 22:27:45     INFO - Crash address: 0x0
[task 2022-06-29T22:27:45.829Z] 22:27:45     INFO - Mac Crash Info:
[task 2022-06-29T22:27:45.829Z] 22:27:45     INFO - 
[task 2022-06-29T22:27:45.829Z] 22:27:45     INFO - Process uptime: 7 seconds
[task 2022-06-29T22:27:45.829Z] 22:27:45     INFO - 
[task 2022-06-29T22:27:45.829Z] 22:27:45     INFO - Thread 7 StreamTrans #2 (crashed)
[task 2022-06-29T22:27:45.829Z] 22:27:45     INFO -  0  XUL!ResidentUniqueDistinguishedAmount(long long*, unsigned int) [nsMemoryReporterManager.cpp:71d40a0a3c9cd9386ad322ff90c17157a89a344a : 539 + 0x29]
[task 2022-06-29T22:27:45.829Z] 22:27:45     INFO -      rax = 0x0000000115cbade0    rdx = 0x0000000000000000
[task 2022-06-29T22:27:45.829Z] 22:27:45     INFO -      rcx = 0x00000001051313e8    rbx = 0x000070000b11d9dc
[task 2022-06-29T22:27:45.829Z] 22:27:45     INFO -      rsi = 0x00000000000120a8    rdi = 0x00007fff901caca8
[task 2022-06-29T22:27:45.829Z] 22:27:45     INFO -      rbp = 0x000070000b11da50    rsp = 0x000070000b11d980

Comment 1

[Andrew McCreight [:mccr8]]

Looks similar to bug 1777220.