Closed Bug 1690192 Opened 3 years ago Closed 3 years ago

Intermittent leakcheck | default X bytes leaked (ThreadSafeWeakReference<UnscaledFont>, UnscaledFontMac, gfxFontEntry, nsStringBuffer, nsTArray_base)

Categories

(Firefox :: Tabbed Browser, defect, P5)

defect

Tracking

()

RESOLVED FIXED
87 Branch
Tracking Status
firefox-esr78 --- unaffected
firefox85 --- unaffected
firefox86 --- unaffected
firefox87 --- fixed

People

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

References

(Regression)

Details

(Keywords: intermittent-failure, regression, Whiteboard: [retriggered][stockwell fixed:patch])

Attachments

(3 files)

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


[task 2021-02-02T04:06:49.088Z] 04:06:49     INFO - TEST-START | browser/base/content/test/tabcrashed/browser_withoutDump.js
[task 2021-02-02T04:06:49.109Z] 04:06:49     INFO - GECKO(1788) | [Child 1823: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x10de11400 == 1 [pid = 1823] [id = 0]
[task 2021-02-02T04:06:49.109Z] 04:06:49     INFO - GECKO(1788) | [Child 1823: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x1127f2040) [pid = 1823] [serial = 1] [outer = 0x0]
[task 2021-02-02T04:06:49.109Z] 04:06:49     INFO - GECKO(1788) | [Child 1823: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x1127df000) [pid = 1823] [serial = 2] [outer = 0x1127f2040]
[task 2021-02-02T04:06:49.183Z] 04:06:49     INFO - GECKO(1788) | [Child 1823: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x11286d000) [pid = 1823] [serial = 3] [outer = 0x1127f2040]
[task 2021-02-02T04:06:49.303Z] 04:06:49     INFO - GECKO(1788) | [1825, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:202
[task 2021-02-02T04:06:49.303Z] 04:06:49     INFO - GECKO(1788) | [1825, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:202
[task 2021-02-02T04:06:49.303Z] 04:06:49     INFO - GECKO(1788) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/4y/fxf4tjpj6hz4v2ddcs7_hln8000017/T/tmpa3gRos.mozrunner/runtests_leaks_tab_pid1825.log
[task 2021-02-02T04:06:49.304Z] 04:06:49     INFO - GECKO(1788) | [1825, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:202
[task 2021-02-02T04:06:49.304Z] 04:06:49     INFO - GECKO(1788) | [1825, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:202
[task 2021-02-02T04:06:49.304Z] 04:06:49     INFO - GECKO(1788) | [1825, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:251
[task 2021-02-02T04:06:49.593Z] 04:06:49     INFO - GECKO(1788) | Et tu, Brute?
[task 2021-02-02T04:06:49.593Z] 04:06:49     INFO - GECKO(1788) | XPCOM_MEM_BLOAT_LOG: /var/folders/4y/fxf4tjpj6hz4v2ddcs7_hln8000017/T/tmpa3gRos.mozrunner/runtests_leaks.log
[task 2021-02-02T04:06:49.593Z] 04:06:49     INFO - GECKO(1788) | Writing to log: /var/folders/4y/fxf4tjpj6hz4v2ddcs7_hln8000017/T/tmpa3gRos.mozrunner/runtests_leaks_tab_pid1823.log
[task 2021-02-02T04:06:49.788Z] 04:06:49     INFO - GECKO(1788) | [Parent 1788, Unnamed thread 12625d680] WARNING: Resource acquired is being released in non-LIFO order; why?
[task 2021-02-02T04:06:49.788Z] 04:06:49     INFO - GECKO(1788) | : file /builds/worker/checkouts/gecko/xpcom/threads/BlockingResourceBase.cpp:292
[task 2021-02-02T04:06:49.789Z] 04:06:49     INFO - GECKO(1788) | --- Mutex : dumpSafetyLock (currently acquired)
[task 2021-02-02T04:06:49.789Z] 04:06:49     INFO - GECKO(1788) |  calling context
[task 2021-02-02T04:06:49.789Z] 04:06:49     INFO - GECKO(1788) |   [stack trace unavailable]
[task 2021-02-02T04:06:49.789Z] 04:06:49     INFO - GECKO(1788) | [Parent 1788, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:512
[task 2021-02-02T04:06:49.789Z] 04:06:49     INFO - GECKO(1788) | [Parent 1788, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:512
[task 2021-02-02T04:06:49.789Z] 04:06:49     INFO - GECKO(1788) | [Parent 1788, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:512
[task 2021-02-02T04:06:49.790Z] 04:06:49     INFO - GECKO(1788) | [Parent 1788, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:512
[task 2021-02-02T04:06:49.790Z] 04:06:49     INFO - GECKO(1788) | [Child 1820: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x109ed5c00 == 3 [pid = 1820] [id = 2]
[task 2021-02-02T04:06:49.790Z] 04:06:49     INFO - GECKO(1788) | [Child 1820: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (0x109f0e3c0) [pid = 1820] [serial = 5] [outer = 0x0]
[task 2021-02-02T04:06:49.797Z] 04:06:49     INFO - GECKO(1788) | [Child 1820: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 6 (0x109ed6c00) [pid = 1820] [serial = 6] [outer = 0x109f0e3c0]
[task 2021-02-02T04:06:49.797Z] 04:06:49     INFO - GECKO(1788) | [Parent 1788, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:512
[task 2021-02-02T04:06:49.798Z] 04:06:49     INFO - GECKO(1788) | [Child 1805: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x1081d9c00 == 4 [pid = 1805] [id = 1] [url = about:blank]
[task 2021-02-02T04:06:49.798Z] 04:06:49     INFO - GECKO(1788) | [Child 1805: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x108282c00 == 3 [pid = 1805] [id = 4] [url = http://example.com/]
[task 2021-02-02T04:06:49.798Z] 04:06:49     INFO - GECKO(1788) | [Child 1805: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x1081dcc00 == 2 [pid = 1805] [id = 3] [url = http://example.com/]
[task 2021-02-02T04:06:49.798Z] 04:06:49     INFO - GECKO(1788) | [Child 1805: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x1081d1c00 == 1 [pid = 1805] [id = 2] [url = about:blank]
[task 2021-02-02T04:06:49.859Z] 04:06:49     INFO - GECKO(1788) | [Parent 1788, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:512
[task 2021-02-02T04:06:49.859Z] 04:06:49     INFO - GECKO(1788) | [Parent 1788, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/workspace/obj-build/dist/include/mozilla/ipc/ProtocolUtils.h:299
[task 2021-02-02T04:06:49.860Z] 04:06:49     INFO - GECKO(1788) | [Parent 1788: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x125d9c000 == 14 [pid = 1788] [id = 26]
[task 2021-02-02T04:06:49.860Z] 04:06:49     INFO - GECKO(1788) | [Parent 1788: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 29 (0x11cbc2900) [pid = 1788] [serial = 79] [outer = 0x0]
[task 2021-02-02T04:06:49.860Z] 04:06:49     INFO - GECKO(1788) | [Parent 1788: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 30 (0x12694dc00) [pid = 1788] [serial = 80] [outer = 0x11cbc2900]
[task 2021-02-02T04:06:49.944Z] 04:06:49     INFO - GECKO(1788) | [Parent 1788: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 31 (0x126e0a800) [pid = 1788] [serial = 81] [outer = 0x11cbc2900]
[task 2021-02-02T04:06:49.955Z] 04:06:49     INFO - GECKO(1788) | Crash cleaned up
[task 2021-02-02T04:06:49.982Z] 04:06:49     INFO - GECKO(1788) | about:tabcrashed loaded and ready
[task 2021-02-02T04:06:50.005Z] 04:06:50     INFO - GECKO(1788) | [Child 1805: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (0x1081ec3c0) [pid = 1805] [serial = 7] [outer = 0x0] [url = http://example.com/]
[task 2021-02-02T04:06:50.006Z] 04:06:50     INFO - GECKO(1788) | [Child 1805: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (0x1081ecc80) [pid = 1805] [serial = 10] [outer = 0x0] [url = http://example.com/]
[task 2021-02-02T04:06:50.006Z] 04:06:50     INFO - GECKO(1788) | [Child 1805: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (0x1081ec040) [pid = 1805] [serial = 5] [outer = 0x0] [url = about:blank]
[task 2021-02-02T04:06:50.007Z] 04:06:50     INFO - GECKO(1788) | [Child 1805: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (0x1081ec200) [pid = 1805] [serial = 3] [outer = 0x0] [url = about:blank]
[task 2021-02-02T04:06:50.090Z] 04:06:50     INFO - GECKO(1788) | MEMORY STAT | vsize 7836MB | residentFast 347MB | heapAllocated 123MB
[task 2021-02-02T04:06:50.090Z] 04:06:50     INFO - TEST-OK | browser/base/content/test/tabcrashed/browser_withoutDump.js | took 994ms

task 2021-02-02T04:06:57.070Z] 04:06:57     INFO - GECKO(1788) | Completed ShutdownLeaks collections in process 1788
[task 2021-02-02T04:06:57.070Z] 04:06:57     INFO - TEST-START | Shutdown
[task 2021-02-02T04:06:57.070Z] 04:06:57     INFO - Browser Chrome Test Summary
[task 2021-02-02T04:06:57.071Z] 04:06:57     INFO - Passed:  58
[task 2021-02-02T04:06:57.071Z] 04:06:57     INFO - Failed:  0
[task 2021-02-02T04:06:57.071Z] 04:06:57     INFO - Todo:    0
[task 2021-02-02T04:06:57.071Z] 04:06:57     INFO - Mode:    e10s
[task 2021-02-02T04:06:57.071Z] 04:06:57     INFO - *** End BrowserChrome Test Results ***

[task 2021-02-02T04:06:59.082Z] 04:06:59     INFO - TEST-INFO | Main app process: exit 0
[task 2021-02-02T04:06:59.082Z] 04:06:59     INFO - TEST-INFO | Confirming we saw 86 DOCSHELL created and 47 destroyed log strings.
[task 2021-02-02T04:06:59.082Z] 04:06:59     INFO - TEST-INFO | Confirming we saw 236 DOMWINDOW created and 131 destroyed log strings.
[task 2021-02-02T04:06:59.082Z] 04:06:59     INFO - TEST-INFO | browser/base/content/test/tabcrashed/browser_autoSubmitRequest.js | This test created 0 hidden window(s)
[task 2021-02-02T04:06:59.083Z] 04:06:59     INFO - TEST-INFO | browser/base/content/test/tabcrashed/browser_autoSubmitRequest.js | This test created 1 hidden docshell(s)
[task 2021-02-02T04:06:59.083Z] 04:06:59     INFO - TEST-INFO | browser/base/content/test/tabcrashed/browser_clearEmail.js | This test created 0 hidden window(s)
[task 2021-02-02T04:06:59.083Z] 04:06:59     INFO - TEST-INFO | browser/base/content/test/tabcrashed/browser_clearEmail.js | This test created 1 hidden docshell(s)
[task 2021-02-02T04:06:59.083Z] 04:06:59     INFO - TEST-INFO | browser/base/content/test/tabcrashed/browser_launchFail.js | This test created 0 hidden window(s)
[task 2021-02-02T04:06:59.083Z] 04:06:59     INFO - TEST-INFO | browser/base/content/test/tabcrashed/browser_launchFail.js | This test created 1 hidden docshell(s)
[task 2021-02-02T04:06:59.084Z] 04:06:59     INFO - TEST-INFO | browser/base/content/test/tabcrashed/browser_multipleCrashedTabs.js | This test created 0 hidden window(s)
[task 2021-02-02T04:06:59.084Z] 04:06:59     INFO - TEST-INFO | browser/base/content/test/tabcrashed/browser_multipleCrashedTabs.js | This test created 1 hidden docshell(s)
[task 2021-02-02T04:06:59.084Z] 04:06:59     INFO - TEST-INFO | browser/base/content/test/tabcrashed/browser_printpreview_crash.js | This test created 0 hidden window(s)
[task 2021-02-02T04:06:59.084Z] 04:06:59     INFO - TEST-INFO | browser/base/content/test/tabcrashed/browser_printpreview_crash.js | This test created 1 hidden docshell(s)
[task 2021-02-02T04:06:59.084Z] 04:06:59     INFO - TEST-INFO | browser/base/content/test/tabcrashed/browser_showForm.js | This test created 0 hidden window(s)
[task 2021-02-02T04:06:59.084Z] 04:06:59     INFO - TEST-INFO | browser/base/content/test/tabcrashed/browser_showForm.js | This test created 1 hidden docshell(s)
[task 2021-02-02T04:06:59.085Z] 04:06:59     INFO - TEST-INFO | browser/base/content/test/tabcrashed/browser_shown.js | This test created 0 hidden window(s)
[task 2021-02-02T04:06:59.085Z] 04:06:59     INFO - TEST-INFO | browser/base/content/test/tabcrashed/browser_shown.js | This test created 1 hidden docshell(s)
[task 2021-02-02T04:06:59.085Z] 04:06:59     INFO - TEST-INFO | browser/base/content/test/tabcrashed/browser_shownRestartRequired.js | This test created 0 hidden window(s)
[task 2021-02-02T04:06:59.085Z] 04:06:59     INFO - TEST-INFO | browser/base/content/test/tabcrashed/browser_shownRestartRequired.js | This test created 1 hidden docshell(s)
[task 2021-02-02T04:06:59.085Z] 04:06:59     INFO - TEST-INFO | browser/base/content/test/tabcrashed/browser_withoutDump.js | This test created 0 hidden window(s)
[task 2021-02-02T04:06:59.085Z] 04:06:59     INFO - TEST-INFO | browser/base/content/test/tabcrashed/browser_withoutDump.js | This test created 1 hidden docshell(s)
[task 2021-02-02T04:06:59.086Z] 04:06:59     INFO - runtests.py | Application ran for: 0:00:53.886175

[task 2021-02-02T04:06:59.187Z] 04:06:59     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2021-02-02T04:06:59.187Z] 04:06:59     INFO - leakcheck | Processing leak log file /var/folders/4y/fxf4tjpj6hz4v2ddcs7_hln8000017/T/tmpa3gRos.mozrunner/runtests_leaks_tab_pid1799.log
[task 2021-02-02T04:06:59.187Z] 04:06:59     INFO - ==> process 1799 will purposefully crash
[task 2021-02-02T04:06:59.188Z] 04:06:59     INFO - TEST-INFO | leakcheck | tab deliberate crash and thus no leak log
[task 2021-02-02T04:06:59.188Z] 04:06:59     INFO - leakcheck | Processing leak log file /var/folders/4y/fxf4tjpj6hz4v2ddcs7_hln8000017/T/tmpa3gRos.mozrunner/runtests_leaks_tab_pid1820.log
[task 2021-02-02T04:06:59.188Z] 04:06:59     INFO - 
[task 2021-02-02T04:06:59.188Z] 04:06:59     INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 1820
[task 2021-02-02T04:06:59.188Z] 04:06:59     INFO - 
[task 2021-02-02T04:06:59.188Z] 04:06:59     INFO -      |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2021-02-02T04:06:59.188Z] 04:06:59     INFO -      |                                      | Per-Inst   Leaked|   Total      Rem|
[task 2021-02-02T04:06:59.188Z] 04:06:59     INFO -    0 |TOTAL                                 |       44        0|   33966        0|
[task 2021-02-02T04:06:59.188Z] 04:06:59     INFO - 
[task 2021-02-02T04:06:59.188Z] 04:06:59     INFO - nsTraceRefcnt::DumpStatistics: 624 entries
[task 2021-02-02T04:06:59.189Z] 04:06:59     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2021-02-02T04:06:59.189Z] 04:06:59     INFO - leakcheck | Processing leak log file /var/folders/4y/fxf4tjpj6hz4v2ddcs7_hln8000017/T/tmpa3gRos.mozrunner/runtests_leaks_tab_pid1822.log
[task 2021-02-02T04:06:59.189Z] 04:06:59     INFO - ==> process 1822 will purposefully crash
[task 2021-02-02T04:06:59.189Z] 04:06:59     INFO - TEST-INFO | leakcheck | tab deliberate crash and thus no leak log
[task 2021-02-02T04:06:59.189Z] 04:06:59     INFO - leakcheck | Processing leak log file /var/folders/4y/fxf4tjpj6hz4v2ddcs7_hln8000017/T/tmpa3gRos.mozrunner/runtests_leaks_tab_pid1823.log
[task 2021-02-02T04:06:59.189Z] 04:06:59     INFO - ==> process 1823 will purposefully crash
[task 2021-02-02T04:06:59.189Z] 04:06:59     INFO - TEST-INFO | leakcheck | tab deliberate crash and thus no leak log
[task 2021-02-02T04:06:59.190Z] 04:06:59     INFO - leakcheck | Processing leak log file /var/folders/4y/fxf4tjpj6hz4v2ddcs7_hln8000017/T/tmpa3gRos.mozrunner/runtests_leaks.log
[task 2021-02-02T04:06:59.190Z] 04:06:59     INFO - 
[task 2021-02-02T04:06:59.190Z] 04:06:59     INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, default process 1788
[task 2021-02-02T04:06:59.190Z] 04:06:59     INFO - 
[task 2021-02-02T04:06:59.190Z] 04:06:59     INFO -      |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2021-02-02T04:06:59.190Z] 04:06:59     INFO -      |                                      | Per-Inst   Leaked|   Total      Rem|
[task 2021-02-02T04:06:59.190Z] 04:06:59     INFO -    0 |TOTAL                                 |       38      464|10878980       11|
[task 2021-02-02T04:06:59.190Z] 04:06:59     INFO - 1254 |ThreadSafeWeakReference<UnscaledFont> |       24       24|       7        1|
[task 2021-02-02T04:06:59.190Z] 04:06:59     INFO - 1295 |UnscaledFontMac                       |       56       56|       8        1|
[task 2021-02-02T04:06:59.191Z] 04:06:59     INFO - 1450 |gfxFontEntry                          |      320      320|     609        1|
[task 2021-02-02T04:06:59.191Z] 04:06:59     INFO - 1968 |nsStringBuffer                        |        8       16|  234910        2|
[task 2021-02-02T04:06:59.191Z] 04:06:59     INFO - 2003 |nsTArray_base                         |        8       48| 4472361        6|
[task 2021-02-02T04:06:59.191Z] 04:06:59     INFO - 
[task 2021-02-02T04:06:59.191Z] 04:06:59     INFO - nsTraceRefcnt::DumpStatistics: 2120 entries
[task 2021-02-02T04:06:59.191Z] 04:06:59     INFO - TEST-INFO | leakcheck | default leaked 1 ThreadSafeWeakReference<UnscaledFont>
[task 2021-02-02T04:06:59.191Z] 04:06:59     INFO - TEST-INFO | leakcheck | default leaked 1 UnscaledFontMac
[task 2021-02-02T04:06:59.191Z] 04:06:59     INFO - TEST-INFO | leakcheck | default leaked 1 gfxFontEntry
[task 2021-02-02T04:06:59.191Z] 04:06:59     INFO - TEST-INFO | leakcheck | default leaked 2 nsStringBuffer
[task 2021-02-02T04:06:59.191Z] 04:06:59     INFO - TEST-INFO | leakcheck | default leaked 6 nsTArray_base
[task 2021-02-02T04:06:59.191Z] 04:06:59     INFO - TEST-UNEXPECTED-FAIL | leakcheck | default 464 bytes leaked (ThreadSafeWeakReference<UnscaledFont>, UnscaledFontMac, gfxFontEntry, nsStringBuffer, nsTArray_base)
[task 2021-02-02T04:06:59.191Z] 04:06:59     INFO - 
[task 2021-02-02T04:06:59.192Z] 04:06:59     INFO - leakcheck | Processing leak log file /var/folders/4y/fxf4tjpj6hz4v2ddcs7_hln8000017/T/tmpa3gRos.mozrunner/runtests_leaks_tab_pid1812.log
[task 2021-02-02T04:06:59.192Z] 04:06:59     INFO - ==> process 1812 will purposefully crash
[task 2021-02-02T04:06:59.192Z] 04:06:59     INFO - TEST-INFO | leakcheck | tab deliberate crash and thus no leak log
[task 2021-02-02T04:06:59.192Z] 04:06:59     INFO - leakcheck | Processing leak log file /var/folders/4y/fxf4tjpj6hz4v2ddcs7_hln8000017/T/tmpa3gRos.mozrunner/runtests_leaks_tab_pid1797.log
[task 2021-02-02T04:06:59.192Z] 04:06:59     INFO - ==> process 1797 will purposefully crash
[task 2021-02-02T04:06:59.192Z] 04:06:59     INFO - TEST-INFO | leakcheck | tab deliberate crash and thus no leak log
[task 2021-02-02T04:06:59.192Z] 04:06:59     INFO - leakcheck | Processing leak log file /var/folders/4y/fxf4tjpj6hz4v2ddcs7_hln8000017/T/tmpa3gRos.mozrunner/runtests_leaks_tab_pid1805.log
[task 2021-02-02T04:06:59.192Z] 04:06:59     INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 1805
[task 2021-02-02T04:06:59.193Z] 04:06:59     INFO - 
[task 2021-02-02T04:06:59.193Z] 04:06:59     INFO -      |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2021-02-02T04:06:59.193Z] 04:06:59     INFO -      |                                      | Per-Inst   Leaked|   Total      Rem|
[task 2021-02-02T04:06:59.193Z] 04:06:59     INFO -    0 |TOTAL                                 |       34        0|  471609        0|
[task 2021-02-02T04:06:59.193Z] 04:06:59     INFO - 
[task 2021-02-02T04:06:59.193Z] 04:06:59     INFO - nsTraceRefcnt::DumpStatistics: 834 entries
[task 2021-02-02T04:06:59.193Z] 04:06:59     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2021-02-02T04:06:59.193Z] 04:06:59     INFO - leakcheck | Processing leak log file /var/folders/4y/fxf4tjpj6hz4v2ddcs7_hln8000017/T/tmpa3gRos.mozrunner/runtests_leaks_tab_pid1795.log
[task 2021-02-02T04:06:59.194Z] 04:06:59     INFO - ==> process 1795 will purposefully crash
[task 2021-02-02T04:06:59.194Z] 04:06:59     INFO - TEST-INFO | leakcheck | tab deliberate crash and thus no leak log
[task 2021-02-02T04:06:59.194Z] 04:06:59     INFO - leakcheck | Processing leak log file /var/folders/4y/fxf4tjpj6hz4v2ddcs7_hln8000017/T/tmpa3gRos.mozrunner/runtests_leaks_tab_pid1810.log
[task 2021-02-02T04:06:59.194Z] 04:06:59     INFO - ==> process 1810 will purposefully crash
[task 2021-02-02T04:06:59.194Z] 04:06:59     INFO - TEST-INFO | leakcheck | tab deliberate crash and thus no leak log
[task 2021-02-02T04:06:59.194Z] 04:06:59     INFO - leakcheck | Processing leak log file /var/folders/4y/fxf4tjpj6hz4v2ddcs7_hln8000017/T/tmpa3gRos.mozrunner/runtests_leaks_tab_pid1814.log
[task 2021-02-02T04:06:59.194Z] 04:06:59     INFO - ==> process 1814 will purposefully crash
[task 2021-02-02T04:06:59.194Z] 04:06:59     INFO - TEST-INFO | leakcheck | tab deliberate crash and thus no leak log
[task 2021-02-02T04:06:59.195Z] 04:06:59     INFO - leakcheck | Processing leak log file /var/folders/4y/fxf4tjpj6hz4v2ddcs7_hln8000017/T/tmpa3gRos.mozrunner/runtests_leaks_tab_pid1790.log
[task 2021-02-02T04:06:59.195Z] 04:06:59     INFO - ==> process 1790 will purposefully crash
[task 2021-02-02T04:06:59.195Z] 04:06:59     INFO - TEST-INFO | leakcheck | tab deliberate crash and thus no leak log
[task 2021-02-02T04:06:59.195Z] 04:06:59     INFO - leakcheck | Processing leak log file /var/folders/4y/fxf4tjpj6hz4v2ddcs7_hln8000017/T/tmpa3gRos.mozrunner/runtests_leaks_tab_pid1791.log
[task 2021-02-02T04:06:59.195Z] 04:06:59     INFO - 
[task 2021-02-02T04:06:59.195Z] 04:06:59     INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 1791
[task 2021-02-02T04:06:59.195Z] 04:06:59     INFO - 
[task 2021-02-02T04:06:59.195Z] 04:06:59     INFO -      |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2021-02-02T04:06:59.195Z] 04:06:59     INFO -      |                                      | Per-Inst   Leaked|   Total      Rem|
[task 2021-02-02T04:06:59.195Z] 04:06:59     INFO -    0 |TOTAL                                 |       36        0|  208655        0|
[task 2021-02-02T04:06:59.195Z] 04:06:59     INFO - 
[task 2021-02-02T04:06:59.196Z] 04:06:59     INFO - nsTraceRefcnt::DumpStatistics: 939 entries
[task 2021-02-02T04:06:59.196Z] 04:06:59     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2021-02-02T04:06:59.196Z] 04:06:59     INFO - leakcheck | Processing leak log file /var/folders/4y/fxf4tjpj6hz4v2ddcs7_hln8000017/T/tmpa3gRos.mozrunner/runtests_leaks_tab_pid1801.log
[task 2021-02-02T04:06:59.196Z] 04:06:59     INFO - ==> process 1801 will purposefully crash
[task 2021-02-02T04:06:59.196Z] 04:06:59     INFO - TEST-INFO | leakcheck | tab deliberate crash and thus no leak log
[task 2021-02-02T04:06:59.196Z] 04:06:59     INFO - leakcheck | Processing leak log file /var/folders/4y/fxf4tjpj6hz4v2ddcs7_hln8000017/T/tmpa3gRos.mozrunner/runtests_leaks_tab_pid1803.log
[task 2021-02-02T04:06:59.196Z] 04:06:59     INFO - ==> process 1803 will purposefully crash
[task 2021-02-02T04:06:59.196Z] 04:06:59     INFO - TEST-INFO | leakcheck | tab deliberate crash and thus no leak log
[task 2021-02-02T04:06:59.196Z] 04:06:59     INFO - leakcheck | Processing leak log file /var/folders/4y/fxf4tjpj6hz4v2ddcs7_hln8000017/T/tmpa3gRos.mozrunner/runtests_leaks_tab_pid1793.log
[task 2021-02-02T04:06:59.196Z] 04:06:59     INFO - ==> process 1793 will purposefully crash
[task 2021-02-02T04:06:59.196Z] 04:06:59     INFO - TEST-INFO | leakcheck | tab deliberate crash and thus no leak log
[task 2021-02-02T04:06:59.197Z] 04:06:59     INFO - leakcheck | Processing leak log file /var/folders/4y/fxf4tjpj6hz4v2ddcs7_hln8000017/T/tmpa3gRos.mozrunner/runtests_leaks_tab_pid1792.log
[task 2021-02-02T04:06:59.197Z] 04:06:59     INFO - ==> process 1792 will purposefully crash
[task 2021-02-02T04:06:59.197Z] 04:06:59     INFO - TEST-INFO | leakcheck | tab deliberate crash and thus no leak log
[task 2021-02-02T04:06:59.197Z] 04:06:59     INFO - leakcheck | Processing leak log file /var/folders/4y/fxf4tjpj6hz4v2ddcs7_hln8000017/T/tmpa3gRos.mozrunner/runtests_leaks_tab_pid1816.log
[task 2021-02-02T04:06:59.197Z] 04:06:59     INFO - ==> process 1816 will purposefully crash
[task 2021-02-02T04:06:59.197Z] 04:06:59     INFO - TEST-INFO | leakcheck | tab deliberate crash and thus no leak log
[task 2021-02-02T04:06:59.197Z] 04:06:59     INFO - leakcheck | Processing leak log file /var/folders/4y/fxf4tjpj6hz4v2ddcs7_hln8000017/T/tmpa3gRos.mozrunner/runtests_leaks_tab_pid1802.log
[task 2021-02-02T04:06:59.197Z] 04:06:59     INFO - ==> process 1802 will purposefully crash
[task 2021-02-02T04:06:59.197Z] 04:06:59     INFO - TEST-INFO | leakcheck | tab deliberate crash and thus no leak log
[task 2021-02-02T04:06:59.197Z] 04:06:59     INFO - runtests.py | Running tests: end.```

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

Jonathan, could you please take a look?
It is the same issue as reported in Bug 1688804.

Flags: needinfo?(jfkthame)
See Also: → 1688804

Ugh, that's frustrating - I ran a bunch of retriggers on try before re-landing bug 1687622, but was not able to reproduce this. And currently on autoland it seems quite variable; we have times like 15ea118b8e5602a20e6b0193ec4535b809b009e0 where 20+ retriggers hit the leak every time, but then we also have series of many pushes where it never occurs.

I don't see how the real "root cause" of this can be bug 1687622, as that didn't change anything about the actual allocation, ownership or management of fonts; it just revised our default fallbacks, which means that we will in some cases be picking a different fallback font than before for certain characters.

My hunch is that we have some sort of timing issue or race condition whereby we don't always finish releasing all cached font instances before shutting down, but it's hard to see how to isolate the problem. I'll create a debug build locally and see if I can get it to reproduce at all.

Flags: needinfo?(jfkthame)

The difference between permanent and passing seems to be the tests which run for bc1. The failing ones run many more test folders, see the 'Test Groups' tab at the bottom above the failure lines. 5/5 of the failures I checked got reported after browser/base/content/test/tabcrashed got executed. With that folder included, the failure is permanent (that view doesn't show backfill tasks).

Interesting - thanks! Is there a way for me to control whether that will be included in bc1 for a given try push?

Either do a mach try empty and then 'Add new Jobs' from the push menu on the top right in Treeherder or mach try fuzzy with test path.

(In reply to Sebastian Hengst [:aryx] (needinfo on intermittent or backout) from comment #7)

Either do a mach try empty and then 'Add new Jobs'

Meant this in combination with filtering by test path: Filter button in second toolbar at top > Test path > Enter the folder and save.

Whiteboard: [retriggered][stockwell needswork:owner]

Latest failures are under browser/base/content/test/tabcrashed/browser_withoutDump.js
Can't add jobs under that manifest for https://treeherder.mozilla.org/jobs?repo=try&resultStatus=testfailed%2Cbusted%2Cexception&classifiedState=unclassified&revision=6413a07a4bf27b2c2228b927228afc3d496dbff6

Assignee: nobody → apavel
Status: NEW → ASSIGNED
Assignee: apavel → nobody
Status: ASSIGNED → NEW
Assignee: nobody → apavel
Status: NEW → ASSIGNED

Jonathan can the failing test be disabled until there is a fix here?

Assignee: apavel → nobody
Status: ASSIGNED → NEW
Flags: needinfo?(jfkthame)

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

Jonathan can the failing test be disabled until there is a fix here?

I guess that's OK.... although I think there's a chance it'll just start to show up on a different test. But it's fine to try.

(I'm not sure whether there is really a bug here; IIUC, this is happening on testcases that have explicitly crashed a tab, which means we may not have had the opportunity to clean up and free resources properly. But I'm hoping to try and figure out what font is involved, and if there's something we can do about it.)

Flags: needinfo?(jfkthame)
Pushed by archaeopteryx@coole-files.de:
https://hg.mozilla.org/integration/autoland/rev/229f6b955db1
disable browser_withoutDump.js on osx-qr debug r=intermittent-reviewers,jmaher DONTBUILD
Whiteboard: [retriggered][stockwell disable-recommended] → [retriggered][stockwell disabled]

Still an issue, needs bisection which test(s) trigger the leak.

Pushed by jkew@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/9b1fb42a6171
Fix possible leaks of an unreferenced gfxFont in FindFontForChar and GlobalFontFallback. r=m_kato
https://hg.mozilla.org/integration/autoland/rev/7e0472edbc43
Back out changeset 229f6b955db1 to re-enable browser_withoutDump.js test. r=m_kato

Backout of changeset 229f6b955db1 to re-enable browser_withoutDump.js test merged:
https://hg.mozilla.org/mozilla-central/rev/7e0472edbc43

Keywords: leave-open
Whiteboard: [retriggered][stockwell disabled] → [retriggered][stockwell fixed:patch]
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → FIXED
Target Milestone: --- → 87 Branch
Assignee: nobody → apavel
Assignee: apavel → nobody
Assignee: nobody → jfkthame
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: