Closed Bug 1465658 Opened 6 years ago Closed 6 years ago

Intermittent leakcheck | gpu process: X bytes leaked (APZCTreeManager::UpdateZoomConstraints, APZUpdater, APZUpdater::UpdateHitTestingTree, AsyncPanZoomController, CompositorManagerParent, ...)

Categories

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

Product:
Core
Component:
Graphics: WebRender
Type:
defect

Tracking

()

Status:
RESOLVED DUPLICATE of bug 1542800

People

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

References

(Blocks 1 open bug, Regression)

Details

(Keywords: intermittent-failure, regression, Whiteboard: [retriggered][gfx-noted])

Filed by: aiakab [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=180986740&repo=autoland https://queue.taskcluster.net/v1/task/B0DRRzPjTXqu7_j_QGFq5g/runs/0/artifacts/public/logs/live_backing.log 21:46:16 INFO - 2487 INFO TEST-OK | dom/media/webspeech/synth/test/test_speech_simple.html | took 462ms 21:46:16 INFO - GECKO(5540) | ++DOMWINDOW == 44 (000002046CD89800) [pid = 6148] [serial = 82] [outer = 000002046C45E000] 21:46:16 INFO - 2488 INFO None2489 INFO TEST-START | Shutdown 21:46:16 INFO - 2490 INFO Passed: 322 21:46:16 INFO - 2491 INFO Failed: 0 21:46:16 INFO - 2492 INFO Todo: 0 21:46:16 INFO - 2493 INFO Mode: e10s 21:46:16 INFO - 2494 INFO Slowest: 2834ms - /tests/dom/media/webspeech/synth/test/test_bfcache.html 21:46:16 INFO - 2495 INFO SimpleTest FINISHED 21:46:16 INFO - 2496 INFO TEST-INFO | Ran 1 Loops 21:46:16 INFO - 2497 INFO SimpleTest FINISHED 21:46:16 INFO - GECKO(5540) | --DOMWINDOW == 43 (000002046B6EE800) [pid = 6148] [serial = 55] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/dom/media/webspeech/synth/test/file_setup.html] 21:46:16 INFO - GECKO(5540) | --DOMWINDOW == 42 (000002046B6EC600) [pid = 6148] [serial = 49] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/dom/media/webspeech/synth/test/file_indirect_service_events.html] 21:46:16 INFO - GECKO(5540) | --DOMWINDOW == 41 (000002046021AC00) [pid = 6148] [serial = 9] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/dom/media/webspeech/synth/test/file_bfcache_page2.html] 21:46:16 INFO - GECKO(5540) | ++DOMWINDOW == 42 (000002046C4A9000) [pid = 6148] [serial = 83] [outer = 000002046C45E000] 21:46:16 INFO - GECKO(5540) | [GPU 6556, Compositor] WARNING: Possibly dropping task posted to updater thread: file z:/build/build/src/gfx/layers/apz/src/APZUpdater.cpp, line 416 21:46:16 INFO - GECKO(5540) | Destroying context 000001A8D12E5000 surface 000001A8CC377580 on display 000001A8D1243050 21:46:16 INFO - GECKO(5540) | [Parent 5540, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:46:16 INFO - GECKO(5540) | [GPU 6556, Compositor] WARNING: Possibly dropping task posted to updater thread: file z:/build/build/src/gfx/layers/apz/src/APZUpdater.cpp, line 416 21:46:16 INFO - GECKO(5540) | [GPU 6556, Compositor] WARNING: Possibly dropping task posted to updater thread: file z:/build/build/src/gfx/layers/apz/src/APZUpdater.cpp, line 416 21:46:16 INFO - GECKO(5540) | [Child 3052, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:46:16 INFO - GECKO(5540) | [Child 3052, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:46:16 INFO - GECKO(5540) | [GPU 6556, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:46:16 INFO - GECKO(5540) | [GPU 6556, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:46:16 INFO - GECKO(5540) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 21:46:16 INFO - GECKO(5540) | [GPU 6556, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:46:16 INFO - GECKO(5540) | [GPU 6556, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:46:16 INFO - GECKO(5540) | [Parent 5540, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:46:16 INFO - GECKO(5540) | [Child 3052, Main Thread] WARNING: NS_ENSURE_TRUE(maybeContext) failed: file z:/build/build/src/xpcom/threads/nsThread.cpp, line 803 21:46:16 INFO - GECKO(5540) | [Parent 5540, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:46:16 INFO - GECKO(5540) | --DOMWINDOW == 2 (00000187656E4C00) [pid = 3052] [serial = 2] [outer = 0000000000000000] [url = about:blank] 21:46:16 INFO - GECKO(5540) | --DOCSHELL 0000018760347800 == 0 [pid = 3052] [id = {ddb6893d-e845-4ced-8f69-00760aeb3e2b}] 21:46:16 INFO - GECKO(5540) | [Child 3052, Main Thread] WARNING: '!gThread', file z:/build/build/src/xpcom/threads/nsTimerImpl.cpp, line 399 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 1 (0000018760313A00) [pid = 3052] [serial = 1] [outer = 0000000000000000] [url = moz-extension://77434aa7-7650-404c-b122-c522024910c8/_generated_background_page.html] 21:46:17 INFO - GECKO(5540) | [Child 3052, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 3052, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 3052, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 3052, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 3052, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 3052, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 3052, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 3052, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 3052, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 0 (000001876B8DA400) [pid = 3052] [serial = 3] [outer = 0000000000000000] [url = moz-extension://77434aa7-7650-404c-b122-c522024910c8/_generated_background_page.html] 21:46:17 INFO - GECKO(5540) | nsStringStats 21:46:17 INFO - GECKO(5540) | => mAllocCount: 8529 21:46:17 INFO - GECKO(5540) | => mReallocCount: 202 21:46:17 INFO - GECKO(5540) | => mFreeCount: 8529 21:46:17 INFO - GECKO(5540) | => mShareCount: 7310 21:46:17 INFO - GECKO(5540) | => mAdoptCount: 830 21:46:17 INFO - GECKO(5540) | => mAdoptFreeCount: 836 21:46:17 INFO - GECKO(5540) | => Process ID: 3052, Thread ID: 2324 21:46:17 INFO - GECKO(5540) | --DOCSHELL 0000025B108BF800 == 3 [pid = 5540] [id = {6d405912-1333-4796-b2bf-3b3609d6c894}] 21:46:17 INFO - GECKO(5540) | [Parent 5540, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 14 (0000025B1695C400) [pid = 5540] [serial = 14] [outer = 0000000000000000] [url = about:blank] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 13 (0000025B1695B800) [pid = 5540] [serial = 13] [outer = 0000000000000000] [url = about:blank] 21:46:17 INFO - GECKO(5540) | --DOCSHELL 0000025B108C1000 == 2 [pid = 5540] [id = {945bc090-120f-4de6-bdd1-54ac318018ad}] 21:46:17 INFO - GECKO(5540) | --DOCSHELL 0000025B14B25800 == 1 [pid = 5540] [id = {2e50feaa-a73c-43b9-a9a3-8c82d672ca7e}] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 12 (0000025B10064400) [pid = 5540] [serial = 4] [outer = 0000000000000000] [url = about:blank] 21:46:17 INFO - GECKO(5540) | [Child 6148, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:46:17 INFO - GECKO(5540) | [Child 6148, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:46:17 INFO - GECKO(5540) | --DOCSHELL 0000025B108D3000 == 0 [pid = 5540] [id = {608d6897-d9da-4de6-9936-7b9e8c5b070d}] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 2 (0000028DBD17DC00) [pid = 5516] [serial = 2] [outer = 0000000000000000] [url = about:blank] 21:46:17 INFO - GECKO(5540) | [GPU 6556, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:46:17 INFO - GECKO(5540) | [Parent 5540, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:46:17 INFO - GECKO(5540) | [GPU 6556, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:46:17 INFO - GECKO(5540) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 21:46:17 INFO - GECKO(5540) | [Child 5516, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:46:17 INFO - GECKO(5540) | [Child 5516, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:46:17 INFO - GECKO(5540) | [GPU 6556, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:46:17 INFO - GECKO(5540) | [Child 6148, Main Thread] WARNING: NS_ENSURE_TRUE(maybeContext) failed: file z:/build/build/src/xpcom/threads/nsThread.cpp, line 803 21:46:17 INFO - GECKO(5540) | [Parent 5540, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:46:17 INFO - GECKO(5540) | [Parent 5540, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:46:17 INFO - GECKO(5540) | [GPU 6556, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:46:17 INFO - GECKO(5540) | [GPU 6556, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:46:17 INFO - GECKO(5540) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 21:46:17 INFO - GECKO(5540) | Destroying context 000001A8D1293000 surface 000001A8CC377200 on display 000001A8D1243050 21:46:17 INFO - GECKO(5540) | [GPU 6556, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:46:17 INFO - GECKO(5540) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 21:46:17 INFO - GECKO(5540) | [Child 5516, Main Thread] WARNING: NS_ENSURE_TRUE(maybeContext) failed: file z:/build/build/src/xpcom/threads/nsThread.cpp, line 803 21:46:17 INFO - GECKO(5540) | [Parent 5540, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 1 (0000028DB7914C00) [pid = 5516] [serial = 1] [outer = 0000000000000000] [url = chrome://gfxsanity/content/sanitytest.html] 21:46:17 INFO - GECKO(5540) | [Child 5516, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 5516, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 5516, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 5516, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 5516, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 5516, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 5516, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 5516, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 5516, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 0 (0000028DC2EC9000) [pid = 5516] [serial = 3] [outer = 0000000000000000] [url = chrome://gfxsanity/content/sanitytest.html] 21:46:17 INFO - GECKO(5540) | nsStringStats 21:46:17 INFO - GECKO(5540) | => mAllocCount: 7779 21:46:17 INFO - GECKO(5540) | => mReallocCount: 160 21:46:17 INFO - GECKO(5540) | => mFreeCount: 7779 21:46:17 INFO - GECKO(5540) | => mShareCount: 6956 21:46:17 INFO - GECKO(5540) | => mAdoptCount: 494 21:46:17 INFO - GECKO(5540) | => mAdoptFreeCount: 494 21:46:17 INFO - GECKO(5540) | => Process ID: 5516, Thread ID: 4112 21:46:17 INFO - GECKO(5540) | --DOCSHELL 000002046B6AF800 == 5 [pid = 6148] [id = {65f17227-1642-4b8b-ac15-b141cf2370c0}] 21:46:17 INFO - GECKO(5540) | --DOCSHELL 000002046C475000 == 4 [pid = 6148] [id = {742cfc71-d10d-4efa-b443-ccd8c2005fb5}] 21:46:17 INFO - GECKO(5540) | --DOCSHELL 000002046C473000 == 3 [pid = 6148] [id = {0bd88e72-abc4-412d-ac7e-d8fa33d090a1}] 21:46:17 INFO - GECKO(5540) | --DOCSHELL 000002046B6A5800 == 2 [pid = 6148] [id = {61c50c2c-8f22-423f-8f11-b94742f37020}] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 41 (000002046D7CD400) [pid = 6148] [serial = 62] [outer = 0000000000000000] [url = about:blank] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 40 (000002046D70B400) [pid = 6148] [serial = 58] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/SimpleTest/iframe-between-tests.html] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 39 (000002046D7CC400) [pid = 6148] [serial = 61] [outer = 0000000000000000] [url = about:blank] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 38 (000002046CD8F000) [pid = 6148] [serial = 57] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/dom/media/webspeech/synth/test/file_setup.html] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 37 (000002046CD87C00) [pid = 6148] [serial = 54] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/dom/media/webspeech/synth/test/test_setup.html] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 36 (000002046CD8D800) [pid = 6148] [serial = 56] [outer = 0000000000000000] [url = about:blank] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 35 (000002046C4AB400) [pid = 6148] [serial = 53] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/SimpleTest/iframe-between-tests.html] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 34 (000002046CD88000) [pid = 6148] [serial = 52] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/dom/media/webspeech/synth/test/file_indirect_service_events.html] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 33 (000002046C4AEC00) [pid = 6148] [serial = 48] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/dom/media/webspeech/synth/test/test_indirect_service_events.html] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 32 (000002046D70AC00) [pid = 6148] [serial = 16] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/dom/media/webspeech/synth/test/file_bfcache_page2.html] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 31 (000002046B6C9400) [pid = 6148] [serial = 8] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/dom/media/webspeech/synth/test/test_bfcache.html] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 30 (000002046C4B4C00) [pid = 6148] [serial = 28] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/dom/media/webspeech/synth/test/test_global_queue_cancel.html] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 29 (000002046B6C8C00) [pid = 6148] [serial = 18] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/dom/media/webspeech/synth/test/test_global_queue.html] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 28 (000002046C4B1800) [pid = 6148] [serial = 38] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/dom/media/webspeech/synth/test/test_global_queue_pause.html] 21:46:17 INFO - GECKO(5540) | --DOCSHELL 0000020460246800 == 1 [pid = 6148] [id = {500a1dc2-ab91-4796-86ed-dc9c548be39e}] 21:46:17 INFO - GECKO(5540) | --DOCSHELL 000002046B6A4000 == 0 [pid = 6148] [id = {69d99b43-9584-4108-a18b-f4c78cfb2835}] 21:46:17 INFO - GECKO(5540) | 1527716777642 Marionette DEBUG Received observer notification xpcom-will-shutdown 21:46:17 INFO - GECKO(5540) | 1527716777643 Marionette INFO Stopped listening on port 2828 21:46:17 INFO - GECKO(5540) | 1527716777643 Marionette DEBUG Remote service is inactive 21:46:17 INFO - GECKO(5540) | [Parent 5540, Main Thread] WARNING: NS_ENSURE_TRUE(mDB) failed: file z:/build/build/src/netwerk/cache/nsDiskCacheDeviceSQL.cpp, line 1421 21:46:17 INFO - GECKO(5540) | [GPU 6556, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:46:17 INFO - GECKO(5540) | [GPU 6556, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:46:17 INFO - GECKO(5540) | [Parent 5540, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:46:17 INFO - GECKO(5540) | [Parent 5540, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 27 (000002046B6E9600) [pid = 6148] [serial = 66] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/dom/media/webspeech/synth/test/file_speech_error.html] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 26 (0000020460214C00) [pid = 6148] [serial = 1] [outer = 0000000000000000] [url = http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Ctask_1527713630%5Cappdata%5Clocal%5Ctemp&cleanupCrashes=true] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 25 (000002046B78F400) [pid = 6148] [serial = 4] [outer = 0000000000000000] [url = http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Ctask_1527713630%5Cappdata%5Clocal%5Ctemp&cleanupCrashes=true] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 24 (000002046C4B3400) [pid = 6148] [serial = 67] [outer = 0000000000000000] [url = about:blank] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 23 (000002046C45E000) [pid = 6148] [serial = 5] [outer = 0000000000000000] [url = about:blank] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 22 (000002046E6E3C00) [pid = 6148] [serial = 76] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/SimpleTest/iframe-between-tests.html] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 21 (000002046D7D1000) [pid = 6148] [serial = 68] [outer = 0000000000000000] [url = about:blank] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 20 (000002046D7CC000) [pid = 6148] [serial = 73] [outer = 0000000000000000] [url = about:blank] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 19 (000002046D7D6C00) [pid = 6148] [serial = 74] [outer = 0000000000000000] [url = about:blank] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 18 (000002046D109600) [pid = 6148] [serial = 72] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/dom/media/webspeech/synth/test/file_speech_queue.html] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 17 (000002046D109000) [pid = 6148] [serial = 60] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/dom/media/webspeech/synth/test/file_speech_cancel.html] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 16 (000002046B6E9C00) [pid = 6148] [serial = 78] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/dom/media/webspeech/synth/test/file_speech_simple.html] 21:46:17 INFO - GECKO(5540) | [Child 6148, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 6148, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 6148, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 6148, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 6148, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 6148, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 6148, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 6148, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 6148, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 15 (000002046C4AC800) [pid = 6148] [serial = 79] [outer = 0000000000000000] [url = about:blank] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 14 (000002046C4B3C00) [pid = 6148] [serial = 80] [outer = 0000000000000000] [url = about:blank] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 13 (000002046587C000) [pid = 6148] [serial = 64] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/SimpleTest/iframe-between-tests.html] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 12 (000002046C4A9000) [pid = 6148] [serial = 83] [outer = 0000000000000000] [url = about:blank] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 11 (000002046D7D2800) [pid = 6148] [serial = 70] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/SimpleTest/iframe-between-tests.html] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 10 (000002046B6E9200) [pid = 6148] [serial = 12] [outer = 0000000000000000] [url = about:newtab] 21:46:17 INFO - GECKO(5540) | [Child 6148, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 6148, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 6148, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 6148, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 6148, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 6148, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 6148, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 6148, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | [Child 6148, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 9 (000002046CD89800) [pid = 6148] [serial = 82] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/SimpleTest/iframe-between-tests.html] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 8 (000002046D7D0C00) [pid = 6148] [serial = 71] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/dom/media/webspeech/synth/test/test_speech_queue.html] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 7 (000002046D70E000) [pid = 6148] [serial = 65] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/dom/media/webspeech/synth/test/test_speech_error.html] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 6 (000002046CD86800) [pid = 6148] [serial = 14] [outer = 0000000000000000] [url = about:newtab] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 5 (000002046D70B000) [pid = 6148] [serial = 59] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/dom/media/webspeech/synth/test/test_speech_cancel.html] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 4 (000002046C4AC400) [pid = 6148] [serial = 81] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/dom/media/webspeech/synth/test/file_speech_simple.html] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 3 (000002046E6E4C00) [pid = 6148] [serial = 75] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/dom/media/webspeech/synth/test/file_speech_queue.html] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 2 (000002046D7CD000) [pid = 6148] [serial = 63] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/dom/media/webspeech/synth/test/file_speech_cancel.html] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 1 (000002046B795C00) [pid = 6148] [serial = 77] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/dom/media/webspeech/synth/test/test_speech_simple.html] 21:46:17 INFO - GECKO(5540) | --DOMWINDOW == 0 (000002046D7D0000) [pid = 6148] [serial = 69] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/dom/media/webspeech/synth/test/file_speech_error.html] 21:46:17 INFO - GECKO(5540) | nsStringStats 21:46:17 INFO - GECKO(5540) | => mAllocCount: 33949 21:46:17 INFO - GECKO(5540) | => mReallocCount: 1359 21:46:17 INFO - GECKO(5540) | => mFreeCount: 33949 21:46:17 INFO - GECKO(5540) | => mShareCount: 54924 21:46:17 INFO - GECKO(5540) | => mAdoptCount: 1941 21:46:17 INFO - GECKO(5540) | => mAdoptFreeCount: 2147 21:46:17 INFO - GECKO(5540) | => Process ID: 6148, Thread ID: 184 21:46:17 INFO - GECKO(5540) | [Parent 5540, Main Thread] WARNING: NS_ENSURE_TRUE(maybeContext) failed: file z:/build/build/src/xpcom/threads/nsThread.cpp, line 803 21:46:17 INFO - GECKO(5540) | nsStringStats 21:46:17 INFO - GECKO(5540) | => mAllocCount: 447 21:46:17 INFO - GECKO(5540) | => mReallocCount: 95 21:46:17 INFO - GECKO(5540) | => mFreeCount: 447 21:46:17 INFO - GECKO(5540) | => mShareCount: 735 21:46:17 INFO - GECKO(5540) | => mAdoptCount: 0 21:46:17 INFO - GECKO(5540) | => mAdoptFreeCount: 0 21:46:17 INFO - GECKO(5540) | => Process ID: 6556, Thread ID: 4180 21:46:18 INFO - GECKO(5540) | --DOMWINDOW == 11 (0000025B10067400) [pid = 5540] [serial = 6] [outer = 0000000000000000] [url = about:blank] 21:46:18 INFO - GECKO(5540) | --DOMWINDOW == 10 (0000025B14B95A00) [pid = 5540] [serial = 9] [outer = 0000000000000000] [url = about:blank] 21:46:18 INFO - GECKO(5540) | [Parent 5540, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:18 INFO - GECKO(5540) | [Parent 5540, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:18 INFO - GECKO(5540) | [Parent 5540, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:18 INFO - GECKO(5540) | [Parent 5540, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:18 INFO - GECKO(5540) | [Parent 5540, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:18 INFO - GECKO(5540) | [Parent 5540, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:18 INFO - GECKO(5540) | [Parent 5540, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:18 INFO - GECKO(5540) | [Parent 5540, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:18 INFO - GECKO(5540) | [Parent 5540, Main Thread] WARNING: '!mMainThread', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 517 21:46:18 INFO - GECKO(5540) | --DOMWINDOW == 9 (0000025B16179C00) [pid = 5540] [serial = 10] [outer = 0000000000000000] [url = about:blank] 21:46:18 INFO - GECKO(5540) | --DOMWINDOW == 8 (0000025B11191E00) [pid = 5540] [serial = 1] [outer = 0000000000000000] [url = chrome://gfxsanity/content/sanityparent.html] 21:46:18 INFO - GECKO(5540) | --DOMWINDOW == 7 (0000025B04B28C00) [pid = 5540] [serial = 2] [outer = 0000000000000000] [url = about:blank] 21:46:18 INFO - GECKO(5540) | --DOMWINDOW == 6 (0000025B04B0D400) [pid = 5540] [serial = 12] [outer = 0000000000000000] [url = chrome://extensions/content/dummy.xul] 21:46:18 INFO - GECKO(5540) | --DOMWINDOW == 5 (0000025B1695DC00) [pid = 5540] [serial = 15] [outer = 0000000000000000] [url = chrome://extensions/content/dummy.xul] 21:46:18 INFO - GECKO(5540) | --DOMWINDOW == 4 (0000025B0FB1C000) [pid = 5540] [serial = 5] [outer = 0000000000000000] [url = chrome://browser/content/browser.xul] 21:46:18 INFO - GECKO(5540) | --DOMWINDOW == 3 (0000025B04B0E400) [pid = 5540] [serial = 3] [outer = 0000000000000000] [url = resource://gre-resources/hiddenWindow.html] 21:46:18 INFO - GECKO(5540) | --DOMWINDOW == 2 (0000025B16791000) [pid = 5540] [serial = 11] [outer = 0000000000000000] [url = about:blank] 21:46:18 INFO - GECKO(5540) | --DOMWINDOW == 1 (0000025B14B95800) [pid = 5540] [serial = 8] [outer = 0000000000000000] [url = about:blank] 21:46:18 INFO - GECKO(5540) | --DOMWINDOW == 0 (0000025B10071400) [pid = 5540] [serial = 7] [outer = 0000000000000000] [url = resource://gre-resources/hiddenWindow.html] 21:46:18 INFO - GECKO(5540) | nsStringStats 21:46:18 INFO - GECKO(5540) | => mAllocCount: 104001 21:46:18 INFO - GECKO(5540) | => mReallocCount: 8326 21:46:18 INFO - GECKO(5540) | => mFreeCount: 104001 21:46:18 INFO - GECKO(5540) | => mShareCount: 102115 21:46:18 INFO - GECKO(5540) | => mAdoptCount: 3352 21:46:18 INFO - GECKO(5540) | => mAdoptFreeCount: 3584 21:46:18 INFO - GECKO(5540) | => Process ID: 5540, Thread ID: 1676 21:46:18 INFO - TEST-INFO | Main app process: exit 0 21:46:18 INFO - runtests.py | Application ran for: 0:00:22.284000 21:46:18 INFO - zombiecheck | Reading PID log: c:\users\task_1527713630\appdata\local\temp\tmpc8s2t7pidlog 21:46:18 INFO - ==> process 5540 launched child process 6556 ("Z:\task_1527713630\build\application\firefox\firefox.exe" -contentproc --channel="5540.0.582351658\1561429044" -parentBuildID 20180530162942 -greomni "Z:\task_1527713630\build\application\firefox\omni.ja" -appomni "Z:\task_1527713630\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1527713630\build\application\firefox\browser" - "C:\Users\task_1527713630\AppData\LocalLow\Mozilla\Temp-{5ffcaa2b-78d2-4892-977f-8601a4f66098}" 5540 "\\.\pipe\gecko-crash-server-pipe.5540" 1644 gpu) 21:46:18 INFO - ==> process 5540 launched child process 5516 ("Z:\task_1527713630\build\application\firefox\firefox.exe" -contentproc --channel="5540.3.1640395592\2038918136" -childID 1 -isForBrowser -prefsHandle 2164 -prefsLen 16263 -schedulerPrefs 0001,2 -parentBuildID 20180530162942 -greomni "Z:\task_1527713630\build\application\firefox\omni.ja" -appomni "Z:\task_1527713630\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1527713630\build\application\firefox\browser" - 5540 "\\.\pipe\gecko-crash-server-pipe.5540" 2156 tab) 21:46:18 INFO - ==> process 5540 launched child process 6148 ("Z:\task_1527713630\build\application\firefox\firefox.exe" -contentproc --channel="5540.13.2014547806\1311324198" -childID 2 -isForBrowser -prefsHandle 2616 -prefsLen 16263 -schedulerPrefs 0001,2 -parentBuildID 20180530162942 -greomni "Z:\task_1527713630\build\application\firefox\omni.ja" -appomni "Z:\task_1527713630\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1527713630\build\application\firefox\browser" - 5540 "\\.\pipe\gecko-crash-server-pipe.5540" 2640 tab) 21:46:18 INFO - ==> process 5540 launched child process 3052 ("Z:\task_1527713630\build\application\firefox\firefox.exe" -contentproc --channel="5540.20.1551968785\2100166179" -childID 3 -isForBrowser -prefsHandle 2948 -prefsLen 17573 -schedulerPrefs 0001,2 -parentBuildID 20180530162942 -greomni "Z:\task_1527713630\build\application\firefox\omni.ja" -appomni "Z:\task_1527713630\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1527713630\build\application\firefox\browser" - 5540 "\\.\pipe\gecko-crash-server-pipe.5540" 2972 tab) 21:46:18 INFO - zombiecheck | Checking for orphan process with PID: 5516 21:46:18 INFO - zombiecheck | Checking for orphan process with PID: 6556 21:46:18 INFO - zombiecheck | Checking for orphan process with PID: 3052 21:46:18 INFO - zombiecheck | Checking for orphan process with PID: 6148 21:46:18 INFO - Stopping web server 21:46:18 INFO - Stopping web socket server 21:46:18 INFO - Stopping ssltunnel 21:46:18 INFO - Stopping websocket/process bridge 21:46:18 INFO - TEST-INFO | leakcheck | default process: leak threshold set at 0 bytes 21:46:18 INFO - TEST-INFO | leakcheck | plugin process: leak threshold set at 0 bytes 21:46:18 INFO - TEST-INFO | leakcheck | tab process: leak threshold set at 1000 bytes 21:46:18 INFO - TEST-INFO | leakcheck | geckomediaplugin process: leak threshold set at 20000 bytes 21:46:18 INFO - TEST-INFO | leakcheck | gpu process: leak threshold set at 0 bytes 21:46:18 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, default process 5540 21:46:18 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->| 21:46:18 INFO - | | Per-Inst Leaked| Total Rem| 21:46:18 INFO - 0 |TOTAL | 43 0| 6372040 0| 21:46:18 INFO - nsTraceRefcnt::DumpStatistics: 1858 entries 21:46:18 INFO - TEST-PASS | leakcheck | default process: no leaks detected! 21:46:18 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, gpu process 6556 21:46:18 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->| 21:46:18 INFO - | | Per-Inst Leaked| Total Rem| 21:46:18 INFO - 0 |TOTAL | 19 11652| 101282 198| 21:46:18 INFO - 2 |APZCTreeManager::UpdateZoomConstraints| 40 40| 35 1| 21:46:18 INFO - 5 |APZUpdater | 384 384| 2 1| 21:46:18 INFO - 13 |APZUpdater::UpdateHitTestingTree | 40 40| 218 1| 21:46:18 INFO - 19 |AsyncPanZoomController | 2192 4384| 13 2| 21:46:18 INFO - 28 |CompositorManagerParent | 296 592| 4 2| 21:46:18 INFO - 32 |CondVar | 64 640| 92 10| 21:46:18 INFO - 56 |IAPZCTreeManager | 16 16| 2 1| 21:46:18 INFO - 57 |IPC::Channel | 8 16| 18 2| 21:46:18 INFO - 60 |ISurfaceAllocator | 16 32| 16 2| 21:46:18 INFO - 66 |InputQueue | 80 80| 2 1| 21:46:18 INFO - 85 |Mutex | 80 1520| 345 19| 21:46:18 INFO - 111 |PCompositorBridgeParent | 280 560| 5 2| 21:46:18 INFO - 115 |PCompositorManagerParent | 256 512| 4 2| 21:46:18 INFO - 184 |RefCountedMonitor | 152 304| 19 2| 21:46:18 INFO - 211 |StoreRef | 16 32| 18 2| 21:46:18 INFO - 259 |ZoomConstraints | 12 36| 150 3| 21:46:18 INFO - 273 |ipc::MessageChannel | 408 816| 20 2| 21:46:18 INFO - 274 |ipc::MessageChannel::DispatchOnChannel| 40 80| 20 2| 21:46:18 INFO - 308 |nsRect | 16 880| 35535 55| 21:46:18 INFO - 310 |nsTArray_base | 8 688| 52571 86| 21:46:18 INFO - nsTraceRefcnt::DumpStatistics: 324 entries 21:46:18 INFO - TEST-INFO | leakcheck | gpu process: leaked 1 APZCTreeManager::UpdateZoomConstraints 21:46:18 INFO - TEST-INFO | leakcheck | gpu process: leaked 1 APZUpdater 21:46:18 INFO - TEST-INFO | leakcheck | gpu process: leaked 1 APZUpdater::UpdateHitTestingTree 21:46:18 INFO - TEST-INFO | leakcheck | gpu process: leaked 2 AsyncPanZoomController 21:46:18 INFO - TEST-INFO | leakcheck | gpu process: leaked 2 CompositorManagerParent 21:46:18 INFO - TEST-INFO | leakcheck | gpu process: leaked 10 CondVar 21:46:18 INFO - TEST-INFO | leakcheck | gpu process: leaked 1 IAPZCTreeManager 21:46:18 INFO - TEST-INFO | leakcheck | gpu process: leaked 2 IPC::Channel 21:46:18 INFO - TEST-INFO | leakcheck | gpu process: leaked 2 ISurfaceAllocator 21:46:18 INFO - TEST-INFO | leakcheck | gpu process: leaked 1 InputQueue 21:46:18 INFO - TEST-INFO | leakcheck | gpu process: leaked 19 Mutex 21:46:18 INFO - TEST-INFO | leakcheck | gpu process: leaked 2 PCompositorBridgeParent 21:46:18 INFO - TEST-INFO | leakcheck | gpu process: leaked 2 PCompositorManagerParent 21:46:18 INFO - TEST-INFO | leakcheck | gpu process: leaked 2 RefCountedMonitor 21:46:18 INFO - TEST-INFO | leakcheck | gpu process: leaked 2 StoreRef 21:46:18 INFO - TEST-INFO | leakcheck | gpu process: leaked 3 ZoomConstraints 21:46:18 INFO - TEST-INFO | leakcheck | gpu process: leaked 2 ipc::MessageChannel 21:46:18 INFO - TEST-INFO | leakcheck | gpu process: leaked 2 ipc::MessageChannel::DispatchOnChannel 21:46:18 INFO - TEST-INFO | leakcheck | gpu process: leaked 55 nsRect 21:46:18 INFO - TEST-INFO | leakcheck | gpu process: leaked 86 nsTArray_base 21:46:18 ERROR - 2498 ERROR TEST-UNEXPECTED-FAIL | leakcheck | gpu process: 11652 bytes leaked (APZCTreeManager::UpdateZoomConstraints, APZUpdater, APZUpdater::UpdateHitTestingTree, AsyncPanZoomController, CompositorManagerParent, ...) 21:46:18 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 3052
This doesn't look audio related at all, even though failing test is webspeech.
Component: Audio/Video → Web Speech
In the last 7 days, there are 47 failures on this bug. They occur on windows10-64-qr (debug). Recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=182786009&repo=autoland&lineNumber=1553 anatal: can you have a look at this?
Flags: needinfo?(anatal)
Whiteboard: [retriggered] → [retriggered][stockwell needswork]
In the last 7 days, there are 33 failures on windows10-64-qr (debug). Recent log: https://treeherder.mozilla.org/logviewer.html#?job_id=182896732&repo=autoland&lineNumber=114795
Frequent intermittent Quantumrender failure.
Component: Web Speech → Graphics: WebRender
Flags: needinfo?(anatal) → needinfo?(bugmail)
(In reply to Tiberius Oros[:tiberius_oros] from comment #3) > I ran retriggers on the following pushes: > https://treeherder.mozilla.org/#/jobs?repo=autoland&filter- > searchStr=Windows%2010%20x64%20QuantumRender%20debug%20Mochitests%20with%20e1 > 0s%20test-windows10-64-qr%2Fdebug-mochitest-media-e10s%20M- > e10s(mda)&tochange=e8eb0005b50cbcd82856604682d8dfceb7be1ab7&fromchange=1bbb18 > b5af9742f2c1bd9aae65724f75cfbf79d4 > > And it seems that the failure originated from here: > https://treeherder.mozilla.org/#/ > jobs?repo=autoland&revision=a4465713555bee4fea1ec2c295c7ca51b720903a&filter- > searchStr=Windows%2010%20x64%20QuantumRender%20debug%20Mochitests%20with%20e1 > 0s%20test-windows10-64-qr%2Fdebug-mochitest-media-e10s%20M-e10s(mda) Probably a regression from bug 1452845 which landed just before that in the range. I'll take a look.
Assignee: nobody → bugmail
Blocks: 1452845
Flags: needinfo?(bugmail)
Blocks: stage-wr-backlog
Whiteboard: [retriggered][stockwell disable-recommended] → [retriggered][stockwell disable-recommended][gfx-noted]
Over the last 7 days there are 31 failures on this bug. These happen on windows10-64-qr and linux64-qr. Here is the most recent log example: https://treeherder.mozilla.org/logviewer.html#?job_id=186202190&repo=autoland&lineNumber=1492 14:44:30 ERROR - 13 ERROR TEST-UNEXPECTED-FAIL | leakcheck | gpu process: 11684 bytes leaked (APZCTreeManager::UpdateZoomConstraints, APZUpdater, APZUpdater::UpdateHitTestingTree, AsyncPanZoomController, CompositorManagerParent, ...)
Flags: needinfo?(bugmail)
It's on my list of things to look at.
Flags: needinfo?(bugmail)
Given that the leaked objects include runnables posted to the updater thread, it seems likely that we're leaking an APZUpdater instance which is holding on to stuff. The APZUpdater in turn is refcounted, and APZCTreeManagerParent and CompositorBridgeParent hold refptrs to it. APZCTreeManagerParent is *not* refcounted; we allocate and deallocate it manually, and we already know (from bug 1446181) that they get leaked sometimes. So that would probably explain this, and fixing those leaks should address this as well.
Depends on: 1446181
The failure rate here has considerably decreased, there are 8 failures in the last 21 days. removing disable recommended.
Whiteboard: [retriggered][stockwell disable-recommended][gfx-noted] → [retriggered][gfx-noted]
Summary: Intermittent leakcheck | gpu process: 11652 bytes leaked (APZCTreeManager::UpdateZoomConstraints, APZUpdater, APZUpdater::UpdateHitTestingTree, AsyncPanZoomController, CompositorManagerParent, ...) → Intermittent leakcheck | gpu process: X bytes leaked (APZCTreeManager::UpdateZoomConstraints, APZUpdater, APZUpdater::UpdateHitTestingTree, AsyncPanZoomController, CompositorManagerParent, ...)

I started looking into this a bit. All the failures that I looked at have an APZUpdater::UpdateHitTestingTree leaked. Notably there is no corresponding APZUpdater::UpdateEpochRequirement leaked which is put into the updater queue just before. So this implies that the epoch requirement runnable is processed, which updates the epoch requirements. And those epoch requirements are never satisfied, leading to the UpdateHitTestingTree runnable never getting run. Subsequently on shutdown it is leaked along with everything it holds on to, which includes at least the APZUpdater and probably a bunch of other stuff.

At any rate, my plan of attack here is to try and figure out why the epoch requirement is not satisfied, and try and correct this leak, and then see if there are any more remaining leaked objects. I'll try reproducing locally on Linux with the GPU process enabled on a debug build, since rr would probably make debugging this a lot easier.

So yeah it looks like the WebRenderBridgeParent is destroyed between the UpdateHitTestingTree task getting queued and getting run. So then the epoch never updates and all the tasks in the queue for that layers id get leaked. I think there's an implicit assumption in the APZUpdater code that every epoch update (i.e. every non-empty transaction that comes over PWebRenderBridgeParent) will trigger a scene build, but I guess that's not the case. We should add a mechanism where the WRBP shutting down can tell the APZUpdater to discard or process all the remaining tasks for that layers id.

There's actually a bit more nuance to this. When the root WebRenderBridgeParent for a window is destroyed it flushes the scene builder queue, so any pending epoch updates and UpdateHitTestingTree tasks should get run. The APZUpdater code relies on this mechanism for clean shutdown.

Further investigation reveals that during the shutdown phase, it's possible for the parent process pipeline to get torn down while the content process is still sending display list updates. This causes the scene builder to go fail this check which then results in an "empty transaction" even though there was a new displaylist and epoch for the content pipeline. In this scenario, the pre/post swap hooks are not triggered, and so APZUpdater doesn't get the epoch update, and this results in the updated tasks getting leaked even when the scene builder thread is flushed.

So the correct fix here is to ensure that even in this "empty transaction" case (more specifically when there is no root pipeline) the APZUpdater gets an updated pipeline info and so runs pending tasks.

I think this "empty transaction" codepath was introduced by bug 1488887, so at least some of the leaks are a regression from that. However it looks like there was a slow trickle of instances of this bug even prior to that landing, so perhaps there are multiple causes. I'll put a fix for this issue in a dependent bug and we can see if this still happens after that lands.

No longer depends on: 1446181
Regressed by: 1488887

Looks like bug 1542800 fixed this.

Status: NEW → RESOLVED
Closed: 6 years ago
No longer depends on: 1542800
Resolution: --- → DUPLICATE
Has Regression Range: --- → yes
You need to log in before you can comment on or make changes to this bug.