1539119 - Intermittent Last test finished | application crashed [@ mozilla::gfx::VRDisplayHost::~VRDisplayHost()]

Status: RESOLVED FIXED

(Core :: WebVR, defect, P2)

Description

[Treeherder Bug Filer]

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

https://treeherder.mozilla.org/logviewer.html#?job_id=236092057&repo=autoland

https://queue.taskcluster.net/v1/task/XUKJsvO8TH6fSKSUQ3neNQ/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/XUKJsvO8TH6fSKSUQ3neNQ/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

11:28:37 INFO - REFTEST TEST-LOAD | file:///Z:/task_1553596087/build/tests/reftest/tests/dom/vr/test/reftest/wrapper.html?change_size.png | 1 / 2 (50%)
11:28:37 INFO - ++DOMWINDOW == 11 (000001F3EA9E2400) [pid = 4232] [serial = 11] [outer = 000001F3EA5E97A0]
11:28:37 INFO - REFTEST INFO | REFTEST fuzzy test (0, 0) <= (0, 0) <= (1, 600)
11:28:37 INFO - REFTEST TEST-PASS | file:///Z:/task_1553596087/build/tests/reftest/tests/dom/vr/test/reftest/change_size.html == file:///Z:/task_1553596087/build/tests/reftest/tests/dom/vr/test/reftest/wrapper.html?change_size.png | image comparison, max difference: 0, number of differing pixels: 0
11:28:37 INFO - REFTEST TEST-END | file:///Z:/task_1553596087/build/tests/reftest/tests/dom/vr/test/reftest/change_size.html == file:///Z:/task_1553596087/build/tests/reftest/tests/dom/vr/test/reftest/wrapper.html?change_size.png
11:28:37 INFO - ++DOMWINDOW == 12 (000001F3EA9E4400) [pid = 4232] [serial = 12] [outer = 000001F3EA5E97A0]
11:28:37 INFO - REFTEST INFO | RESTORE PREFERENCE pref(dom.vr.controller.enumerate.interval,1000)
11:28:37 INFO - REFTEST INFO | RESTORE PREFERENCE pref(dom.vr.display.enumerate.interval,5000)
11:28:37 INFO - REFTEST INFO | RESTORE PREFERENCE pref(dom.vr.display.rafMaxDuration,50)
11:28:37 INFO - REFTEST INFO | RESTORE PREFERENCE pref(dom.vr.puppet.submitframe,0)
11:28:37 INFO - REFTEST INFO | RESTORE PREFERENCE pref(dom.vr.require-gesture,true)
11:28:37 INFO - REFTEST INFO | RESTORE PREFERENCE pref(dom.vr.test.enabled,false)
11:28:37 INFO - REFTEST INFO | RESTORE PREFERENCE pref(dom.vr.puppet.enabled,false)
11:28:37 INFO - REFTEST INFO | Slowest test took 1011ms (file:///Z:/task_1553596087/build/tests/reftest/tests/dom/vr/test/reftest/draw_rect.html)
11:28:37 INFO - REFTEST INFO | Total canvas count = 0
11:28:37 INFO - [GPU 7848, Compositor] WARNING: Possibly dropping task posted to updater thread: file z:/build/build/src/gfx/layers/apz/src/APZUpdater.cpp, line 422
11:28:37 INFO - [GPU 7848, Compositor] WARNING: Possibly dropping task posted to updater thread: file z:/build/build/src/gfx/layers/apz/src/APZUpdater.cpp, line 422
11:28:37 INFO - [Parent 7208, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
11:28:37 INFO - --DOMWINDOW == 11 (000001F3EC737400) [pid = 4232] [serial = 5] [outer = 0000000000000000] [url = about:blank]
11:28:37 INFO - --DOMWINDOW == 10 (000001F3EA56E400) [pid = 4232] [serial = 2] [outer = 0000000000000000] [url = about:blank]
11:28:37 INFO - [Child 2064, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
11:28:37 INFO - [Child 2064, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/sJavaScript errc/chrome/common/ipc_channel_ror: resource://reftest/reftest.jsm, line 1558: NS_ERROR_NOT_AVAILAwin.cc, BLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPropertyBag2.getPropertyAsAString]
11:28:37 INFO - line 341
11:28:37 INFO - [Parent 7208, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
...
11:28:39 INFO - [Parent 7208, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file z:/build/build/src/xpcom/base/nsCycleCollector.cpp, line 3355
11:28:39 INFO - nsStringStats
11:28:39 INFO - => mAllocCount: 71001
11:28:39 INFO - => mReallocCount: 0
11:28:39 INFO - => mFreeCount: 71001
11:28:39 INFO - => mShareCount: 73753
11:28:39 INFO - => mAdoptCount: 2046
11:28:39 INFO - => mAdoptFreeCount: 2120
11:28:39 INFO - => Process ID: 7208, Thread ID: 10756
11:28:39 INFO - REFTEST INFO | Copy/paste: Z:\task_1553596087\build\win32-minidump_stackwalk.exe c:\users\task_1553596087\appdata\local\temp\tmpzu_4uk.mozrunner\minidumps\cefcc923-28fc-4c09-b91b-ae264d3c3d19.dmp Z:\task_1553596087\build\symbols
11:28:50 INFO - REFTEST INFO | Saved minidump as Z:\task_1553596087\build\blobber_upload_dir\cefcc923-28fc-4c09-b91b-ae264d3c3d19.dmp
11:28:50 INFO - REFTEST INFO | Saved app info as Z:\task_1553596087\build\blobber_upload_dir\cefcc923-28fc-4c09-b91b-ae264d3c3d19.extra
11:28:50 INFO - REFTEST PROCESS-CRASH | Last test finished | application crashed [@ mozilla::gfx::VRDisplayHost::~VRDisplayHost()]
11:28:50 INFO - Crash dump filename: c:\users\task_1553596087\appdata\local\temp\tmpzu_4uk.mozrunner\minidumps\cefcc923-28fc-4c09-b91b-ae264d3c3d19.dmp
11:28:50 INFO - Operating system: Windows NT
11:28:50 INFO - 10.0.17134
11:28:50 INFO - CPU: amd64
11:28:50 INFO - family 6 model 79 stepping 1
11:28:50 INFO - 16 CPUs
11:28:50 INFO -
11:28:50 INFO - GPU: UNKNOWN
11:28:50 INFO -
11:28:50 INFO - Crash reason: EXCEPTION_ILLEGAL_INSTRUCTION
11:28:50 INFO - Crash address: 0x7ffbdcca7af0
11:28:50 INFO - Assertion: Unknown assertion type 0x00000000
11:28:50 INFO - Process uptime: 9 seconds
11:28:50 INFO -
11:28:50 INFO - Thread 2 (crashed)
11:28:50 INFO - 0 xul.dll!void mozilla::gfx::VRDisplayHost::~VRDisplayHost() [VRDisplayHost.cpp:e75b6b732e7c02670ec3cb2b18a4679216baeec8 : 89 + 0x0]
11:28:50 INFO - rax = 0x00007ffbe2f25050 rdx = 0x0000000000000001
11:28:50 INFO - rcx = 0x000001caf6b91000 rbx = 0x0000000000000008
11:28:50 INFO - rsi = 0x000001caf6b91000 rdi = 0x0000000000000000
11:28:50 INFO - rbp = 0x0000000000000001 rsp = 0x0000004b463ff4f8
11:28:50 INFO - r8 = 0x00007ffbe2f25010 r9 = 0x78ff73726e477860
11:28:50 INFO - r10 = 0xfefefefefefefeff r11 = 0x8080808080808080
11:28:50 INFO - r12 = 0x000001cae678e270 r13 = 0x000001cae677e2e0
11:28:50 INFO - r14 = 0x0000004b463ff580 r15 = 0x00007ffbe2f250c1
11:28:50 INFO - rip = 0x00007ffbdcca7af0
11:28:50 INFO - Found by: given as instruction pointer in context
11:28:50 INFO - 1 xul.dll!void nsTArray_Impl<RefPtr<mozilla::gfx::VRDisplayHost>,nsTArrayInfallibleAllocator>::~nsTArray_Impl() [nsTArray.h:e75b6b732e7c02670ec3cb2b18a4679216baeec8 : 880 + 0x81]
11:28:50 INFO - rsp = 0x0000004b463ff500 rip = 0x00007ffbdccb4e7b
11:28:50 INFO - Found by: stack scanning
11:28:50 INFO - 2 xul.dll!mozilla::gfx::VRSystemManager::NotifyVSync() [gfxVR.cpp:e75b6b732e7c02670ec3cb2b18a4679216baeec8 : 51 + 0xa]
11:28:50 INFO - rsp = 0x0000004b463ff560 rip = 0x00007ffbdccb6c0e
11:28:50 INFO - Found by: call frame info
11:28:50 INFO - 3 xul.dll!mozilla::gfx::VRSystemManagerPuppet::NotifyVSync() [gfxVRPuppet.cpp:e75b6b732e7c02670ec3cb2b18a4679216baeec8 : 669 + 0x5]
11:28:50 INFO - rsp = 0x0000004b463ff5b0 rip = 0x00007ffbdccad53e
11:28:50 INFO - Found by: call frame info
11:28:50 INFO - 4 xul.dll!mozilla::gfx::VRManager::NotifyVsync(mozilla::TimeStamp const &) [VRManager.cpp:e75b6b732e7c02670ec3cb2b18a4679216baeec8 : 208 + 0x6]
11:28:50 INFO - rsp = 0x0000004b463ff5f0 rip = 0x00007ffbdccb30c0
11:28:50 INFO - Found by: call frame info
11:28:50 INFO - 5 xul.dll!mozilla::gfx::VRSystemManagerPuppet::Run10msTasks() [gfxVRPuppet.cpp:e75b6b732e7c02670ec3cb2b18a4679216baeec8 : 665 + 0xb]
11:28:50 INFO - rsp = 0x0000004b463ff630 rip = 0x00007ffbdccad4e2
11:28:50 INFO - Found by: call frame info
11:28:50 INFO - 6 xul.dll!mozilla::gfx::VRManager::Run10msTasks() [VRManager.cpp:e75b6b732e7c02670ec3cb2b18a4679216baeec8 : 337 + 0x6]
11:28:50 INFO - rsp = 0x0000004b463ff690 rip = 0x00007ffbdccb3774
11:28:50 INFO - Found by: call frame info
11:28:50 INFO - 7 xul.dll!mozilla::gfx::VRManager::RunTasks() [VRManager.cpp:e75b6b732e7c02670ec3cb2b18a4679216baeec8 : 274 + 0x8]
11:28:50 INFO - rsp = 0x0000004b463ff700 rip = 0x00007ffbdccb348e
11:28:50 INFO - Found by: call frame info
11:28:50 INFO - 8 xul.dll!nsTimerImpl::Fire(int) [nsTimerImpl.cpp:e75b6b732e7c02670ec3cb2b18a4679216baeec8 : 559 + 0xd]
11:28:50 INFO - rsp = 0x0000004b463ff7c0 rip = 0x00007ffbdb6c5c0f
11:28:50 INFO - Found by: call frame info
11:28:50 INFO - 9 xul.dll!nsTimerEvent::Run() [TimerThread.cpp:e75b6b732e7c02670ec3cb2b18a4679216baeec8 : 260 + 0x8]
11:28:50 INFO - rsp = 0x0000004b463ff920 rip = 0x00007ffbdb6c57ec
11:28:50 INFO - Found by: call frame info
11:28:50 INFO - 10 xul.dll!MessageLoop::RunTask(already_AddRefed<nsIRunnable>) [message_loop.cc:e75b6b732e7c02670ec3cb2b18a4679216baeec8 : 442 + 0x6]
11:28:50 INFO - rsp = 0x0000004b463ff990 rip = 0x00007ffbdbda8acf
11:28:50 INFO - Found by: call frame info
11:28:50 INFO - 11 xul.dll!MessageLoop::DeferOrRunPendingTask(MessageLoop::PendingTask &&) [message_loop.cc:e75b6b732e7c02670ec3cb2b18a4679216baeec8 : 450 + 0x8]
11:28:50 INFO - rsp = 0x0000004b463ffa10 rip = 0x00007ffbdbda95fc
11:28:50 INFO - Found by: call frame info
11:28:50 INFO - 12 xul.dll!MessageLoop::DoWork() [message_loop.cc:e75b6b732e7c02670ec3cb2b18a4679216baeec8 : 523 + 0xb]
11:28:50 INFO - rsp = 0x0000004b463ffa60 rip = 0x00007ffbdbda993b
11:28:50 INFO - Found by: call frame info
11:28:50 INFO - 13 xul.dll!base::MessagePumpForUI::DoRunLoop() [message_pump_win.cc:e75b6b732e7c02670ec3cb2b18a4679216baeec8 : 203 + 0x9]
11:28:50 INFO - rsp = 0x0000004b463ffb00 rip = 0x00007ffbdbd92ff9
11:28:50 INFO - Found by: call frame info
11:28:50 INFO - 14 xul.dll!base::MessagePumpWin::Run(base::MessagePump::Delegate *) [message_pump_win.h:e75b6b732e7c02670ec3cb2b18a4679216baeec8 : 79 + 0x57]
11:28:50 INFO - rsp = 0x0000004b463ffbb0 rip = 0x00007ffbdbd93d27
11:28:50 INFO - Found by: call frame info
11:28:50 INFO - 15 xul.dll!MessageLoop::RunHandler() [message_loop.cc:e75b6b732e7c02670ec3cb2b18a4679216baeec8 : 308 + 0x8]
11:28:50 INFO - rsp = 0x0000004b463ffc10 rip = 0x00007ffbdbda8692
11:28:50 INFO - Found by: call frame info
11:28:50 INFO - 16 xul.dll!base::Thread::ThreadMain() [thread.cc:e75b6b732e7c02670ec3cb2b18a4679216baeec8 : 192 + 0x5a]
11:28:50 INFO - rsp = 0x0000004b463ffc60 rip = 0x00007ffbdbdb2ede
11:28:50 INFO - Found by: call frame info
11:28:50 INFO - 17 xul.dll!static unsigned long `anonymous namespace'::ThreadFunc(void *) [platform_thread_win.cc:e75b6b732e7c02670ec3cb2b18a4679216baeec8 : 19 + 0x6]
11:28:50 INFO - rsp = 0x0000004b463ffe80 rip = 0x00007ffbdbd9482a
11:28:50 INFO - Found by: call frame info
11:28:50 INFO - 18 kernel32.dll!guard_dispatch_icall_nop + 0x86d4
11:28:50 INFO - rsp = 0x0000004b463ffeb0 rip = 0x00007ffc2cd03034
11:28:50 INFO - Found by: call frame info
11:28:50 INFO - 19 xul.dll + 0x824820
11:28:50 INFO - rsp = 0x0000004b463ffed8 rip = 0x00007ffbdbd94820
11:28:50 INFO - Found by: stack scanning
11:28:50 INFO - 20 mozglue.dll!static void patched_BaseThreadInitThunk(int, void *, void *) [WindowsDllBlocklist.cpp:e75b6b732e7c02670ec3cb2b18a4679216baeec8 : 712 + 0xe]
11:28:50 INFO - rsp = 0x0000004b463ffee0 rip = 0x00007ffc26467fdb
11:28:50 INFO - Found by: stack scanning
11:28:50 INFO - 21 ntdll.dll!guard_dispatch_icall_nop + 0x66b01
11:28:50 INFO - rsp = 0x0000004b463fff60 rip = 0x00007ffc2d531461
11:28:50 INFO - Found by: call frame info
11:28:50 INFO - 22 KERNELBASE.dll!guard_dispatch_icall_nop + 0xe15b0
11:28:50 INFO - rsp = 0x0000004b463fff90 rip = 0x00007ffc2997bf10
11:28:50 INFO - Found by: stack scanning

Comment 1

[Intermittent Failures Robot]

45 failures in 3910 pushes (0.012 failures/push) were associated with this bug in the last 7 days.

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

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

Repository breakdown:

• autoland: 27
• mozilla-inbound: 10
• mozilla-central: 8

Platform breakdown:

• windows10-64-qr: 32
• windows10-64: 11
• windows10-64-ccov: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1539119&startday=2019-03-25&endday=2019-03-31&tree=all

Comment 2

[Intermittent Failures Robot]

18 failures in 461 pushes (0.039 failures/push) were associated with this bug yesterday.

Repository breakdown:

• mozilla-central: 4
• autoland: 12
• mozilla-inbound: 2

Platform breakdown:

• windows10-64-ccov: 4
• windows10-64: 3
• windows10-64-qr: 11

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1539119&startday=2019-04-02&endday=2019-04-02&tree=all

Comment 3

[Intermittent Failures Robot]

17 failures in 638 pushes (0.027 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 12
• mozilla-central: 1
• mozilla-inbound: 4

Platform breakdown:

• windows10-64-qr: 12
• windows10-64: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1539119&startday=2019-04-03&endday=2019-04-03&tree=all

Comment 4

[Intermittent Failures Robot]

18 failures in 574 pushes (0.031 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 12
• mozilla-central: 2
• mozilla-inbound: 4

Platform breakdown:

• windows10-64-qr: 16
• windows10-64: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1539119&startday=2019-04-04&endday=2019-04-04&tree=all

Comment 5

[Intermittent Failures Robot]

68 failures in 2843 pushes (0.024 failures/push) were associated with this bug in the last 7 days.

This is the #31 most frequent failure this week.

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

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

Repository breakdown:

• autoland: 45
• mozilla-inbound: 12
• mozilla-central: 11

Platform breakdown:

• windows10-64-qr: 49
• windows10-64: 14
• windows10-64-ccov: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1539119&startday=2019-04-01&endday=2019-04-07&tree=all

Comment 6

[Firefox Bug Husbandry Bot]

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

Comment 7

[:kip (Kearwood Gilbert)]

I will be removing the affected code in Bug 1473402

Comment 8

[:kip (Kearwood Gilbert)]

I have removed VRDisplayHost in Bug 1466702, eliminating the affected code.