Closed Bug 1465658 Opened 2 years ago Closed 8 months ago

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

Categories

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

defect

Tracking

()

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
Duplicate of this bug: 1465688
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)
See Also: → 1470858
(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)
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, ...)
Duplicate of this bug: 1528223
Duplicate of this bug: 1533311
Duplicate of this bug: 1517357
Duplicate of this bug: 1530336

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: 8 months ago
No longer depends on: 1542800
Resolution: --- → DUPLICATE
Duplicate of bug: 1542800
You need to log in before you can comment on or make changes to this bug.