Closed Bug 1525254 Opened 5 years ago Closed 5 years ago

Intermittent leakcheck | tab 219384 bytes leaked (APZEventState, ActiveElementManager, AsyncFreeSnowWhite, AtomSet, BackstagePass, ...)

Categories

(Core :: DOM: Service Workers, defect, P3)

defect

Tracking

()

RESOLVED FIXED
Tracking Status
firefox-esr60 --- unaffected
firefox-esr68 --- unaffected
firefox68 --- unaffected
firefox69 --- unaffected
firefox70 --- fixed

People

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

References

(Regression)

Details

(Keywords: intermittent-failure, memory-leak, regression, Whiteboard: [stockwell needswork:owner])

#[markdown(off)]
Filed by: rmaries [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=226118938&repo=mozilla-central

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

[task 2019-02-05T11:49:15.183Z] 11:49:15 INFO - TEST-UNEXPECTED-FAIL | leakcheck | tab 219384 bytes leaked (APZEventState, ActiveElementManager, AsyncFreeSnowWhite, AtomSet, BackstagePass, ...)
[task 2019-02-05T11:49:15.184Z] 11:49:15 INFO -
[task 2019-02-05T11:49:15.185Z] 11:49:15 INFO - leakcheck | Processing leak log file /tmp/tmp0Xltct.mozrunner/runtests_leaks_tab_pid3780.log
[task 2019-02-05T11:49:15.186Z] 11:49:15 INFO -
[task 2019-02-05T11:49:15.186Z] 11:49:15 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 3780
[task 2019-02-05T11:49:15.188Z] 11:49:15 INFO -
[task 2019-02-05T11:49:15.189Z] 11:49:15 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2019-02-05T11:49:15.190Z] 11:49:15 INFO - | | Per-Inst Leaked| Total Rem|
[task 2019-02-05T11:49:15.191Z] 11:49:15 INFO - 0 |TOTAL | 47 0| 22424 0|
[task 2019-02-05T11:49:15.193Z] 11:49:15 INFO -
[task 2019-02-05T11:49:15.194Z] 11:49:15 INFO - nsTraceRefcnt::DumpStatistics: 412 entries
[task 2019-02-05T11:49:15.202Z] 11:49:15 INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2019-02-05T11:49:15.204Z] 11:49:15 INFO - leakcheck | Processing leak log file /tmp/tmp0Xltct.mozrunner/runtests_leaks_tab_pid3662.log
[task 2019-02-05T11:49:15.205Z] 11:49:15 INFO -
[task 2019-02-05T11:49:15.206Z] 11:49:15 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 3662
[task 2019-02-05T11:49:15.208Z] 11:49:15 INFO -
[task 2019-02-05T11:49:15.209Z] 11:49:15 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2019-02-05T11:49:15.210Z] 11:49:15 INFO - | | Per-Inst Leaked| Total Rem|
[task 2019-02-05T11:49:15.211Z] 11:49:15 INFO - 0 |TOTAL | 43 0| 39702 0|
[task 2019-02-05T11:49:15.213Z] 11:49:15 INFO -
[task 2019-02-05T11:49:15.214Z] 11:49:15 INFO - nsTraceRefcnt::DumpStatistics: 853 entries
[task 2019-02-05T11:49:15.215Z] 11:49:15 INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2019-02-05T11:49:15.217Z] 11:49:15 INFO - leakcheck | Processing leak log file /tmp/tmp0Xltct.mozrunner/runtests_leaks_tab_pid3598.log
[task 2019-02-05T11:49:15.218Z] 11:49:15 INFO -
[task 2019-02-05T11:49:15.219Z] 11:49:15 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 3598
[task 2019-02-05T11:49:15.222Z] 11:49:15 INFO -
[task 2019-02-05T11:49:15.223Z] 11:49:15 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2019-02-05T11:49:15.224Z] 11:49:15 INFO - | | Per-Inst Leaked| Total Rem|
[task 2019-02-05T11:49:15.225Z] 11:49:15 INFO - 0 |TOTAL | 33 0| 363493 0|
[task 2019-02-05T11:49:15.225Z] 11:49:15 INFO -
[task 2019-02-05T11:49:15.226Z] 11:49:15 INFO - nsTraceRefcnt::DumpStatistics: 974 entries
[task 2019-02-05T11:49:15.227Z] 11:49:15 INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2019-02-05T11:49:15.230Z] 11:49:15 INFO - leakcheck | Processing leak log file /tmp/tmp0Xltct.mozrunner/runtests_leaks_tab_pid3478.log
[task 2019-02-05T11:49:15.231Z] 11:49:15 INFO -
[task 2019-02-05T11:49:15.232Z] 11:49:15 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 3478
[task 2019-02-05T11:49:15.233Z] 11:49:15 INFO -
[task 2019-02-05T11:49:15.234Z] 11:49:15 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2019-02-05T11:49:15.235Z] 11:49:15 INFO - | | Per-Inst Leaked| Total Rem|
[task 2019-02-05T11:49:15.236Z] 11:49:15 INFO - 0 |TOTAL | 38 0| 198118 0|
[task 2019-02-05T11:49:15.236Z] 11:49:15 INFO -
[task 2019-02-05T11:49:15.238Z] 11:49:15 INFO - nsTraceRefcnt::DumpStatistics: 791 entries
[task 2019-02-05T11:49:15.239Z] 11:49:15 INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2019-02-05T11:49:15.242Z] 11:49:15 INFO - leakcheck | Processing leak log file /tmp/tmp0Xltct.mozrunner/runtests_leaks.log
[task 2019-02-05T11:49:15.243Z] 11:49:15 INFO -
[task 2019-02-05T11:49:15.244Z] 11:49:15 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, default process 3406
[task 2019-02-05T11:49:15.245Z] 11:49:15 INFO -
[task 2019-02-05T11:49:15.245Z] 11:49:15 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2019-02-05T11:49:15.246Z] 11:49:15 INFO - | | Per-Inst Leaked| Total Rem|
[task 2019-02-05T11:49:15.248Z] 11:49:15 INFO - 0 |TOTAL | 41 22904|59288572 269|
[task 2019-02-05T11:49:15.249Z] 11:49:15 INFO - 99 |BrowsingContext | 160 160| 148 1|
[task 2019-02-05T11:49:15.250Z] 11:49:15 INFO - 100 |BrowsingContextGroup | 88 88| 23 1|
[task 2019-02-05T11:49:15.254Z] 11:49:15 INFO - 153 |ChannelEventQueue | 200 200| 837 1|
[task 2019-02-05T11:49:15.255Z] 11:49:15 INFO - 155 |ChannelWrapper::ChannelWrapperStub | 32 32| 863 1|
[task 2019-02-05T11:49:15.256Z] 11:49:15 INFO - 212 |CondVar | 80 400| 2228 5|
[task 2019-02-05T11:49:15.257Z] 11:49:15 INFO - 227 |ConsoleReportCollector | 104 104| 1660 1|
[task 2019-02-05T11:49:15.257Z] 11:49:15 INFO - 231 |ContentParent | 1672 1672| 11 1|
[task 2019-02-05T11:49:15.258Z] 11:49:15 INFO - 281 |DataStorage | 424 1272| 4 3|
[task 2019-02-05T11:49:15.259Z] 11:49:15 INFO - 472 |HttpBaseChannel | 1416 1416| 1290 1|
[task 2019-02-05T11:49:15.260Z] 11:49:15 INFO - 473 |HttpChannelParent | 288 288| 837 1|
[task 2019-02-05T11:49:15.261Z] 11:49:15 INFO - 475 |HttpChannelParentListener | 96 96| 684 1|
[task 2019-02-05T11:49:15.263Z] 11:49:15 INFO - 537 |InterceptedHttpChannel | 1688 1688| 170 1|
[task 2019-02-05T11:49:15.263Z] 11:49:15 INFO - 572 |LoadContext | 104 208| 1100 2|
[task 2019-02-05T11:49:15.264Z] 11:49:15 INFO - 574 |LoadInfo | 352 352| 2413 1|
[task 2019-02-05T11:49:15.265Z] 11:49:15 INFO - 627 |Mutex | 72 1224| 27886 17|
[task 2019-02-05T11:49:15.266Z] 11:49:15 INFO - 761 |PBrowserParent | 328 328| 22 1|
[task 2019-02-05T11:49:15.266Z] 11:49:15 INFO - 862 |PContentParent | 1192 1192| 11 1|
[task 2019-02-05T11:49:15.267Z] 11:49:15 INFO - 882 |PHttpChannelParent | 40 40| 837 1|
[task 2019-02-05T11:49:15.267Z] 11:49:15 INFO - 1018 |PollableEvent | 32 32| 1 1|
[task 2019-02-05T11:49:15.268Z] 11:49:15 INFO - 1073 |ReentrantMonitor | 40 40| 1311 1|
[task 2019-02-05T11:49:15.269Z] 11:49:15 INFO - 1074 |RefCountedMonitor | 160 160| 255 1|
[task 2019-02-05T11:49:15.270Z] 11:49:15 INFO - 1100 |RequestContextService | 80 80| 1 1|
[task 2019-02-05T11:49:15.271Z] 11:49:15 INFO - 1156 |Service | 224 224| 1 1|
[task 2019-02-05T11:49:15.271Z] 11:49:15 INFO - 1162 |ServiceWorkerInterceptController | 24 24| 701 1|
[task 2019-02-05T11:49:15.272Z] 11:49:15 INFO - 1199 |SharedMemory | 32 32| 1029 1|
[task 2019-02-05T11:49:15.273Z] 11:49:15 INFO - 1280 |TabParent | 1272 1272| 22 1|
[task 2019-02-05T11:49:15.274Z] 11:49:15 INFO - 1299 |ThirdPartyUtil | 32 32| 1 1|
[task 2019-02-05T11:49:15.274Z] 11:49:15 INFO - 1400 |WeakReference<BrowsingContext> | 32 64| 435 2|
[task 2019-02-05T11:49:15.275Z] 11:49:15 INFO - 1407 |WeakReference<PBrowserParent> | 32 32| 22 1|
[task 2019-02-05T11:49:15.276Z] 11:49:15 INFO - 1412 |WeakReference<PContentParent> | 32 32| 22 1|
[task 2019-02-05T11:49:15.277Z] 11:49:15 INFO - 1424 |WeakReference<WebExtensionPolicy> | 32 64| 2880 2|
[task 2019-02-05T11:49:15.277Z] 11:49:15 INFO - 1625 |ipc::MessageChannel | 528 528| 380 1|
[task 2019-02-05T11:49:15.278Z] 11:49:15 INFO - 1626 |ipc::MessageChannel::DispatchOnChannel| 40 40| 380 1|
[task 2019-02-05T11:49:15.279Z] 11:49:15 INFO - 1760 |nsAuthURLParser | 24 24| 408 1|
[task 2019-02-05T11:49:15.280Z] 11:49:15 INFO - 1795 |nsCategoryObserver | 104 104| 6 1|
[task 2019-02-05T11:49:15.281Z] 11:49:15 INFO - 1826 |nsCookiePermission | 56 56| 1 1|
[task 2019-02-05T11:49:15.281Z] 11:49:15 INFO - 1827 |nsCookieService | 336 336| 1 1|
[task 2019-02-05T11:49:15.282Z] 11:49:15 INFO - 1887 |nsEffectiveTLDService | 1048 1048| 1 1|
[task 2019-02-05T11:49:15.283Z] 11:49:15 INFO - 1963 |nsHttpAuthCache::OriginClearObserver | 32 64| 2 2|
[task 2019-02-05T11:49:15.283Z] 11:49:15 INFO - 1970 |nsHttpConnectionMgr | 440 440| 1 1|
[task 2019-02-05T11:49:15.284Z] 11:49:15 INFO - 1972 |nsHttpHandler | 960 960| 1 1|
[task 2019-02-05T11:49:15.285Z] 11:49:15 INFO - 1973 |nsHttpRequestHead | 184 184| 1346 1|
[task 2019-02-05T11:49:15.286Z] 11:49:15 INFO - 1977 |nsIDNService | 152 152| 1 1|
[task 2019-02-05T11:49:15.286Z] 11:49:15 INFO - 1979 |nsIOService | 312 312| 1 1|
[task 2019-02-05T11:49:15.287Z] 11:49:15 INFO - 2012 |nsJSPrincipals | 24 96| 20963 4|
[task 2019-02-05T11:49:15.288Z] 11:49:15 INFO - 2022 |nsLocalFile | 192 768| 8810 4|
[task 2019-02-05T11:49:15.289Z] 11:49:15 INFO - 2025 |nsMainThreadPtrHolder<T> | 40 160| 3468 4|
[task 2019-02-05T11:49:15.290Z] 11:49:15 INFO - 2061 |nsNodeWeakReference | 40 40| 14528 1|
[task 2019-02-05T11:49:15.290Z] 11:49:15 INFO - 2062 |nsNotifyAddrListener | 128 128| 1 1|
[task 2019-02-05T11:49:15.291Z] 11:49:15 INFO - 2079 |nsPermissionManager | 200 200| 1 1|
[task 2019-02-05T11:49:15.292Z] 11:49:15 INFO - 2086 |nsPrefBranch | 112 224| 62 2|
[task 2019-02-05T11:49:15.293Z] 11:49:15 INFO - 2096 |nsProxyInfo | 104 104| 1096 1|
[task 2019-02-05T11:49:15.293Z] 11:49:15 INFO - 2101 |nsRedirectHistoryEntry | 56 56| 317 1|
[task 2019-02-05T11:49:15.294Z] 11:49:15 INFO - 2133 |nsSiteSecurityService | 96 96| 1 1|
[task 2019-02-05T11:49:15.295Z] 11:49:15 INFO - 2137 |nsSocketTransportService | 328 328| 1 1|
[task 2019-02-05T11:49:15.296Z] 11:49:15 INFO - 2138 |nsStandardURL | 248 1240| 36212 5|
[task 2019-02-05T11:49:15.296Z] 11:49:15 INFO - 2143 |nsStreamConverterService | 64 64| 1 1|
[task 2019-02-05T11:49:15.297Z] 11:49:15 INFO - 2148 |nsStringBuffer | 12 1536| 469687 128|
[task 2019-02-05T11:49:15.298Z] 11:49:15 INFO - 2197 |nsTArray_base | 8 256|28259756 32|
[task 2019-02-05T11:49:15.299Z] 11:49:15 INFO - 2243 |nsVariant | 72 72| 1602 1|
[task 2019-02-05T11:49:15.299Z] 11:49:15 INFO - 2248 |nsWeakReference | 40 440| 2314 11|
[task 2019-02-05T11:49:15.300Z] 11:49:15 INFO -
[task 2019-02-05T11:49:15.301Z] 11:49:15 INFO - nsTraceRefcnt::DumpStatistics: 2340 entries

Last test run: dom/serviceworkers/test/test_xslt.html

Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → INCOMPLETE

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=240386828&repo=autoland&lineNumber=4993

17:13:44 INFO - TEST-UNEXPECTED-FAIL | leakcheck | tab 187295 bytes leaked (APZEventState, ActiveElementManager, AsyncFreeSnowWhite, AtomSet, BackstagePass, ...)
17:13:44 INFO -
17:13:44 INFO - leakcheck | Processing leak log file c:\users\task_1555347421\appdata\local\temp\tmpyl22dj.mozrunner\runtests_leaks_tab_pid5008.log
17:13:44 INFO -
17:13:44 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 5008
17:13:44 INFO -
17:13:44 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
17:13:44 INFO - | | Per-Inst Leaked| Total Rem|
17:13:44 INFO - 0 |TOTAL | 28 44| 30025 1|
17:13:44 INFO - 228 |Mutex | 44 44| 394 1|
17:13:44 INFO -
17:13:44 INFO - nsTraceRefcnt::DumpStatistics: 856 entries
17:13:44 INFO - TEST-INFO | leakcheck | tab leaked 1 Mutex
17:13:44 INFO - WARNING | leakcheck | tab 44 bytes leaked (Mutex)
17:13:44 INFO -
17:13:44 INFO - leakcheck | Processing leak log file c:\users\task_1555347421\appdata\local\temp\tmpyl22dj.mozrunner\runtests_leaks_tab_pid5524.log
17:13:44 INFO -
17:13:44 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 5524
17:13:44 INFO -
17:13:44 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
17:13:44 INFO - | | Per-Inst Leaked| Total Rem|
17:13:44 INFO - 0 |TOTAL | 26 0| 14152 0|
17:13:44 INFO -
17:13:44 INFO - nsTraceRefcnt::DumpStatistics: 417 entries
17:13:44 INFO - TEST-PASS | leakcheck | tab no leaks detected!
17:13:44 INFO - leakcheck | Processing leak log file c:\users\task_1555347421\appdata\local\temp\tmpyl22dj.mozrunner\runtests_leaks_tab_pid5572.log
17:13:44 INFO -
17:13:44 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 5572
17:13:44 INFO -
17:13:44 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
17:13:44 INFO - | | Per-Inst Leaked| Total Rem|
17:13:44 INFO - 0 |TOTAL | 28 44| 30025 1|
17:13:44 INFO - 228 |Mutex | 44 44| 394 1|
17:13:44 INFO -
17:13:44 INFO - nsTraceRefcnt::DumpStatistics: 856 entries

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 5 years ago5 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Priority: -- → P3

Hi Jed, can the occurrences here be from Bug 1511556 ?

Looks like this appeared first when that landed: https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2019-07-18&endday=2019-07-25&tree=trunk&bug=1525254

Flags: needinfo?(jld)

(In reply to Andreea Pavel [:apavel] from comment #16)

Hi Jed, can the occurrences here be from Bug 1511556 ?

No. The code I changed in that bug is used only when the crash reporter is disabled at build time. We do that for Address Sanitizer builds, but not our normal builds, and I don't see any ASan builds in that spike of failures.

Flags: needinfo?(jld)

Leaving this for Cosmin to continue.

Flags: needinfo?(csabou)
Regressed by: 1568055
See Also: → 1568517

(In reply to Cosmin Sabou [:CosminS] from comment #19)

Nika, could you please take a look over this? https://treeherder.mozilla.org/#/jobs?repo=autoland&searchStr=macosx1014-64%2Cdebug%2Cweb-platform-tests-e10s-8&tochange=792bb52098f764738202f6e0a5d5588b20a65639&fromchange=266931d06efb975e630a88f7ba422aef8ffbebaa&selectedJob=257895250

https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=257895250&repo=autoland&lineNumber=74273

I introduced a leak which looked a lot like this in that patch, but the part of my patch which made this non-intermittent was backed out in bug 1568055 comment 4. The ongoing intermittent issue is unlikely to be my fault.

Flags: needinfo?(nika)
Has Regression Range: --- → yes
You need to log in before you can comment on or make changes to this bug.