Closed Bug 1535729 Opened 5 years ago Closed 5 years ago

Intermittent Last test finished | application crashed [@ mozilla::gfx::VRDisplayHost::Release()] after Assertion failure: int32_t:/build/build/src/netwerk/cache/nsD

Categories

(Core :: WebVR, defect, P2)

defect

Tracking

()

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

People

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

References

Details

(Keywords: crash, intermittent-failure, regression)

Crash Data

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

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

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

https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://queue.taskcluster.net/v1/task/L0xaeTZXRxOjUNtj8i9qAg/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

18:55:12 INFO - REFTEST TEST-START | file:///Z:/task_1552672237/build/tests/reftest/tests/dom/vr/test/reftest/change_size.html == file:///Z:/task_1552672237/build/tests/reftest/tests/dom/vr/test/reftest/wrapper.html?change_size.png
18:55:12 INFO - REFTEST INFO | RESTORE PREFERENCE pref(dom.vr.controller.enumerate.interval,1000)
18:55:12 INFO - REFTEST INFO | RESTORE PREFERENCE pref(dom.vr.display.enumerate.interval,5000)
18:55:12 INFO - REFTEST INFO | RESTORE PREFERENCE pref(dom.vr.display.rafMaxDuration,50)
18:55:12 INFO - REFTEST INFO | RESTORE PREFERENCE pref(dom.vr.puppet.submitframe,0)
18:55:12 INFO - REFTEST INFO | RESTORE PREFERENCE pref(dom.vr.require-gesture,true)
18:55:12 INFO - REFTEST INFO | RESTORE PREFERENCE pref(dom.vr.test.enabled,false)
18:55:12 INFO - REFTEST INFO | RESTORE PREFERENCE pref(dom.vr.puppet.enabled,false)
18:55:12 INFO - REFTEST INFO | SET PREFERENCE pref(dom.vr.puppet.enabled,true)
18:55:12 INFO - REFTEST INFO | SET PREFERENCE pref(dom.vr.test.enabled,true)
18:55:12 INFO - REFTEST INFO | SET PREFERENCE pref(dom.vr.require-gesture,false)
18:55:12 INFO - REFTEST INFO | SET PREFERENCE pref(dom.vr.puppet.submitframe,1)
18:55:12 INFO - REFTEST INFO | SET PREFERENCE pref(dom.vr.display.rafMaxDuration,200)
18:55:12 INFO - REFTEST INFO | SET PREFERENCE pref(dom.vr.display.enumerate.interval,0)
18:55:12 INFO - REFTEST INFO | SET PREFERENCE pref(dom.vr.controller.enumerate.interval,0)
18:55:12 INFO - REFTEST TEST-LOAD | file:///Z:/task_1552672237/build/tests/reftest/tests/dom/vr/test/reftest/change_size.html | 1 / 2 (50%)
18:55:12 INFO - ++DOMWINDOW == 10 (000001FCC0C58800) [pid = 3612] [serial = 10] [outer = 000001FCC09085C0]
18:55:12 INFO - [Child 3612, Main Thread] WARNING: NS_ENSURE_TRUE(nsContentUtils::IsJavascriptMIMEType(type)) failed: file z:/build/build/src/dom/script/ScriptLoader.cpp, line 1493
18:55:12 INFO - [Child 3612, Main Thread] WARNING: NS_ENSURE_TRUE(nsContentUtils::IsJavascriptMIMEType(type)) failed: file z:/build/build/src/dom/script/ScriptLoader.cpp, line 1493
18:55:12 INFO - Initializing context 000001FCC38B7000 surface 000001FCC3888880 on display 000001FCC0910180
18:55:12 INFO - --DOMWINDOW == 9 (000001FCB8A90800) [pid = 3612] [serial = 2] [outer = 0000000000000000] [url = about:blank]
18:55:13 INFO - REFTEST INFO | RESTORE PREFERENCE pref(dom.vr.controller.enumerate.interval,1000)
18:55:13 INFO - REFTEST INFO | RESTORE PREFERENCE pref(dom.vr.display.enumerate.interval,5000)
18:55:13 INFO - REFTEST INFO | RESTORE PREFERENCE pref(dom.vr.display.rafMaxDuration,50)
18:55:13 INFO - REFTEST INFO | RESTORE PREFERENCE pref(dom.vr.puppet.submitframe,0)
18:55:13 INFO - REFTEST INFO | RESTORE PREFERENCE pref(dom.vr.require-gesture,true)
18:55:13 INFO - REFTEST INFO | RESTORE PREFERENCE pref(dom.vr.test.enabled,false)
18:55:13 INFO - REFTEST INFO | RESTORE PREFERENCE pref(dom.vr.puppet.enabled,false)
18:55:13 INFO - REFTEST INFO | SET PREFERENCE pref(dom.vr.puppet.enabled,true)
18:55:13 INFO - REFTEST INFO | SET PREFERENCE pref(dom.vr.test.enabled,true)
18:55:13 INFO - REFTEST INFO | SET PREFERENCE pref(dom.vr.require-gesture,false)
18:55:13 INFO - REFTEST INFO | SET PREFERENCE pref(dom.vr.puppet.submitframe,1)
18:55:13 INFO - REFTEST INFO | SET PREFERENCE pref(dom.vr.display.rafMaxDuration,200)
18:55:13 INFO - REFTEST INFO | SET PREFERENCE pref(dom.vr.display.enumerate.interval,0)
18:55:13 INFO - REFTEST INFO | SET PREFERENCE pref(dom.vr.controller.enumerate.interval,0)
18:55:13 INFO - REFTEST TEST-LOAD | file:///Z:/task_1552672237/build/tests/reftest/tests/dom/vr/test/reftest/wrapper.html?change_size.png | 1 / 2 (50%)
18:55:13 INFO - ++DOMWINDOW == 10 (000001FCC0C56800) [pid = 3612] [serial = 11] [outer = 000001FCC09085C0]
18:55:13 INFO - REFTEST INFO | REFTEST fuzzy test (0, 0) <= (0, 0) <= (1, 600)
18:55:13 INFO - REFTEST TEST-PASS | file:///Z:/task_1552672237/build/tests/reftest/tests/dom/vr/test/reftest/change_size.html == file:///Z:/task_1552672237/build/tests/reftest/tests/dom/vr/test/reftest/wrapper.html?change_size.png | image comparison, max difference: 0, number of differing pixels: 0
18:55:13 INFO - REFTEST TEST-END | file:///Z:/task_1552672237/build/tests/reftest/tests/dom/vr/test/reftest/change_size.html == file:///Z:/task_1552672237/build/tests/reftest/tests/dom/vr/test/reftest/wrapper.html?change_size.png
18:55:13 INFO - ++DOMWINDOW == 11 (000001FCC389F400) [pid = 3612] [serial = 12] [outer = 000001FCC09085C0]
18:55:14 INFO - REFTEST INFO | RESTORE PREFERENCE pref(dom.vr.controller.enumerate.interval,1000)
18:55:14 INFO - REFTEST INFO | RESTORE PREFERENCE pref(dom.vr.display.enumerate.interval,5000)
18:55:14 INFO - REFTEST INFO | RESTORE PREFERENCE pref(dom.vr.display.rafMaxDuration,50)
18:55:14 INFO - REFTEST INFO | RESTORE PREFERENCE pref(dom.vr.puppet.submitframe,0)
18:55:14 INFO - REFTEST INFO | RESTORE PREFERENCE pref(dom.vr.require-gesture,true)
18:55:14 INFO - REFTEST INFO | RESTORE PREFERENCE pref(dom.vr.test.enabled,false)
18:55:14 INFO - REFTEST INFO | RESTORE PREFERENCE pref(dom.vr.puppet.enabled,false)
18:55:14 INFO - REFTEST INFO | Slowest test took 1103ms (file:///Z:/task_1552672237/build/tests/reftest/tests/dom/vr/test/reftest/draw_rect.html)
18:55:14 INFO - REFTEST INFO | Total canvas count = 0
18:55:14 INFO - [Parent 1528, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:14 INFO - [Child 2460, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:14 INFO - [Child 2460, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:14 INFO - JavaScript error: resource://reftest/reftest.jsm, line 1558: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPropertyBag2.getPropertyAsAString]
18:55:14 INFO - [Parent 1528, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:14 INFO - [Parent 1528, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:14 INFO - [Child 3612, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:14 INFO - [Child 3612, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/ch[GPU 9164, Chrome_ChildThreadromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:14 INFO - ] WARNING: pipe errorJavaScript error: 109: fil: resou
18:55:14 INFO - ###!!! [Parent][MessageChannel] Error: (msgtype=0x9C0017,name=PVRManager::Msg_UpdateDisplayInfo) Channel closing: too late to send/recv, messages will be lost
18:55:14 INFO - rce://reftest/refte z:/build/build/src/est.jsm, line 1558: NS_EiRROR_NOT_AVAILABLE: Component returnepc/chromium/src/chrome/cd failure code: 0x80040ommon/ipc_channe111 (NS_ERROR_NOT_AVAILABLE) [nsIPropertyBag2.getPropertyAsAString]
18:55:14 INFO - l[Parent 1528,_
18:55:14 INFO - ###!!! [Parent][MessageChannel] Error: (msgtype=0x9C0017,name=PVRManager::Msg_UpdateDisplayInfo) Channel closing: too late to send/recv, messages will be lost
18:55:14 INFO - Gecko_IOThread] WARNING: pipe errwin.cc, line 341
18:55:14 INFO - or: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:14 INFO - [GPU 9164, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:14 INFO - ###!!! [Parent][MessageChannel] Error: (msgtype=0x9C0017,name=PVRManager::Msg_UpdateDisplayInfo) Channel closing: too late to send/recv, messages will be lost
18:55:14 INFO - ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
18:55:14 INFO - [GPU 9164, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:14 INFO - [GPU 9164, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:14 INFO - [GPU 9164, Chrome_ChildThread] WARNING: pipe error: 232: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 502
18:55:14 INFO - [GPU 9164, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:14 INFO - [GPU 9164, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:14 INFO - [Parent 1528, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:14 INFO - ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
18:55:14 INFO - [GPU 9164, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:14 INFO - [Parent 1528, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:14 INFO - [Parent 1528, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:14 INFO - [Parent 1528, Main Thread] WARNING: NS_ENSURE_TRUE(service) failed: file z:/build/build/src/netwerk/cache2/CacheObserver.cpp, line 336
18:55:14 INFO - [Parent 1528, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file z:/build/build/src/netwerk/cache2/CacheObserver.cpp, line 358
18:55:14 INFO - [Parent 1528, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file z:/build/build/src/netwerk/cache2/CacheObserver.cpp, line 471
18:55:15 INFO - --DOMWINDOW == 14 (00000172E0687800) [pid = 2460] [serial = 2] [outer = 0000000000000000] [url = about:blank]
18:55:15 INFO - --DOMWINDOW == 13 (00000172E8570400) [pid = 2460] [serial = 4] [outer = 0000000000000000] [url = about:blank]
18:55:15 INFO - --DOMWINDOW == 12 (00000172E8572400) [pid = 2460] [serial = 6] [outer = 0000000000000000] [url = about:blank]
18:55:15 INFO - --DOMWINDOW == 11 (00000172E8573C00) [pid = 2460] [serial = 8] [outer = 0000000000000000] [url = about:blank]
18:55:15 INFO - --DOMWINDOW == 10 (00000172E8575400) [pid = 2460] [serial = 10] [outer = 0000000000000000] [url = about:blank]
18:55:15 INFO - --DOMWINDOW == 9 (00000172E8515100) [pid = 2460] [serial = 9] [outer = 0000000000000000] [url = moz-extension://49455f72-d805-4b48-8fd7-069af3793d2f/_generated_background_page.html]
18:55:15 INFO - --DOMWINDOW == 8 (00000172E8514D40) [pid = 2460] [serial = 7] [outer = 0000000000000000] [url = moz-extension://825cedde-7dc1-46f6-9f5a-5f02733befe2/_generated_background_page.html]
18:55:15 INFO - --DOMWINDOW == 7 (00000172E8514980) [pid = 2460] [serial = 5] [outer = 0000000000000000] [url = moz-extension://6b034895-4566-4946-91f1-f4be826620c3/_generated_background_page.html]
18:55:15 INFO - --DOMWINDOW == 6 (00000172E85145C0) [pid = 2460] [serial = 3] [outer = 0000000000000000] [url = moz-extension://01ecc951-0d6f-4e9e-b9fc-5fa131c5c053/_generated_background_page.html]
18:55:15 INFO - --DOMWINDOW == 5 (00000172E8514020) [pid = 2460] [serial = 1] [outer = 0000000000000000] [url = moz-extension://f02ba0d1-4770-4a28-9822-52ad51c4ff74/_generated_background_page.html]
18:55:15 INFO - --DOCSHELL 00000172E8583000 == 4 [pid = 2460] [id = {9c90ac33-773d-4bf6-a7bf-33a08af076c1}] [url = moz-extension://01ecc951-0d6f-4e9e-b9fc-5fa131c5c053/_generated_background_page.html]
18:55:15 INFO - --DOCSHELL 00000172E8585800 == 3 [pid = 2460] [id = {0c71fa5f-37ce-4436-88aa-ef9ea73abb2a}] [url = moz-extension://6b034895-4566-4946-91f1-f4be826620c3/_generated_background_page.html]
18:55:15 INFO - --DOCSHELL 00000172DC576800 == 2 [pid = 2460] [id = {b89dcc0d-9082-4678-8e88-dee27e55eec7}] [url = moz-extension://f02ba0d1-4770-4a28-9822-52ad51c4ff74/_generated_background_page.html]
18:55:15 INFO - --DOCSHELL 00000172E8588000 == 1 [pid = 2460] [id = {4109ab34-18a0-4e22-9601-769a2718d720}] [url = moz-extension://825cedde-7dc1-46f6-9f5a-5f02733befe2/_generated_background_page.html]
18:55:15 INFO - --DOCSHELL 00000172E858B000 == 0 [pid = 2460] [id = {2380bb70-22e3-4d65-b1e3-719aab7c3102}] [url = moz-extension://49455f72-d805-4b48-8fd7-069af3793d2f/_generated_background_page.html]
18:55:15 INFO - --DOMWINDOW == 4 (00000172E8843C00) [pid = 2460] [serial = 15] [outer = 0000000000000000] [url = moz-extension://49455f72-d805-4b48-8fd7-069af3793d2f/_generated_background_page.html]
18:55:15 INFO - --DOMWINDOW == 3 (00000172E8841C00) [pid = 2460] [serial = 14] [outer = 0000000000000000] [url = moz-extension://825cedde-7dc1-46f6-9f5a-5f02733befe2/_generated_background_page.html]
18:55:15 INFO - --DOMWINDOW == 2 (00000172E883D400) [pid = 2460] [serial = 11] [outer = 0000000000000000] [url = moz-extension://f02ba0d1-4770-4a28-9822-52ad51c4ff74/_generated_background_page.html]
18:55:15 INFO - --DOMWINDOW == 1 (00000172E8840800) [pid = 2460] [serial = 13] [outer = 0000000000000000] [url = moz-extension://6b034895-4566-4946-91f1-f4be826620c3/_generated_background_page.html]
18:55:15 INFO - --DOMWINDOW == 0 (00000172E883F400) [pid = 2460] [serial = 12] [outer = 0000000000000000] [url = moz-extension://01ecc951-0d6f-4e9e-b9fc-5fa131c5c053/_generated_background_page.html]
18:55:15 INFO - --DOCSHELL 000001FCB4977000 == 1 [pid = 3612] [id = {a503008b-7ef3-48a2-adf9-c8c2cee3d5c8}] [url = chrome://gfxsanity/content/sanitytest.html]
18:55:15 INFO - --DOMWINDOW == 10 (000001FCC0997400) [pid = 3612] [serial = 3] [outer = 0000000000000000] [url = chrome://gfxsanity/content/sanitytest.html]
18:55:15 INFO - --DOMWINDOW == 9 (000001FCC0908020) [pid = 3612] [serial = 1] [outer = 0000000000000000] [url = chrome://gfxsanity/content/sanitytest.html]
18:55:15 INFO - --DOCSHELL 000001FCB496B000 == 0 [pid = 3612] [id = {566773fc-48c6-483e-9120-3659cf09e3af}] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
18:55:15 INFO - --DOMWINDOW == 8 (000001FCC09085C0) [pid = 3612] [serial = 4] [outer = 0000000000000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
18:55:15 INFO - --DOMWINDOW == 7 (000001FCC1366C00) [pid = 3612] [serial = 9] [outer = 0000000000000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
18:55:15 INFO - --DOMWINDOW == 6 (000001FCC0991800) [pid = 3612] [serial = 5] [outer = 0000000000000000] [url = about:blank]
18:55:15 INFO - --DOMWINDOW == 5 (000001FCC389F400) [pid = 3612] [serial = 12] [outer = 0000000000000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
18:55:15 INFO - --DOMWINDOW == 4 (000001FCC0997C00) [pid = 3612] [serial = 6] [outer = 0000000000000000] [url = about:blank]
18:55:15 INFO - --DOMWINDOW == 3 (000001FCC0C56800) [pid = 3612] [serial = 11] [outer = 0000000000000000] [url = file:///Z:/task_1552672237/build/tests/reftest/tests/dom/vr/test/reftest/wrapper.html?change_size.png]
18:55:15 INFO - Destroying context 000001FCC38B7000 surface 000001FCC3888880 on display 000001FCC0910180
18:55:15 INFO - --DOMWINDOW == 2 (000001FCC0C54800) [pid = 3612] [serial = 8] [outer = 0000000000000000] [url = file:///Z:/task_1552672237/build/tests/reftest/tests/dom/vr/test/reftest/wrapper.html?draw_rect.png]
18:55:15 INFO - Destroying context 000001FCC138B000 surface 000001FCC0CC9AC0 on display 000001FCC0910180
18:55:15 INFO - --DOMWINDOW == 1 (000001FCC0C54400) [pid = 3612] [serial = 7] [outer = 0000000000000000] [url = file:///Z:/task_1552672237/build/tests/reftest/tests/dom/vr/test/reftest/draw_rect.html]
18:55:15 INFO - --DOMWINDOW == 0 (000001FCC0C58800) [pid = 3612] [serial = 10] [outer = 0000000000000000] [url = file:///Z:/task_1552672237/build/tests/reftest/tests/dom/vr/test/reftest/change_size.html]
18:55:15 INFO - nsStringStats
18:55:15 INFO - => mAllocCount: 14290
18:55:15 INFO - => mReallocCount: 0
18:55:15 INFO - => mFreeCount: 14290
18:55:15 INFO - => mShareCount: 9175
18:55:15 INFO - => mAdoptCount: 480
18:55:15 INFO - => mAdoptFreeCount: 512
18:55:15 INFO - => Process ID: 2460, Thread ID: 1352
18:55:15 INFO - [Parent 1528, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:15 INFO - [Child 9312, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:15 INFO - [Child 9312, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:15 INFO - JavaScript error: resource://reftest/reftest.jsm, line 1558: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPropertyBag2.getPropertyAsAString]
18:55:15 INFO - [Parent 1528, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:15 INFO - [GPU 9164, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:15 INFO - ###!!! [Parent][MessageChannel] Error: (msgtype=0x9C0017,name=PVRManager::Msg_UpdateDisplayInfo) Channel closing: too late to send/recv, messages will be lost
18:55:15 INFO - [GPU 9164, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:15 INFO - ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
18:55:15 INFO - [GPU 9164, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:15 INFO - [Parent 1528, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:15 INFO - [Parent 1528, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:16 INFO - nsStringStats
18:55:16 INFO - => mAllocCount: 6327
18:55:16 INFO - => mReallocCount: 0
18:55:16 INFO - => mFreeCount: 6327
18:55:16 INFO - => mShareCount: 4560
18:55:16 INFO - => mAdoptCount: 62
18:55:16 INFO - => mAdoptFreeCount: 62
18:55:16 INFO - => Process ID: 9312, Thread ID: 12036
18:55:16 INFO - nsStringStats
18:55:16 INFO - => mAllocCount: 10437
18:55:16 INFO - => mReallocCount: 0
18:55:16 INFO - => mFreeCount: 10437
18:55:16 INFO - => mShareCount: 8873
18:55:16 INFO - => mAdoptCount: 699
18:55:16 INFO - => mAdoptFreeCount: 715
18:55:16 INFO - => Process ID: 3612, Thread ID: 8232
18:55:16 INFO - [Parent 1528, StreamTrans #36] WARNING: 'NS_FAILED(rv)', file z:/build/build/src/modules/libjar/nsJARChannel.cpp, line 371
18:55:16 INFO - [Parent 1528, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file z:/build/build/src/modules/libjar/nsJARChannel.cpp, line 994
18:55:16 INFO - JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 674: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "JSON store: writing data"
18:55:17 INFO - 1552676117108 Marionette TRACE Received observer notification xpcom-will-shutdown
18:55:17 INFO - 1552676117110 Marionette INFO Stopped listening on port 2828
18:55:17 INFO - 1552676117111 Marionette DEBUG Remote service is inactive
18:55:17 INFO - [VR 3716, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:17 INFO - [GPU 9164, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:17 INFO - [Parent 1528, Main Thread] WARNING: NS_ENSURE_TRUE(mDB) failed: file zAssertion failure: int32_t:/build/build/src/netwerk/cache/nsD
18:55:17 INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x9A0002,name=PVRGPU::Msg_StopVRService) Closed channel: cannot send/recv
18:55:17 INFO - iskCacheDe(mRefCnt) > 0 (dup release), at z:/viceSQL.cpp, lbuild/builine 1285
18:55:17 INFO - d/src/gfx/vr/VRDisplayHost.h:35
18:55:17 INFO - [GPU 9164
18:55:17 INFO - ###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
18:55:17 INFO - , Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:17 INFO - [GPU 9164, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:17 INFO - [Parent 1528, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:17 INFO - nsStringStats
18:55:17 INFO - => mAllocCount: 526
18:55:17 INFO - => mReallocCount: 0
18:55:17 INFO - => mFreeCount: 526
18:55:17 INFO - => mShareCount: 275
18:55:17 INFO - => mAdoptCount: 0
18:55:17 INFO - => mAdoptFreeCount: 0
18:55:17 INFO - => Process ID: 3716, Thread ID: 6280
18:55:17 INFO - [GPU 9164, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
18:55:17 INFO - #01: mozilla::gfx::VRSystemManager::NotifyVSync() [gfx/vr/gfxVR.cpp:51]
18:55:17 INFO -
18:55:17 INFO - #02: mozilla::gfx::VRSystemManagerPuppet::NotifyVSync() [gfx/vr/gfxVRPuppet.cpp:671]
18:55:17 INFO -
18:55:17 INFO - #03: mozilla::gfx::VRManager::NotifyVsync(mozilla::TimeStamp const &) [gfx/vr/VRManager.cpp:207]
18:55:17 INFO -
18:55:17 INFO - #04: mozilla::gfx::VRSystemManagerPuppet::Run10msTasks() [gfx/vr/gfxVRPuppet.cpp:665]
18:55:17 INFO -
18:55:17 INFO - #05: mozilla::gfx::VRManager::Run10msTasks() [gfx/vr/VRManager.cpp:334]
18:55:17 INFO -
18:55:17 INFO - #06: mozilla::gfx::VRManager::RunTasks() [gfx/vr/VRManager.cpp:277]
18:55:17 INFO -
18:55:17 INFO - #07: nsTimerImpl::Fire(int) [xpcom/threads/nsTimerImpl.cpp:559]
18:55:17 INFO -
18:55:17 INFO - #08: nsTimerEvent::Run() [xpcom/threads/TimerThread.cpp:260]
18:55:17 INFO -
18:55:17 INFO - #09: MessageLoop::RunTask(already_AddRefed<nsIRunnable>) [ipc/chromium/src/base/message_loop.cc:443]
18:55:17 INFO -
18:55:17 INFO - #10: MessageLoop::DeferOrRunPendingTask(MessageLoop::PendingTask &&) [ipc/chromium/src/base/message_loop.cc:450]
18:55:17 INFO -
18:55:17 INFO - #11: MessageLoop::DoWork() [ipc/chromium/src/base/message_loop.cc:525]
18:55:17 INFO -
18:55:17 INFO - #12: base::MessagePumpForUI::DoRunLoop() [ipc/chromium/src/base/message_pump_win.cc:204]
18:55:17 INFO -
18:55:17 INFO - #13: base::MessagePumpWin::Run(base::MessagePump::Delegate *) [ipc/chromium/src/base/message_pump_win.h:79]
18:55:17 INFO -
18:55:17 INFO - #14: MessageLoop::RunInternal() [ipc/chromium/src/base/message_loop.cc:315]
18:55:17 INFO -
18:55:17 INFO -
18:55:17 INFO - #15: MessageLoop::RunHandler() [ipc/chromium/src/base/message_loop.cc:309]
18:55:17 INFO -
18:55:17 INFO - #16: base::Thread::ThreadMain() [ipc/chromium/src/base/thread.cc:192]
18:55:17 INFO -
18:55:17 INFO - #17: static unsigned long `anonymous namespace'::ThreadFunc(void *) [ipc/chromium/src/base/platform_thread_win.cc:20]
18:55:17 INFO -
18:55:17 INFO - #18: KERNEL32.DLL + 0x13034
18:55:17 INFO -
18:55:17 INFO - #19: static void patched_BaseThreadInitThunk(int, void *, void *) [mozglue/build/WindowsDllBlocklist.cpp:713]
18:55:17 INFO -
18:55:17 INFO - #20: ntdll.dll + 0x71461
18:55:17 INFO -
18:55:17 INFO - ###!!! [Child][MessageChannel::SendAndWait] Error: Channel error: cannot send/recv
18:55:18 INFO - --DOMWINDOW == 15 (00000157C47D0400) [pid = 1528] [serial = 11] [outer = 0000000000000000] [url = about:blank]
18:55:18 INFO - --DOMWINDOW == 14 (00000157C2CAA000) [pid = 1528] [serial = 7] [outer = 0000000000000000] [url = about:blank]
18:55:20 INFO - --DOCSHELL 00000157C2B89000 == 6 [pid = 1528] [id = {89571092-4d3a-47ec-9ac3-4bc736706fb0}] [url = about:blank]
18:55:20 INFO - --DOCSHELL 00000157BED95800 == 5 [pid = 1528] [id = {2fb6d15f-500d-4b86-baf8-4189d44c9c5c}] [url = chrome://gfxsanity/content/sanityparent.html]
18:55:20 INFO - --DOMWINDOW == 13 (00000157B45CD800) [pid = 1528] [serial = 4] [outer = 0000000000000000] [url = about:blank]
18:55:20 INFO - --DOMWINDOW == 12 (00000157BED2FD40) [pid = 1528] [serial = 5] [outer = 0000000000000000] [url = about:blank]
18:55:20 INFO - --DOMWINDOW == 11 (00000157BED2F200) [pid = 1528] [serial = 1] [outer = 0000000000000000] [url = chrome://gfxsanity/content/sanityparent.html]
18:55:20 INFO - --DOMWINDOW == 10 (00000157C421C000) [pid = 1528] [serial = 9] [outer = 0000000000000000] [url = about:blank]
18:55:20 INFO - --DOMWINDOW == 9 (00000157BED2F980) [pid = 1528] [serial = 3] [outer = 0000000000000000] [url = chrome://browser/content/browser.xul]
18:55:20 INFO - --DOMWINDOW == 8 (00000157BEAC0400) [pid = 1528] [serial = 2] [outer = 0000000000000000] [url = about:blank]
18:55:20 INFO - --DOCSHELL 00000157C45AB800 == 4 [pid = 1528] [id = {cb08a591-9ecd-4048-9bae-c0244b9d517d}] [url = resource://gre-resources/hiddenWindow.html]
18:55:20 INFO - --DOCSHELL 00000157C1B3E800 == 3 [pid = 1528] [id = {998ccab1-c078-4ae6-bc9e-bed984823252}] [url = chrome://extensions/content/dummy.xul]
18:55:20 INFO - --DOCSHELL 00000157C6631000 == 2 [pid = 1528] [id = {147b08de-1fce-4e59-b02b-8b2691a14549}] [url = about:blank]
18:55:20 INFO - --DOCSHELL 00000157C6635000 == 1 [pid = 1528] [id = {a69038fc-efcb-4151-925e-60b5c9534984}] [url = chrome://reftest/content/reftest.xul]
18:55:20 INFO - --DOCSHELL 00000157BFA85800 == 0 [pid = 1528] [id = {ed6e5f2c-2c57-4a26-b2f8-ecc400e763b5}] [url = chrome://browser/content/browser.xul]
18:55:20 INFO - --DOMWINDOW == 7 (00000157C4053200) [pid = 1528] [serial = 10] [outer = 0000000000000000] [url = resource://gre-resources/hiddenWindow.html]
18:55:20 INFO - --DOMWINDOW == 6 (00000157C6833400) [pid = 1528] [serial = 16] [outer = 0000000000000000] [url = about:blank]
18:55:20 INFO - --DOMWINDOW == 5 (00000157C40ACB60) [pid = 1528] [serial = 13] [outer = 0000000000000000] [url = about:blank]
18:55:20 INFO - --DOMWINDOW == 4 (00000157C3336C00) [pid = 1528] [serial = 14] [outer = 0000000000000000] [url = about:blank]
18:55:20 INFO - --DOMWINDOW == 3 (00000157C33C5E20) [pid = 1528] [serial = 15] [outer = 0000000000000000] [url = chrome://reftest/content/reftest.xul]
18:55:20 INFO - --DOMWINDOW == 2 (00000157C3332C00) [pid = 1528] [serial = 8] [outer = 0000000000000000] [url = chrome://extensions/content/dummy.xul]
18:55:20 INFO - --DOMWINDOW == 1 (00000157C1FA7E20) [pid = 1528] [serial = 6] [outer = 0000000000000000] [url = chrome://extensions/content/dummy.xul]
18:55:20 INFO - --DOMWINDOW == 0 (00000157C332C800) [pid = 1528] [serial = 12] [outer = 0000000000000000] [url = resource://gre-resources/hiddenWindow.html]
18:55:21 INFO - [Parent 1528, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file z:/build/build/src/xpcom/base/nsCycleCollector.cpp, line 3355
18:55:23 INFO - nsStringStats
18:55:23 INFO - => mAllocCount: 71848
18:55:23 INFO - => mReallocCount: 0
18:55:23 INFO - => mFreeCount: 71848
18:55:23 INFO - => mShareCount: 76219
18:55:23 INFO - => mAdoptCount: 2274
18:55:23 INFO - => mAdoptFreeCount: 2346
18:55:23 INFO - => Process ID: 1528, Thread ID: 11348
18:55:28 INFO - REFTEST INFO | Copy/paste: Z:\task_1552672237\build\win32-minidump_stackwalk.exe c:\users\task_1552672237\appdata\local\temp\tmpbdjpsw.mozrunner\minidumps\2efca55d-46bf-48f5-8565-7ced6764dbb5.dmp Z:\task_1552672237\build\symbols
18:55:41 INFO - REFTEST INFO | Saved minidump as Z:\task_1552672237\build\blobber_upload_dir\2efca55d-46bf-48f5-8565-7ced6764dbb5.dmp
18:55:41 INFO - REFTEST INFO | Saved app info as Z:\task_1552672237\build\blobber_upload_dir\2efca55d-46bf-48f5-8565-7ced6764dbb5.extra
18:55:41 INFO - REFTEST PROCESS-CRASH | Last test finished | application crashed [@ mozilla::gfx::VRDisplayHost::Release()]
18:55:41 INFO - Crash dump filename: c:\users\task_1552672237\appdata\local\temp\tmpbdjpsw.mozrunner\minidumps\2efca55d-46bf-48f5-8565-7ced6764dbb5.dmp
18:55:41 INFO - Operating system: Windows NT
18:55:41 INFO - 10.0.17134
18:55:41 INFO - CPU: amd64
18:55:41 INFO - family 6 model 79 stepping 1
18:55:41 INFO - 16 CPUs
18:55:41 INFO -
18:55:41 INFO - GPU: UNKNOWN
18:55:41 INFO -
18:55:41 INFO - Crash reason: EXCEPTION_BREAKPOINT
18:55:41 INFO - Crash address: 0x7ff977271f71
18:55:41 INFO - Assertion: Unknown assertion type 0x00000000
18:55:41 INFO - Process uptime: 22 seconds
18:55:41 INFO -
18:55:41 INFO - Thread 2 (crashed)
18:55:41 INFO - 0 xul.dll!mozilla::gfx::VRDisplayHost::Release() [VRDisplayHost.h:8ae5bb51b14199227e03a37b0bdd3b35f1f4d01e : 35 + 0x0]
18:55:41 INFO - rax = 0x00007ff9bffb2ef0 rdx = 0x00007ff9cf5fa640
18:55:41 INFO - rcx = 0x00007ff98308c8b2 rbx = 0x0000023a7d7e8aa0
18:55:41 INFO - rsi = 0x0000000000000000 rdi = 0x0000000000000008
18:55:41 INFO - rbp = 0x0000000000000001 rsp = 0x000000d0d89ff390
18:55:41 INFO - r8 = 0x000000d0d89f9488 r9 = 0x000000d0d89fab10
18:55:41 INFO - r10 = 0x0000000000000000 r11 = 0x000000d0d89fa9b0
18:55:41 INFO - r12 = 0x00007ff989b7ca38 r13 = 0x00007ff9893cbba8
18:55:41 INFO - r14 = 0x000000d0d89ff470 r15 = 0x00007ff989b7ca30
18:55:41 INFO - rip = 0x00007ff977271f71
18:55:41 INFO - Found by: given as instruction pointer in context
18:55:41 INFO - 1 xul.dll!nsTArray_Impl<RefPtr<mozilla::gfx::VRDisplayHost>,nsTArrayInfallibleAllocator>::ClearAndRetainStorage() [nsTArray.h:8ae5bb51b14199227e03a37b0bdd3b35f1f4d01e : 1296 + 0xed]
18:55:41 INFO - rbx = 0x0000023a7d7e8aa0 rbp = 0x0000000000000001
18:55:41 INFO - rsp = 0x000000d0d89ff3d0 r12 = 0x00007ff989b7ca38
18:55:41 INFO - r13 = 0x00007ff9893cbba8 r14 = 0x000000d0d89ff470
18:55:41 INFO - r15 = 0x00007ff989b7ca30 rip = 0x00007ff9772aa396
18:55:41 INFO - Found by: call frame info
18:55:41 INFO - 2 xul.dll!mozilla::gfx::VRSystemManager::NotifyVSync() [gfxVR.cpp:8ae5bb51b14199227e03a37b0bdd3b35f1f4d01e : 51 + 0x5c]
18:55:41 INFO - rbx = 0x0000023a7d7e8aa0 rbp = 0x0000000000000001
18:55:41 INFO - rsp = 0x000000d0d89ff440 r12 = 0x00007ff989b7ca38
18:55:41 INFO - r13 = 0x00007ff9893cbba8 r14 = 0x000000d0d89ff470
18:55:41 INFO - r15 = 0x00007ff989b7ca30 rip = 0x00007ff9772926f8
18:55:41 INFO - Found by: call frame info
18:55:41 INFO - 3 xul.dll!mozilla::gfx::VRSystemManagerPuppet::NotifyVSync() [gfxVRPuppet.cpp:8ae5bb51b14199227e03a37b0bdd3b35f1f4d01e : 669 + 0xd]
18:55:41 INFO - rbx = 0x0000023a7d7e8aa0 rbp = 0x0000000000000001
18:55:41 INFO - rsp = 0x000000d0d89ff4c0 r12 = 0x00007ff989b7ca38
18:55:41 INFO - r13 = 0x00007ff9893cbba8 r14 = 0x000000d0d89ff470
18:55:41 INFO - r15 = 0x00007ff989b7ca30 rip = 0x00007ff97727f49c
18:55:41 INFO - Found by: call frame info
18:55:41 INFO - 4 xul.dll!mozilla::gfx::VRManager::NotifyVsync(mozilla::TimeStamp const &) [VRManager.cpp:8ae5bb51b14199227e03a37b0bdd3b35f1f4d01e : 208 + 0x11]
18:55:41 INFO - rbx = 0x0000023a7d7e8aa0 rbp = 0x0000000000000001
18:55:41 INFO - rsp = 0x000000d0d89ff530 r12 = 0x00007ff989b7ca38
18:55:41 INFO - r13 = 0x00007ff9893cbba8 r14 = 0x000000d0d89ff470
18:55:41 INFO - r15 = 0x00007ff989b7ca30 rip = 0x00007ff97728b461
18:55:41 INFO - Found by: call frame info
18:55:41 INFO - 5 xul.dll!mozilla::gfx::VRSystemManagerPuppet::Run10msTasks() [gfxVRPuppet.cpp:8ae5bb51b14199227e03a37b0bdd3b35f1f4d01e : 665 + 0xb]
18:55:41 INFO - rbx = 0x0000023a7d7e8aa0 rbp = 0x0000000000000001
18:55:41 INFO - rsp = 0x000000d0d89ff5a0 r12 = 0x00007ff989b7ca38
18:55:41 INFO - r13 = 0x00007ff9893cbba8 r14 = 0x000000d0d89ff470
18:55:41 INFO - r15 = 0x00007ff989b7ca30 rip = 0x00007ff97727f41a
18:55:41 INFO - Found by: call frame info
18:55:41 INFO - 6 xul.dll!mozilla::gfx::VRManager::Run10msTasks() [VRManager.cpp:8ae5bb51b14199227e03a37b0bdd3b35f1f4d01e : 335 + 0x11]
18:55:41 INFO - rbx = 0x0000023a7d7e8aa0 rbp = 0x0000000000000001
18:55:41 INFO - rsp = 0x000000d0d89ff600 r12 = 0x00007ff989b7ca38
18:55:41 INFO - r13 = 0x00007ff9893cbba8 r14 = 0x000000d0d89ff470
18:55:41 INFO - r15 = 0x00007ff989b7ca30 rip = 0x00007ff97728c0a1
18:55:41 INFO - Found by: call frame info
18:55:41 INFO - 7 xul.dll!mozilla::gfx::VRManager::RunTasks() [VRManager.cpp:8ae5bb51b14199227e03a37b0bdd3b35f1f4d01e : 274 + 0x10]
18:55:41 INFO - rbx = 0x0000023a7d7e8aa0 rbp = 0x0000000000000001
18:55:41 INFO - rsp = 0x000000d0d89ff690 r12 = 0x00007ff989b7ca38
18:55:41 INFO - r13 = 0x00007ff9893cbba8 r14 = 0x000000d0d89ff470
18:55:41 INFO - r15 = 0x00007ff989b7ca30 rip = 0x00007ff97728bb9e
18:55:41 INFO - Found by: call frame info

Moving these bugs (intermittent test failures with crashes) out of P5.

Priority: P5 → --

I will be removing the affected code in Bug 1473402

Depends on: 1473402
Priority: -- → P2

I have removed the affect code in Bug 1466702, which re-implements most of VRManager and related classes.

Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → FIXED
Assignee: nobody → kgilbert
Depends on: 1466702
No longer depends on: 1473402
Target Milestone: --- → mozilla69
You need to log in before you can comment on or make changes to this bug.