1839581 - Intermittent leakcheck | tab 300289 bytes leaked (APZEventState, ActiveElementManager, AsyncFreeSnowWhite, AttributeStyles, BackstagePass, ...)

Status: RESOLVED FIXED

(Core :: Panning and Zooming, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2023-06-21T08:05:49.320Z] 08:05:49     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2023-06-21T08:05:49.321Z] 08:05:49     INFO - leakcheck | Processing leak log file /tmp/tmp__4a_18p.mozrunner/runtests_leaks_tab_pid9738.log
[task 2023-06-21T08:05:49.322Z] 08:05:49     INFO - 
[task 2023-06-21T08:05:49.322Z] 08:05:49     INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 9738
[task 2023-06-21T08:05:49.323Z] 08:05:49     INFO - 
[task 2023-06-21T08:05:49.323Z] 08:05:49     INFO -      |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2023-06-21T08:05:49.324Z] 08:05:49     INFO -      |                                      | Per-Inst   Leaked|   Total      Rem|
[task 2023-06-21T08:05:49.325Z] 08:05:49     INFO -    0 |TOTAL                                 |       60   300289|  120312     3621|
[task 2023-06-21T08:05:49.325Z] 08:05:49     INFO -    2 |APZEventState                         |      192      384|       5        2|
[task 2023-06-21T08:05:49.326Z] 08:05:49     INFO -    6 |ActiveElementManager                  |       40       80|       5        2|
[task 2023-06-21T08:05:49.326Z] 08:05:49     INFO -   18 |AsyncFreeSnowWhite                    |       40       40|       1        1|
[task 2023-06-21T08:05:49.327Z] 08:05:49     INFO -   21 |AttributeStyles                       |      136      272|      14        2|
[task 2023-06-21T08:05:49.328Z] 08:05:49     INFO -   26 |BackstagePass                         |      168      168|       1        1|
[task 2023-06-21T08:05:49.328Z] 08:05:49     INFO -   30 |BrowserChild                          |      936     1872|       5        2|
[task 2023-06-21T08:05:49.329Z] 08:05:49     INFO -   32 |BrowserChildMessageManager            |      168      336|       5        2|
[task 2023-06-21T08:05:49.329Z] 08:05:49     INFO -   33 |BrowsingContext                       |     1200     3600|       6        3|
[task 2023-06-21T08:05:49.330Z] 08:05:49     INFO -   34 |BrowsingContextGroup                  |      280      280|       1        1|
[task 2023-06-21T08:05:49.330Z] 08:05:49     INFO -   48 |ChannelEventQueue                     |      192      384|      25        2|
[task 2023-06-21T08:05:49.331Z] 08:05:49     INFO -   50 |CharacterData                         |      128     3584|     117       28|
[task 2023-06-21T08:05:49.331Z] 08:05:49     INFO -   52 |ChildDNSService                       |      184      184|       1        1|
[task 2023-06-21T08:05:49.332Z] 08:05:49     INFO -   60 |ChildSHistory                         |      176      352|       5        2|
[task 2023-06-21T08:05:49.332Z] 08:05:49     INFO -   71 |ComponentModuleLoader                 |      224      224|       1        1|
[task 2023-06-21T08:05:49.333Z] 08:05:49     INFO -   78 |CondVar                               |       80      400|      45        5|
[task 2023-06-21T08:05:49.333Z] 08:05:49     INFO -   82 |ConsoleReportCollector                |      104      624|      55        6|
[task 2023-06-21T08:05:49.334Z] 08:05:49     INFO -   85 |CookieJarSettings                     |       88      176|      24        2|
[task 2023-06-21T08:05:49.335Z] 08:05:49     INFO -   87 |CopyOnWriteValue                      |       40       40|       1        1|
[task 2023-06-21T08:05:49.335Z] 08:05:49     INFO -   91 |CustomElementReactionsStack           |      152      152|       1        1|
[task 2023-06-21T08:05:49.336Z] 08:05:49     INFO -   92 |CycleCollectedJSContext               |    27696    27696|       1        1|
[task 2023-06-21T08:05:49.336Z] 08:05:49     INFO -   93 |CycleCollectedJSRuntime               |      520      520|       1        1|
[task 2023-06-21T08:05:49.337Z] 08:05:49     INFO -   95 |DNSServiceBase                        |       32       32|       1        1|
[task 2023-06-21T08:05:49.337Z] 08:05:49     INFO -   96 |DOMArena                              |       24       24|       6        1|
[task 2023-06-21T08:05:49.338Z] 08:05:49     INFO -   98 |DOMEventTargetHelper                  |      120      960|      55        8|
[task 2023-06-21T08:05:49.338Z] 08:05:49     INFO -  107 |DR_State                              |       48       48|       1        1|
[task 2023-06-21T08:05:49.339Z] 08:05:49     INFO -  117 |DeferredDNSPrefetches                 |     8264     8264|       1        1|
[task 2023-06-21T08:05:49.339Z] 08:05:49     INFO -  119 |DelayedFireSingleTapEvent             |       80       80|       7        1|
[task 2023-06-21T08:05:49.340Z] 08:05:49     INFO -  122 |DocGroup                              |      152      152|       6        1|
[task 2023-06-21T08:05:49.340Z] 08:05:49     INFO -  123 |Document                              |     2792     5584|      15        2|
[task 2023-06-21T08:05:49.341Z] 08:05:49     INFO -  135 |DocumentType                          |      176      352|       5        2|
[task 2023-06-21T08:05:49.342Z] 08:05:49     INFO -  137 |DrawTargetSkia                        |      216      432|       6        2|
[task 2023-06-21T08:05:49.342Z] 08:05:49     INFO -  143 |EventCounts                           |       48       96|      14        2|
[task 2023-06-21T08:05:49.343Z] 08:05:49     INFO -  144 |EventListenerManager                  |      128      256|      67        2|
[task 2023-06-21T08:05:49.343Z] 08:05:49     INFO -  148 |ExpirationTrackerObserver             |       32       32|      11        1|
[task 2023-06-21T08:05:49.344Z] 08:05:49     INFO -  157 |FeaturePolicy                         |      136      408|      18        3|
[task 2023-06-21T08:05:49.344Z] 08:05:49     INFO -  165 |FontFace                              |       80      160|      14        2|
[task 2023-06-21T08:05:49.345Z] 08:05:49     INFO -  166 |FontFaceImpl                          |       88      176|      14        2|
[task 2023-06-21T08:05:49.345Z] 08:05:49     INFO -  167 |FontFaceSet                           |      160      320|      14        2|
[task 2023-06-21T08:05:49.346Z] 08:05:49     INFO -  168 |FontFaceSetDocumentImpl               |      336      672|      14        2|
[task 2023-06-21T08:05:49.346Z] 08:05:49     INFO -  169 |FontFaceSetImpl                       |      304      608|      14        2|
[task 2023-06-21T08:05:49.347Z] 08:05:49     INFO -  172 |FragmentOrElement                     |      112     2352|     294       21|
[task 2023-06-21T08:05:49.347Z] 08:05:49     INFO -  187 |GlobalImageObserver                   |       24       24|       1        1|
[task 2023-06-21T08:05:49.348Z] 08:05:49     INFO -  189 |GlobalStyleSheetCache                 |      160      160|       1        1|
[task 2023-06-21T08:05:49.349Z] 08:05:49     INFO -  190 |HTMLAnchorElement                     |      176      176|       3        1|
[task 2023-06-21T08:05:49.349Z] 08:05:49     INFO -  191 |HTMLBodyElement                       |      136      272|      14        2|
[task 2023-06-21T08:05:49.350Z] 08:05:49     INFO -  194 |HTMLLinkElement                       |      216      216|       5        1|
[task 2023-06-21T08:05:49.350Z] 08:05:49     INFO -  200 |HTMLMetaElement                       |      136      408|       9        3|
[task 2023-06-21T08:05:49.350Z] 08:05:49     INFO -  202 |HTMLScriptElement                     |      224     1792|      21        8|
[task 2023-06-21T08:05:49.351Z] 08:05:49     INFO -  204 |HTMLTitleElement                      |      168      336|       5        2|
[task 2023-06-21T08:05:49.352Z] 08:05:49     INFO -  213 |HttpChannelChild                      |     2064     4128|      25        2|
[task 2023-06-21T08:05:49.352Z] 08:05:49     INFO -  217 |IAPZCTreeManager                      |       16       32|       5        2|
[task 2023-06-21T08:05:49.353Z] 08:05:49     INFO -  233 |ImageLoader                           |      104      208|      15        2|
[task 2023-06-21T08:05:49.354Z] 08:05:49     INFO -  236 |ImageTracker                          |       56      112|      14        2|
[task 2023-06-21T08:05:49.355Z] 08:05:49     INFO -  241 |JSActor                               |      136      272|      74        2|
[task 2023-06-21T08:05:49.355Z] 08:05:49     INFO -  249 |JSRuntime                             |    42176    42176|       1        1|
[task 2023-06-21T08:05:49.356Z] 08:05:49     INFO -  251 |JSWindowActorChild                    |      152      304|      74        2|
[task 2023-06-21T08:05:49.357Z] 08:05:49     INFO -  256 |LoadInfo                              |      496      992|     108        2|
[task 2023-06-21T08:05:49.358Z] 08:05:49     INFO -  257 |LoadedScript                          |       48      336|      72        7|
[task 2023-06-21T08:05:49.358Z] 08:05:49     INFO -  258 |Loader                                |      168      336|      15        2|
[task 2023-06-21T08:05:49.359Z] 08:05:49     INFO -  262 |Location                              |       88      176|       5        2|
[task 2023-06-21T08:05:49.360Z] 08:05:49     INFO -  289 |ModuleLoaderBase                      |      168      504|      15        3|
[task 2023-06-21T08:05:49.360Z] 08:05:49     INFO -  297 |Mutex                                 |       72     5832|    1312       81|
[task 2023-06-21T08:05:49.361Z] 08:05:49     INFO -  301 |NoOpDNSListener                       |       24       24|       1        1|
[task 2023-06-21T08:05:49.362Z] 08:05:49     INFO -  304 |NodeInfo                              |      128     2560|     164       20|
[task 2023-06-21T08:05:49.362Z] 08:05:49     INFO -  310 |ObserverTable                         |       56       56|       1        1|
[task 2023-06-21T08:05:49.363Z] 08:05:49     INFO -  313 |OnloadBlocker                         |       40       80|      15        2|
[task 2023-06-21T08:05:49.364Z] 08:05:49     INFO -  319 |PAPZCTreeManagerChild                 |       56      112|       5        2|
[task 2023-06-21T08:05:49.364Z] 08:05:49     INFO -  347 |PBrowserChild                         |      120      240|       5        2|
[task 2023-06-21T08:05:49.365Z] 08:05:49     INFO -  415 |PHttpChannelChild                     |       56      112|      25        2|
[task 2023-06-21T08:05:49.366Z] 08:05:49     INFO -  441 |PWindowGlobalChild                    |       56      112|      10        2|
[task 2023-06-21T08:05:49.366Z] 08:05:49     INFO -  448 |Performance                           |     6216    12432|      14        2|
[task 2023-06-21T08:05:49.367Z] 08:05:49     INFO -  449 |PerformanceEntry                      |       72     1080|     111       15|
[task 2023-06-21T08:05:49.368Z] 08:05:49     INFO -  450 |PerformanceEventTiming                |      216      216|      73        1|
[task 2023-06-21T08:05:49.368Z] 08:05:49     INFO -  451 |PerformanceMainThread                 |     6352    12704|      14        2|
[task 2023-06-21T08:05:49.369Z] 08:05:49     INFO -  452 |PerformanceNavigationTiming           |      128      256|      14        2|
[task 2023-06-21T08:05:49.369Z] 08:05:49     INFO -  453 |PerformancePaintTiming                |      104      104|       4        1|
[task 2023-06-21T08:05:49.370Z] 08:05:49     INFO -  454 |PerformanceResourceTiming             |      128     1664|      34       13|
[task 2023-06-21T08:05:49.371Z] 08:05:49     INFO -  455 |Permission                            |       72      288|      21        4|
[task 2023-06-21T08:05:49.371Z] 08:05:49     INFO -  456 |PermissionDelegateHandler             |       48       48|      12        1|
[task 2023-06-21T08:05:49.372Z] 08:05:49     INFO -  457 |PermissionKey                         |       32      224|      25        7|
[task 2023-06-21T08:05:49.372Z] 08:05:49     INFO -  458 |PermissionManager                     |     1440     1440|       1        1|
[task 2023-06-21T08:05:49.373Z] 08:05:49     INFO -  471 |PrincipalHashKey                      |        8        8|      35        1|
[task 2023-06-21T08:05:49.374Z] 08:05:49     INFO -  477 |ProgressTracker                       |      144      144|       1        1|
[task 2023-06-21T08:05:49.374Z] 08:05:49     INFO -  481 |ProtoAndIfaceCache                    |       16       48|      13        3|
[task 2023-06-21T08:05:49.375Z] 08:05:49     INFO -  483 |PuppetBidiKeyboard                    |       32       32|       1        1|
[task 2023-06-21T08:05:49.376Z] 08:05:49     INFO -  484 |PuppetWidget                          |      896     1792|       5        2|
[task 2023-06-21T08:05:49.376Z] 08:05:49     INFO -  489 |RasterImage                           |      264      264|       1        1|
[task 2023-06-21T08:05:49.377Z] 08:05:49     INFO -  492 |ReferrerInfo                          |       72     2232|     146       31|
[task 2023-06-21T08:05:49.377Z] 08:05:49     INFO -  505 |RequestContext                        |      120      240|       7        2|
[task 2023-06-21T08:05:49.378Z] 08:05:49     INFO -  506 |RequestContextService                 |       80       80|       1        1|
[task 2023-06-21T08:05:49.379Z] 08:05:49     INFO -  510 |RestoreTabContentObserver             |       24       24|       1        1|
[task 2023-06-21T08:05:49.379Z] 08:05:49     INFO -  511 |RuleNode                              |       80     6640|     412       83|
[task 2023-06-21T08:05:49.380Z] 08:05:49     INFO -  517 |ScriptFetchOptions                    |       40      280|     123        7|
[task 2023-06-21T08:05:49.380Z] 08:05:49     INFO -  521 |ScriptLoader                          |      328      656|      15        2|
[task 2023-06-21T08:05:49.381Z] 08:05:49     INFO -  529 |ServoArc                              |        8     6120|    2996      765|
[task 2023-06-21T08:05:49.381Z] 08:05:49     INFO -  531 |SessionStorageManager                 |      176      352|       5        2|
[task 2023-06-21T08:05:49.382Z] 08:05:49     INFO -  532 |SessionStorageObserver                |       24       24|       1        1|
[task 2023-06-21T08:05:49.382Z] 08:05:49     INFO -  542 |SharedStringBundle                    |      152     1976|      13       13|
[task 2023-06-21T08:05:49.382Z] 08:05:49     INFO -  543 |SharedStringMap                       |       56      168|       3        3|
[task 2023-06-21T08:05:49.383Z] 08:05:49     INFO -  544 |SharedStyleSheetCache                 |      184      184|       1        1|
[task 2023-06-21T08:05:49.383Z] 08:05:49     INFO -  549 |SheetLoadDataHashKey                  |      104      416|      24        4|
[task 2023-06-21T08:05:49.383Z] 08:05:49     INFO -  557 |StorageObserver                       |       88       88|       1        1|
[task 2023-06-21T08:05:49.384Z] 08:05:49     INFO -  564 |StyleSheet                            |      176     2816|      20       16|
[task 2023-06-21T08:05:49.384Z] 08:05:49     INFO -  565 |StyleSheetInfo                        |      264     3960|      16       15|
[task 2023-06-21T08:05:49.385Z] 08:05:49     INFO -  567 |SubstitutingURL                       |      336     4032|     424       12|
[task 2023-06-21T08:05:49.385Z] 08:05:49     INFO -  582 |ThreadEventTarget                     |       48       96|      14        2|
[task 2023-06-21T08:05:49.385Z] 08:05:49     INFO -  585 |ThreadTargetSink                      |       16       32|      14        2|
[task 2023-06-21T08:05:49.386Z] 08:05:49     INFO -  588 |ThrottledEventQueue                   |       40       80|       2        2|
[task 2023-06-21T08:05:49.386Z] 08:05:49     INFO -  589 |ThrottledEventQueue::Inner            |      272      544|       2        2|
[task 2023-06-21T08:05:49.386Z] 08:05:49     INFO -  594 |TimeoutExecutor                       |       96      384|      20        4|
[task 2023-06-21T08:05:49.387Z] 08:05:49     INFO -  595 |TimeoutSet                            |       56      224|      20        4|
[task 2023-06-21T08:05:49.387Z] 08:05:49     INFO -  602 |UACacheReporter                       |       24       24|       1        1|
[task 2023-06-21T08:05:49.388Z] 08:05:49     INFO -  603 |UIDirectionManager                    |       24       24|       1        1|
[task 2023-06-21T08:05:49.388Z] 08:05:49     INFO -  607 |URLExtraData                          |       40      680|      20       17|
[task 2023-06-21T08:05:49.388Z] 08:05:49     INFO -  620 |WeakReference                         |       32      384|     236       12|
[task 2023-06-21T08:05:49.389Z] 08:05:49     INFO -  631 |WindowContext                         |      464     1392|      12        3|
[task 2023-06-21T08:05:49.389Z] 08:05:49     INFO -  633 |WindowGlobalChild                     |      200      400|      10        2|
[task 2023-06-21T08:05:49.389Z] 08:05:49     INFO -  640 |XPCJSContext                          |      112      112|       1        1|
[task 2023-06-21T08:05:49.390Z] 08:05:49     INFO -  641 |XPCJSRuntime                          |      576      576|       1        1|
[task 2023-06-21T08:05:49.390Z] 08:05:49     INFO -  642 |XPCLocaleCallbacks                    |       40       40|       1        1|
[task 2023-06-21T08:05:49.390Z] 08:05:49     INFO -  644 |XPCNativeInterface                    |       56      112|      46        2|
[task 2023-06-21T08:05:49.390Z] 08:05:49     INFO -  645 |XPCNativeMember                       |       16       32|    2830        2|
[task 2023-06-21T08:05:49.391Z] 08:05:49     INFO -  646 |XPCNativeSet                          |       32       64|      58        2|
[task 2023-06-21T08:05:49.391Z] 08:05:49     INFO -  648 |XPCWrappedNative                      |       96      192|      76        2|
[task 2023-06-21T08:05:49.392Z] 08:05:49     INFO -  649 |XPCWrappedNativeProto                 |       40       80|      13        2|
[task 2023-06-21T08:05:49.392Z] 08:05:49     INFO -  650 |XPCWrappedNativeScope                 |      120      360|      13        3|
[task 2023-06-21T08:05:49.393Z] 08:05:49     INFO -  651 |XPCWrappedNativeTearOff               |       32       96|     112        3|
[task 2023-06-21T08:05:49.393Z] 08:05:49     INFO -  655 |bundleCacheEntry_t                    |       48      672|      14       14|
[task 2023-06-21T08:05:49.393Z] 08:05:49     INFO -  667 |gfxFontEntry                          |      528     1056|      22        2|
[task 2023-06-21T08:05:49.394Z] 08:05:49     INFO -  668 |gfxFontFamily                         |      176      352|      14        2|
[task 2023-06-21T08:05:49.394Z] 08:05:49     INFO -  671 |gfxFontSrcPrincipal                   |       32      128|      28        4|
[task 2023-06-21T08:05:49.394Z] 08:05:49     INFO -  672 |gfxFontSrcURI                         |       48       96|      14        2|
[task 2023-06-21T08:05:49.394Z] 08:05:49     INFO -  678 |image::SourceBuffer                   |      144      144|       1        1|
[task 2023-06-21T08:05:49.395Z] 08:05:49     INFO -  679 |imgCacheEntry                         |       56       56|       1        1|
[task 2023-06-21T08:05:49.395Z] 08:05:49     INFO -  683 |imgRequest                            |      456      456|       1        1|
[task 2023-06-21T08:05:49.396Z] 08:05:49     INFO -  684 |imgRequestProxy                       |      224      224|       1        1|
[task 2023-06-21T08:05:49.396Z] 08:05:49     INFO -  706 |nsAuthURLParser                       |       24       48|       2        2|
[task 2023-06-21T08:05:49.396Z] 08:05:49     INFO -  714 |nsBaseWidget                          |      400      800|       5        2|
[task 2023-06-21T08:05:49.397Z] 08:05:49     INFO -  723 |nsCSPContext                          |      136      544|      20        4|
[task 2023-06-21T08:05:49.397Z] 08:05:49     INFO -  726 |nsCategoryObserver                    |      104      208|       2        2|
[task 2023-06-21T08:05:49.397Z] 08:05:49     INFO -  733 |nsConsoleService                      |      184      184|       1        1|
[task 2023-06-21T08:05:49.398Z] 08:05:49     INFO -  736 |nsContentPolicy                       |       56       56|       1        1|
[task 2023-06-21T08:05:49.398Z] 08:05:49     INFO -  739 |nsContentUtils::UserInteractionObserve|       32       32|       1        1|
[task 2023-06-21T08:05:49.398Z] 08:05:49     INFO -  745 |nsDOMNavigationTiming                 |      200      400|       8        2|
[task 2023-06-21T08:05:49.399Z] 08:05:49     INFO -  746 |nsDOMSlots                            |      112     1456|      83       13|
[task 2023-06-21T08:05:49.399Z] 08:05:49     INFO -  752 |nsDeque                               |      104      208|      46        2|
[task 2023-06-21T08:05:49.400Z] 08:05:49     INFO -  753 |nsDequeBase                           |       96      192|      46        2|
[task 2023-06-21T08:05:49.400Z] 08:05:49     INFO -  773 |nsDocShell::InterfaceRequestorProxy   |       32       64|       5        2|
[task 2023-06-21T08:05:49.400Z] 08:05:49     INFO -  789 |nsFocusManager                        |      200      200|       1        1|
[task 2023-06-21T08:05:49.401Z] 08:05:49     INFO -  798 |nsGenericHTMLElement                  |      136     2856|     230       21|
[task 2023-06-21T08:05:49.401Z] 08:05:49     INFO -  800 |nsGlobalWindowInner                   |      984     1968|      10        2|
[task 2023-06-21T08:05:49.401Z] 08:05:49     INFO -  802 |nsGlobalWindowObserver                |       48       96|      10        2|
[task 2023-06-21T08:05:49.402Z] 08:05:49     INFO -  803 |nsGlobalWindowOuter                   |      488      976|       5        2|
[task 2023-06-21T08:05:49.402Z] 08:05:49     INFO -  810 |nsHtml5AttributeName                  |       32    15968|     509      499|
[task 2023-06-21T08:05:49.402Z] 08:05:49     INFO -  812 |nsHtml5ElementName                    |       40     8280|     217      207|
[task 2023-06-21T08:05:49.403Z] 08:05:49     INFO -  815 |nsHtml5HtmlAttributes                 |      384      384|      39        1|
[task 2023-06-21T08:05:49.403Z] 08:05:49     INFO -  836 |nsHttpAuthCache::OriginClearObserver  |       32       64|       2        2|
[task 2023-06-21T08:05:49.404Z] 08:05:49     INFO -  837 |nsHttpHandler                         |     1280     1280|       1        1|
[task 2023-06-21T08:05:49.404Z] 08:05:49     INFO -  838 |nsHttpRequestHead                     |      176      352|      25        2|
[task 2023-06-21T08:05:49.404Z] 08:05:49     INFO -  839 |nsIContent                            |      112     5488|     411       49|
[task 2023-06-21T08:05:49.405Z] 08:05:49     INFO -  842 |nsIOService                           |      496      496|       1        1|
[task 2023-06-21T08:05:49.405Z] 08:05:49     INFO -  863 |nsJSPrincipals                        |       24      816|     263       34|
[task 2023-06-21T08:05:49.405Z] 08:05:49     INFO -  865 |nsLayoutStatics                       |        1        1|       1        1|
[task 2023-06-21T08:05:49.406Z] 08:05:49     INFO -  869 |nsLoadGroup                           |      224      448|       7        2|
[task 2023-06-21T08:05:49.406Z] 08:05:49     INFO -  872 |nsMainThreadPtrHolder<T>              |       40       40|      33        1|
[task 2023-06-21T08:05:49.407Z] 08:05:49     INFO -  873 |nsMappedAttributes                    |       48       96|      17        2|
[task 2023-06-21T08:05:49.407Z] 08:05:49     INFO -  876 |nsMessageManagerScriptExecutor        |       40       80|       6        2|
[task 2023-06-21T08:05:49.407Z] 08:05:49     INFO -  883 |nsNodeInfoManager                     |      384      768|      15        2|
[task 2023-06-21T08:05:49.407Z] 08:05:49     INFO -  885 |nsNodeWeakReference                   |       40       40|      34        1|
[task 2023-06-21T08:05:49.408Z] 08:05:49     INFO -  887 |nsObserverService                     |       80       80|       1        1|
[task 2023-06-21T08:05:49.408Z] 08:05:49     INFO -  892 |nsPersistentProperties                |      112      112|       1        1|
[task 2023-06-21T08:05:49.408Z] 08:05:49     INFO -  897 |nsPrefBranch                          |      112      112|       3        1|
[task 2023-06-21T08:05:49.409Z] 08:05:49     INFO -  902 |nsProperties                          |       64       64|       1        1|
[task 2023-06-21T08:05:49.409Z] 08:05:49     INFO -  905 |nsRange                               |      240     1200|      20        5|
[task 2023-06-21T08:05:49.409Z] 08:05:49     INFO -  906 |nsRect                                |       16       96|   50210        6|
[task 2023-06-21T08:05:49.410Z] 08:05:49     INFO -  907 |nsRedirectHistoryEntry                |       56      112|       5        2|
[task 2023-06-21T08:05:49.410Z] 08:05:49     INFO -  913 |nsScriptSecurityManager               |       64       64|       1        1|
[task 2023-06-21T08:05:49.411Z] 08:05:49     INFO -  920 |nsSimpleNestedURI                     |      128      256|      18        2|
[task 2023-06-21T08:05:49.411Z] 08:05:49     INFO -  922 |nsSimpleURI                           |      112     1456|     111       13|
[task 2023-06-21T08:05:49.412Z] 08:05:49     INFO -  925 |nsSocketTransportService              |     2784     2784|       1        1|
[task 2023-06-21T08:05:49.412Z] 08:05:49     INFO -  926 |nsStandardURL                         |      336    24864|    2450       74|
[task 2023-06-21T08:05:49.412Z] 08:05:49     INFO -  928 |nsStaticCaseInsensitiveNameTable      |       64      192|       3        3|
[task 2023-06-21T08:05:49.413Z] 08:05:49     INFO -  933 |nsStringBuffer                        |        8     8536|   21379     1067|
[task 2023-06-21T08:05:49.413Z] 08:05:49     INFO -  934 |nsStringBundle                        |      136      136|       1        1|
[task 2023-06-21T08:05:49.413Z] 08:05:49     INFO -  935 |nsStringBundleBase                    |      128     1792|      14       14|
[task 2023-06-21T08:05:49.414Z] 08:05:49     INFO -  936 |nsStringBundleService                 |      144      144|       1        1|
[task 2023-06-21T08:05:49.414Z] 08:05:49     INFO -  938 |nsStyleBackground                     |      168      336|      49        2|
[task 2023-06-21T08:05:49.414Z] 08:05:49     INFO -  939 |nsStyleBorder                         |      384      768|      48        2|
[task 2023-06-21T08:05:49.415Z] 08:05:49     INFO -  941 |nsStyleColumn                         |       64      128|      43        2|
[task 2023-06-21T08:05:49.415Z] 08:05:49     INFO -  942 |nsStyleContent                        |       72      144|      44        2|
[task 2023-06-21T08:05:49.416Z] 08:05:49     INFO -  943 |nsStyleDisplay                        |      368      736|     233        2|
[task 2023-06-21T08:05:49.416Z] 08:05:49     INFO -  944 |nsStyleEffects                        |       96      192|      44        2|
[task 2023-06-21T08:05:49.416Z] 08:05:49     INFO -  945 |nsStyleFont                           |      168      336|      98        2|
[task 2023-06-21T08:05:49.417Z] 08:05:49     INFO -  946 |nsStyleImageLayers                    |      136      544|      92        4|
[task 2023-06-21T08:05:49.417Z] 08:05:49     INFO -  947 |nsStyleList                           |       48       96|      44        2|
[task 2023-06-21T08:05:49.418Z] 08:05:49     INFO -  948 |nsStyleMargin                         |       88      176|      57        2|
[task 2023-06-21T08:05:49.418Z] 08:05:49     INFO -  949 |nsStyleOutline                        |       48       96|      43        2|
[task 2023-06-21T08:05:49.418Z] 08:05:49     INFO -  950 |nsStylePadding                        |       96      192|      44        2|
[task 2023-06-21T08:05:49.426Z] 08:05:49     INFO -  951 |nsStylePosition                       |      480      960|     172        2|
[task 2023-06-21T08:05:49.426Z] 08:05:49     INFO -  952 |nsStyleSVG                            |      312      624|      43        2|
[task 2023-06-21T08:05:49.427Z] 08:05:49     INFO -  953 |nsStyleSVGReset                       |      360      720|      43        2|
[task 2023-06-21T08:05:49.427Z] 08:05:49     INFO -  955 |nsStyleTable                          |        8       16|      43        2|
[task 2023-06-21T08:05:49.428Z] 08:05:49     INFO -  956 |nsStyleTableBorder                    |       12       24|      43        2|
[task 2023-06-21T08:05:49.429Z] 08:05:49     INFO -  957 |nsStyleText                           |      272      544|     126        2|
[task 2023-06-21T08:05:49.429Z] 08:05:49     INFO -  958 |nsStyleTextReset                      |      120      240|     120        2|
[task 2023-06-21T08:05:49.433Z] 08:05:49     INFO -  967 |nsTextFragment                        |       16      448|     119       28|
[task 2023-06-21T08:05:49.434Z] 08:05:49     INFO -  968 |nsTextNode                            |      128     3328|     112       26|
[task 2023-06-21T08:05:49.435Z] 08:05:49     INFO -  969 |nsThread                              |      440     1320|      21        3|
[task 2023-06-21T08:05:49.435Z] 08:05:49     INFO -  977 |nsTimer                               |       32       96|     150        3|
[task 2023-06-21T08:05:49.436Z] 08:05:49     INFO -  979 |nsTimerImpl                           |      176      528|     150        3|
[task 2023-06-21T08:05:49.436Z] 08:05:49     INFO -  981 |nsURIHashKey                          |        8        8|     584        1|
[task 2023-06-21T08:05:49.437Z] 08:05:49     INFO -  984 |nsVariant                             |       72      576|      28        8|
[task 2023-06-21T08:05:49.438Z] 08:05:49     INFO -  987 |nsWeakReference                       |       40      640|     163       16|
[task 2023-06-21T08:05:49.438Z] 08:05:49     INFO -  988 |nsWebBrowser                          |      280      560|       5        2|
[task 2023-06-21T08:05:49.439Z] 08:05:49     INFO -  993 |nsWindowWatcher                       |      136      136|       1        1|
[task 2023-06-21T08:05:49.440Z] 08:05:49     INFO -  997 |nsXPCComponents                       |       88       88|       1        1|
[task 2023-06-21T08:05:49.440Z] 08:05:49     INFO -  998 |nsXPCComponents_Classes               |       40       40|       1        1|
[task 2023-06-21T08:05:49.441Z] 08:05:49     INFO -  999 |nsXPCComponents_Constructor           |       40       40|       1        1|
[task 2023-06-21T08:05:49.442Z] 08:05:49     INFO - 1000 |nsXPCComponents_ID                    |       40       40|       1        1|
[task 2023-06-21T08:05:49.442Z] 08:05:49     INFO - 1001 |nsXPCComponents_Interfaces            |       40       40|       1        1|
[task 2023-06-21T08:05:49.443Z] 08:05:49     INFO - 1002 |nsXPCComponents_Results               |       40       40|       1        1|
[task 2023-06-21T08:05:49.443Z] 08:05:49     INFO - 1003 |nsXPCComponents_Utils                 |       40       40|       1        1|
[task 2023-06-21T08:05:49.444Z] 08:05:49     INFO - 1004 |nsXPCWrappedJS                        |      120      240|      89        2|
[task 2023-06-21T08:05:49.445Z] 08:05:49     INFO - 1005 |nsXPConnect                           |       40       40|       1        1|
[task 2023-06-21T08:05:49.445Z] 08:05:49     INFO - 1008 |nsXULPopupManager                     |      160      160|       1        1|
[task 2023-06-21T08:05:49.446Z] 08:05:49     INFO - 1012 |xpc::CompartmentPrivate               |       88      264|      13        3|
[task 2023-06-21T08:05:49.447Z] 08:05:49     INFO - 
[task 2023-06-21T08:05:49.447Z] 08:05:49     INFO - nsTraceRefcnt::DumpStatistics: 1015 entries
[task 2023-06-21T08:05:49.588Z] 08:05:49     INFO - TEST-INFO | leakcheck | tab leaked 1 nsXULPopupManager
[task 2023-06-21T08:05:49.589Z] 08:05:49     INFO - TEST-INFO | leakcheck | tab leaked 3 xpc::CompartmentPrivate
[task 2023-06-21T08:05:49.594Z] 08:05:49     INFO - TEST-UNEXPECTED-FAIL | leakcheck | tab 300289 bytes leaked (APZEventState, ActiveElementManager, AsyncFreeSnowWhite, AttributeStyles, BackstagePass, ...)
[task 2023-06-21T08:05:49.594Z] 08:05:49     INFO - 

Comment 1

[Iulian Moraru]

First occurrence, so far: backfill range and retriggers.

Comment 2

[Intermittent Failures Robot]

17 failures in 3880 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 9
• mozilla-central: 8

Platform and build breakdown:

• linux1804-64-qr: 16
  • debug: 16
• macosx1015-64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1839581&startday=2023-06-19&endday=2023-06-25&tree=all

Comment 3

[Cosmin Sabou [:CosminS]]

Botond, could you have a look over these quite frequent shutdown leaks?
Last test run seems to be gfx/layers/apz/test/mochitest/test_wheel_transactions.html.
Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=421084985&repo=autoland&lineNumber=14088

Comment 4

[Andrew McCreight [:mccr8]]

The leak checking runs at the end of a run, so that specific test isn't necessarily the cause of it.

Comment 5

[Intermittent Failures Robot]

29 failures in 3594 pushes (0.008 failures/push) were associated with this bug in the last 7 days.

This is the #45 most frequent failure this week.

Repository breakdown:

• autoland: 16
• mozilla-central: 10
• try: 3

Platform and build breakdown:

• linux1804-64-qr: 29
  • debug: 29

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1839581&startday=2023-06-26&endday=2023-07-02&tree=all

Comment 6

[Intermittent Failures Robot]

19 failures in 523 pushes (0.036 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 13
• mozilla-beta: 1
• mozilla-central: 4
• try: 1

Platform and build breakdown:

• linux1804-64-qr: 19
  • debug: 19

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1839581&startday=2023-07-04&endday=2023-07-04&tree=all

Comment 7

[Andrew McCreight [:mccr8]]

This (probably) isn't related to APZ. The set of leaked objects is shown in alphabetical order, and APZEventState happens to appear first. (I should really improve that situation...)

Comment 8

[Andrew McCreight [:mccr8]]

Oops, wait, it is happening after APZ tests run, sorry! That's what I get for not reading the entire bug.

Comment 9

[Botond Ballo [:botond]]

Hey Andrew, just wanted to double-check since comment 4 and comment 8 seem to be contradictory. The failing test job involves running a bunch of different tests (not just APZ), but the last test run before the leak check report is an APZ test.

If you do think this is an issue with the APZ tests, do you have a suggestion for how to investigate something like this?

Comment 10

[Intermittent Failures Robot]

58 failures in 2956 pushes (0.02 failures/push) were associated with this bug in the last 7 days.

This is the #17 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 35
• mozilla-central: 17
• try: 5
• mozilla-beta: 1

Platform and build breakdown:

• linux1804-64-qr: 57
  • debug: 57
• windows11-64-2009-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1839581&startday=2023-07-03&endday=2023-07-09&tree=all

Comment 11

[Andrew McCreight [:mccr8]]

(In reply to Botond Ballo [:botond] [away until July 17] from comment #9)

Hey Andrew, just wanted to double-check since comment 4 and comment 8 seem to be contradictory. The failing test job involves running a bunch of different tests (not just APZ), but the last test run before the leak check report is an APZ test.

Mochitests are run a directory at a time. Leak logs are recorded at process shutdown. The directory of tests that were running when the leak log with a leak was created was gfx/layers/apz/test/mochitest/, so it is definitely being created during APZ tests. My point was only that the last test run in that apz directory might not be at fault.

If you do think this is an issue with the APZ tests, do you have a suggestion for how to investigate something like this?

I've been doing some investigation of this and hopefully I'll have some more information later today.

Comment 12

[Andrew McCreight [:mccr8]]

I did a try run with cycle collector logging enabled, by going to buildBrowserEnv in testing/mochitest/runtests.py and setting some env vars:

browserEnv["MOZ_CC_LOG_DIRECTORY"] = os.environ["MOZ_UPLOAD_DIR"]
browserEnv["MOZ_CC_LOG_THREAD"] = "main"
browserEnv["MOZ_CC_LOG_PROCESS"] = "content"
browserEnv["MOZ_CC_LOG_SHUTDOWN_SKIP"] = "1"
browserEnv["MOZ_DISABLE_CONTENT_SANDBOX"] = "t"

This hit the leak in 2 out of 12 runs. In both cases, I used my find_roots.py script to determine that the nsGlobalWindowInners that were leaking were being held alive by a single JS-implemented nsIObserver. The script for the first leak was on line 51 of helper_tap_default_passive.html. The script for the second leak was on line 50 of helper_tap_passive.html. Both of these are trivial observers being passed in to synthesizeNativeTouch().

It looks like the observer gets passed in to nsDOMWindowUtils::SendNativeTouchPoint, which looks like it ends up in nsIWidget::SynthesizeNativeTouchPoint, which I'm guessing is implemented on Linux in nsWindow::SynthesizeNativeTouchPoint in widget/gtk/nsWindow.cpp (the leaks are all on Linux, FWIW). That in turn passes the observer into widget::AutoObserverNotifier, which stores a strong reference to the nsIObserver in sSavedObservers. That strong reference is only removed if NotifySavedObserver() which has a single caller, BrowserChild::RecvNativeSynthesisResponse(). In other words, if we never receive that message, we'll leak the observer.

Separately, I looked in the Mochitest log for mentions of scripts where the observers occur (helper_tap_default_passive.html and helper_tap_passive.html).

In both logs, I found something like this:

helper_tap_passive.html | Finished synthesizing touch-start, waiting for events...
[Parent 1576, Main Thread] WARNING: BrowserParent was unexpectedly destroyed during event synthesization!: file /builds/worker/checkouts/gecko/dom/ipc/BrowserParent.cpp:1793
[Parent 1576, Main Thread] WARNING: Got an unexpected touchend: file /builds/worker/checkouts/gecko/gfx/layers/apz/util/TouchCounter.cpp:58

The first warning comes from in SynthesizedEventObserver::Observe. If we get that warning, it means we don't send SendNativeSynthesisResponse(), so I think that's why we are leaking. I confirmed that this happened exactly once in each leaking log, and zero times in the 10 logs where we didn't leak.

The most direct way to avoid this leak is to clear sSavedObservers when we're shutting down, but that's really papering over whatever the issue is. Maybe that doesn't matter too much if is only used for testing, though I think I saw something about it being used by devtools. It seems odd to me that we can just fail to send some test event like this and it doesn't cause any test failures, so maybe there's some issue with the test.

Comment 13

[Andrew McCreight [:mccr8]]

Bug 1830660 and bug 1829967 landed around the time when this started flaring up so maybe they are related.

Comment 14

[Intermittent Failures Robot]

44 failures in 3309 pushes (0.013 failures/push) were associated with this bug in the last 7 days.

This is the #25 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• mozilla-central: 11
• autoland: 30
• mozilla-beta: 3

Platform and build breakdown:

• linux1804-64-qr: 44
  • debug: 44

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1839581&startday=2023-07-10&endday=2023-07-16&tree=all

Comment 15

[Andrew McCreight [:mccr8]]

I've written a patch that clears sSavedObservers in BrowserChild::DestroyWindow. I'll see if that fixes the leak. I feel like we should either do that or assert if the table is full on shutdown, if the latter indicates some kind of test issue.

Comment 16

[Andrew McCreight [:mccr8]]

Well, that did not fix the leak and also it caused another assertion in AutoObserverNotifier. For the latter, I forgot that you can have more than one BrowserChild per content process, so that's probably what is going wrong. I'll try some more logging to see what other thing might be the source of the leak.

Comment 17

[Andrew McCreight [:mccr8]]

I changed my patch a bit to clear sSavedObservers when the ContentChild goes away instead of a BrowserChild.

It does leak, but the leak is now being held alive by an element instead of the observer, so that's at least a change.

The description of the element is:

FragmentOrElement (xhtml) a id='b' http://mochi.test:8888/tests/gfx/layers/apz/test/mochitest/helper_tap_passive.html

Comment 18

[Andrew McCreight [:mccr8]]

It looks like that element is being passed in to synthesizeNativeTouch. I have no idea how it could leak, though, from reading the code. It also looks like that element was leaking in the same way before my patch, too, so no change there. I tried reproducing the leak with DMD enabled, but I guess it disrupted the timing or something because I couldn't get it to trigger. Hopefully somebody more familiar with APZ testing can make some progress with what I've found so far.

Comment 19

[Hiroyuki Ikezoe (:hiro)]

From the APZ testing stand point, the element is just used via getElementById("b"), I don't believe it's leaking from the usage. Maybe the leak happens in somewhere in our event handling code? My best guess is this sCaptureTouchList. CCing Edgar.

Comment 20

[Andrew McCreight [:mccr8]]

That could be. There's TouchManager::ReleaseStatics(), but it is called after the final shutdown CC, so if it is keeping any cycle collected thing alive, it'll leak.

Comment 21

[Botond Ballo [:botond]]

Thanks very much for your investigation here, Andrew! I've taken note of how to enable and analyze cycle collector logs for future reference.

Even if we haven't yet tracked down the exact cause of the leak, having narrowed it down from the entire APZ mochitest directory to two specific subtests (helper_tap_passive.html and helper_tap_default_passive.html) is already very useful: it means that, if needed, we can disable these two subtests (with the disablement scoped to the affected platform, Linux debug) while we investigate the leak further.

Comment 22

[Intermittent Failures Robot]

17 failures in 756 pushes (0.022 failures/push) were associated with this bug yesterday.

Repository breakdown:

• mozilla-central: 10
• autoland: 7

Platform and build breakdown:

• linux1804-64-qr: 17
  • debug: 17

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1839581&startday=2023-07-19&endday=2023-07-19&tree=all

Comment 23

[Natalia Csoregi [:nataliaCs]]

Update

There have been 50 failures within the last 7 days, all of them on Linux 18.04 x64 WebRender debug.

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=423545599&repo=mozilla-central&lineNumber=11931

Comment 24

[Intermittent Failures Robot]

51 failures in 3520 pushes (0.014 failures/push) were associated with this bug in the last 7 days.

This is the #26 most frequent failure this week.

** This test has failed more than 150 times in the last 21 days. It should be disabled until it can be fixed. **

Repository breakdown:

• autoland: 22
• try: 1
• mozilla-central: 28

Platform and build breakdown:

• linux1804-64-qr: 51
  • debug: 51

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1839581&startday=2023-07-17&endday=2023-07-23&tree=all

Comment 25

[Hiroyuki Ikezoe (:hiro)]

Attachment: Bug 1839581 - Make sure the last touchend event received in the content. r?botond

It avoids shutdown leaks.

Comment 26

[Hiroyuki Ikezoe (:hiro)]

My speculative change (D184521) seems to have fixed the leak (here is a try with the change).

So now I am ~100% sure the leak is an item of sCaptureTouchList. And I am pretty sure the leak cause has been there before bug 1830660 and bug 1829967.

Comment 27

[Botond Ballo [:botond]]

Thank you, Hiro!

(In reply to Andrew McCreight [:mccr8] from comment #12)

In both logs, I found something like this:

helper_tap_passive.html | Finished synthesizing touch-start, waiting for events...
[Parent 1576, Main Thread] WARNING: BrowserParent was unexpectedly destroyed during event synthesization!: file /builds/worker/checkouts/gecko/dom/ipc/BrowserParent.cpp:1793
[Parent 1576, Main Thread] WARNING: Got an unexpected touchend: file /builds/worker/checkouts/gecko/gfx/layers/apz/util/TouchCounter.cpp:58

Do you understand why we were seeing a BrowserParent unexpectedly destroyed, or should we investigate that further in a follow-up?

Comment 28

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/3f7884c6265a
Make sure the last touchend event received in the content. r=botond

Comment 29

[Serban Stanca [:SerbanS]]

https://hg.mozilla.org/mozilla-central/rev/3f7884c6265a

Comment 30

[Andrew McCreight [:mccr8]]

(In reply to Botond Ballo [:botond] from comment #27)

Do you understand why we were seeing a BrowserParent unexpectedly destroyed, or should we investigate that further in a follow-up?

I'm not sure, but I think that just means the test wasn't waiting for all of the synthesized events to resolve themselves before ending. Once we leave the test, the tab gets torn down, then somehow the event eventually fires and sees that things have been torn down. I'm guessing Hiro's patch addresses that by waiting for the events to resolve.

Comment 31

[Botond Ballo [:botond]]

(In reply to Andrew McCreight [:mccr8] from comment #30)

I think that just means the test wasn't waiting for all of the synthesized events to resolve themselves before ending. Once we leave the test, the tab gets torn down, then somehow the event eventually fires and sees that things have been torn down. I'm guessing Hiro's patch addresses that by waiting for the events to resolve.

That makes sense, thanks!

Comment 32

[Intermittent Failures Robot]

20 failures in 3403 pushes (0.006 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 13
• mozilla-central: 6
• mozilla-beta: 1

Platform and build breakdown:

• linux1804-64-qr: 20
  • debug: 20

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1839581&startday=2023-07-24&endday=2023-07-30&tree=all

Comment 33

[Intermittent Failures Robot]

1 failures in 3337 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1839581&startday=2023-09-11&endday=2023-09-17&tree=all