1511254 - Intermittent PROCESS-CRASH | Main app process exited normally | application crashed [@ xul.dll + 0x21315d]

Status: RESOLVED INCOMPLETE

(Core :: DOM: Core & HTML, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: apavel [at] mozilla.com

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

https://queue.taskcluster.net/v1/task/TPljpy8QTZiD9kba-5jMcQ/runs/0/artifacts/public/logs/live_backing.log

00:24:31     INFO - TEST-START | dom/html/test/test_window_open_close.html
00:24:32     INFO - GECKO(3740) | MEMORY STAT | vsize 1543MB | vsizeMaxContiguous 131907846MB | residentFast 174MB | heapAllocated 22MB
00:24:32     INFO - GECKO(3740) | ###!!! [Parent][MessageChannel] Error: (msgtype=0x190094,name=PBrowser::Msg_UpdateNativeWindowHandle) Channel error: cannot send/recv
00:24:32     INFO - GECKO(3740) | ###!!! [Parent][MessageChannel] Error: (msgtype=0x190083,name=PBrowser::Msg_Destroy) Channel error: cannot send/recv
00:24:32     INFO - GECKO(3740) | ###!!! [Parent][MessageChannel] Error: (msgtype=0x190094,name=PBrowser::Msg_UpdateNativeWindowHandle) Channel error: cannot send/recv
00:24:32     INFO - GECKO(3740) | ###!!! [Parent][MessageChannel] Error: (msgtype=0x190083,name=PBrowser::Msg_Destroy) Channel error: cannot send/recv
00:24:32     INFO - GECKO(3740) | A content process crashed and MOZ_CRASHREPORTER_SHUTDOWN is set, shutting down
00:24:32     INFO - GECKO(3740) | [NPAPI 8964, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
00:24:32     INFO - GECKO(3740) | [NPAPI 8964, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
00:24:32     INFO - GECKO(3740) | [Parent 6708, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
00:24:32     INFO - GECKO(3740) | [Parent 6708, Gecko_IOThread] WARNING: file z:/build/build/src/ipc/chromium/src/base/process_util_win.cc, line 188
00:24:32     INFO - GECKO(3740) | [Child 7736, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
00:24:32     INFO - GECKO(3740) | [Parent 6708, Gecko_IOThread] WARNING: file z:/build/build/src/ipc/chromium/src/base/process_util_win.cc, line 188
00:24:32     INFO - GECKO(3740) | [Parent 6708, Gecko_IOThread] WARNING: file z:/build/build/src/ipc/chromium/src/base/process_util_win.cc, line 188
00:24:32     INFO - GECKO(3740) | [Parent 6708, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
00:24:32     INFO - GECKO(3740) | [Child 6380, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
00:24:32     INFO - GECKO(3740) | [Parent 6708, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
00:24:32     INFO - GECKO(3740) | [Child 3328, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
00:24:32     INFO - GECKO(3740) | [Parent 6708, Gecko_IOThread] WARNING: file z:/build/build/src/ipc/chromium/src/base/process_util_win.cc, line 188
00:24:33     INFO - GECKO(3740) | 1543537473000	Marionette	TRACE	Received observer notification xpcom-will-shutdown
00:24:33     INFO - GECKO(3740) | 1543537473000	Marionette	INFO	Stopped listening on port 2828
00:24:33     INFO - GECKO(3740) | 1543537473000	Marionette	DEBUG	Remote service is inactive
00:24:33     INFO - GECKO(3740) | [GPU 8268, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
00:24:33     INFO - TEST-INFO | Main app process: exit 0
00:24:33     INFO - runtests.py | Application ran for: 0:01:24.057000
00:24:33     INFO - zombiecheck | Reading PID log: c:\users\task_1543536314\appdata\local\temp\tmpalnrjipidlog
00:24:33     INFO - ==> process 6708 launched child process 8268 ("Z:\task_1543536314\build\application\firefox\firefox.exe" -contentproc --channel="6708.0.815222423\542908713" -parentBuildID 20181129222752 -greomni "Z:\task_1543536314\build\application\firefox\omni.ja" -appomni "Z:\task_1543536314\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1543536314\build\application\firefox\browser" - "C:\Users\task_1543536314\AppData\LocalLow\Mozilla\Temp-{2c8c1846-f174-4494-a125-6ae7cc0b8679}" 6708 "\\.\pipe\gecko-crash-server-pipe.6708" 2752 gpu)
00:24:33     INFO - ==> process 6708 launched child process 3328 ("Z:\task_1543536314\build\application\firefox\firefox.exe" -contentproc --channel="6708.6.1152832856\954312837" -childID 1 -isForBrowser -prefsHandle 2808 -prefMapHandle 2032 -prefsLen 1 -prefMapSize 189088 -schedulerPrefs 0001,2 -parentBuildID 20181129222752 -greomni "Z:\task_1543536314\build\application\firefox\omni.ja" -appomni "Z:\task_1543536314\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1543536314\build\application\firefox\browser" - 6708 "\\.\pipe\gecko-crash-server-pipe.6708" 2620 tab)
00:24:33     INFO - ==> process 6708 launched child process 7736 ("Z:\task_1543536314\build\application\firefox\firefox.exe" -contentproc --channel="6708.13.738156368\217022820" -childID 2 -isForBrowser -prefsHandle 2936 -prefMapHandle 2940 -prefsLen 1 -prefMapSize 189088 -schedulerPrefs 0001,2 -parentBuildID 20181129222752 -greomni "Z:\task_1543536314\build\application\firefox\omni.ja" -appomni "Z:\task_1543536314\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1543536314\build\application\firefox\browser" - 6708 "\\.\pipe\gecko-crash-server-pipe.6708" 2952 tab)
00:24:33     INFO - ==> process 6708 launched child process 3744 ("Z:\task_1543536314\build\application\firefox\firefox.exe" -contentproc --channel="6708.20.596796140\580913784" -childID 3 -isForBrowser -prefsHandle 2952 -prefMapHandle 2988 -prefsLen 1 -prefMapSize 189088 -schedulerPrefs 0001,2 -parentBuildID 20181129222752 -greomni "Z:\task_1543536314\build\application\firefox\omni.ja" -appomni "Z:\task_1543536314\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1543536314\build\application\firefox\browser" - 6708 "\\.\pipe\gecko-crash-server-pipe.6708" 2060 tab)
00:24:33     INFO - ==> process 6708 launched child process 6380 ("Z:\task_1543536314\build\application\firefox\firefox.exe" -contentproc --channel="6708.27.1586804860\2091204172" -childID 4 -isForBrowser -prefsHandle 4652 -prefMapHandle 3572 -prefsLen 8321 -prefMapSize 189088 -schedulerPrefs 0001,2 -parentBuildID 20181129222752 -greomni "Z:\task_1543536314\build\application\firefox\omni.ja" -appomni "Z:\task_1543536314\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1543536314\build\application\firefox\browser" - 6708 "\\.\pipe\gecko-crash-server-pipe.6708" 4328 tab)
00:24:33     INFO - ==> process 6708 launched child process 8964 ("Z:\task_1543536314\build\application\firefox\plugin-container.exe" --channel="6708.34.1900804380\1022691149" "C:\Users\task_1543536314\AppData\Local\Temp\tmpcjhmo8.mozrunner\plugins\nptest.dll" "C:\Users\task_1543536314\AppData\LocalLow\Mozilla\Temp-{917a7b23-a47c-4c68-ad15-f725f7fed122}" "Z:\task_1543536314\AppData\Roaming\Adobe\\" -greomni "Z:\task_1543536314\build\application\firefox\omni.ja" -appomni "Z:\task_1543536314\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1543536314\build\application\firefox\browser" - 6708 "\\.\pipe\gecko-crash-server-pipe.6708" 4556 plugin)
00:24:33     INFO - zombiecheck | Checking for orphan process with PID: 3328
00:24:33     INFO - zombiecheck | Checking for orphan process with PID: 3744
00:24:33     INFO - zombiecheck | Checking for orphan process with PID: 8964
00:24:33     INFO - zombiecheck | Checking for orphan process with PID: 6380
00:24:33     INFO - zombiecheck | Checking for orphan process with PID: 8268
00:24:33     INFO - zombiecheck | Checking for orphan process with PID: 7736
00:24:33     INFO - mozcrash Downloading symbols from: https://queue.taskcluster.net/v1/task/Wo_9OGLuRpGXMWZVwp84Wg/artifacts/public/build/target.crashreporter-symbols.zip
00:24:37     INFO - mozcrash Copy/paste: Z:\task_1543536314\build\win32-minidump_stackwalk.exe c:\users\task_1543536314\appdata\local\temp\tmpcjhmo8.mozrunner\minidumps\cbd4f830-40a1-4eb9-a0e6-986dedc561b8.dmp c:\users\task_1543536314\appdata\local\temp\tmpaukqco
00:24:40     INFO - mozcrash Saved minidump as Z:\task_1543536314\build\blobber_upload_dir\cbd4f830-40a1-4eb9-a0e6-986dedc561b8.dmp
00:24:40     INFO - mozcrash Saved app info as Z:\task_1543536314\build\blobber_upload_dir\cbd4f830-40a1-4eb9-a0e6-986dedc561b8.extra
00:24:40     INFO - PROCESS-CRASH | Main app process exited normally | application crashed [@ xul.dll + 0x21315d]
00:24:40     INFO - Crash dump filename: c:\users\task_1543536314\appdata\local\temp\tmpcjhmo8.mozrunner\minidumps\cbd4f830-40a1-4eb9-a0e6-986dedc561b8.dmp
00:24:40     INFO - Operating system: Windows NT
00:24:40     INFO -                   10.0.15063 
00:24:40     INFO - CPU: amd64
00:24:40     INFO -      family 6 model 85 stepping 3
00:24:40     INFO -      8 CPUs
00:24:40     INFO - 
00:24:40     INFO - GPU: UNKNOWN
00:24:40     INFO - 
00:24:40     INFO - Crash reason:  EXCEPTION_BREAKPOINT
00:24:40     INFO - Crash address: 0x7ff9c2ae315d
00:24:40     INFO - Assertion: Unknown assertion type 0x00000000
00:24:40     INFO - Process uptime: 82 seconds
00:24:40     INFO - 
00:24:40     INFO - Thread 0 (crashed)
00:24:40     INFO -  0  xul.dll + 0x21315d
00:24:40     INFO -     rax = 0x00007ff9c7a5f815   rdx = 0x0000002dd13fcd28
00:24:40     INFO -     rcx = 0x00007ff9df2efb98   rbx = 0x0000022a9b2af700
00:24:40     INFO -     rsi = 0x0000022a98393220   rdi = 0x0000022a96010a20
00:24:40     INFO -     rbp = 0x0000022a9d1d3040   rsp = 0x0000002dd13fcd00
00:24:40     INFO -      r8 = 0x0000002dd13fcd30    r9 = 0x0000002dd13fcd50
00:24:40     INFO -     r10 = 0x000000000000003a   r11 = 0x0000022a90600370
00:24:40     INFO -     r12 = 0x00007ff9c7398812   r13 = 0x00000000ffffffff
00:24:40     INFO -     r14 = 0x00000000d13fcd01   r15 = 0x0000000000000000
00:24:40     INFO -     rip = 0x00007ff9c2ae315d
00:24:40     INFO -     Found by: given as instruction pointer in context
00:24:40     INFO -  1  ucrtbase.dll!round + 0x2d
00:24:40     INFO -     rbp = 0x0000022a9d1d3040   rsp = 0x0000002dd13fd150
00:24:40     INFO -     rip = 0x00007ff9f28fc48d
00:24:40     INFO -     Found by: stack scanning
00:24:40     INFO - 
00:24:40     INFO - Thread 1
00:24:40     INFO -  0  ntdll.dll!wspawnlpe + 0x814
00:24:40     INFO -     rax = 0x0000022a9d7cb000   rdx = 0x0000022a907790c0
00:24:40     INFO -     rcx = 0x00000000ba9999fc   rbx = 0x0000002dd15ff768
00:24:40     INFO -     rsi = 0x0000002dd15ff7b0   rdi = 0x0000002dd15ff7c0
00:24:40     INFO -     rbp = 0x0000000000000000   rsp = 0x0000002dd15ff6d8
00:24:40     INFO -      r8 = 0x0000000000000004    r9 = 0x00007ff9e6440000
00:24:40     INFO -     r10 = 0x0000022a907790c0   r11 = 0x0000022a9d7cb000
00:24:40     INFO -     r12 = 0x0000000000000000   r13 = 0x0000000000000000
00:24:40     INFO -     r14 = 0x0000002dd15ff990   r15 = 0x0000000000000012
00:24:40     INFO -     rip = 0x00007ff9f57e54c4
00:24:40     INFO -     Found by: given as instruction pointer in context
00:24:40     INFO - 
00:24:40     INFO - Thread 2
00:24:40     INFO -  0  ntdll.dll!wspawnlpe + 0x774
00:24:40     INFO -     rax = 0x000056d9a4a872d0   rdx = 0x0000000000000000
00:24:40     INFO -     rcx = 0x0000002dd0f9f7d0   rbx = 0x0000000000000000
00:24:40     INFO -     rsi = 0x00000000000003e8   rdi = 0x0000000000000328
00:24:40     INFO -     rbp = 0x00000000000003e8   rsp = 0x0000002dd0f9f6b8
00:24:40     INFO -      r8 = 0x0000002dd0f9f6b8    r9 = 0x00000000000003e8
00:24:40     INFO -     r10 = 0x0000000000000000   r11 = 0x0000000000000246
00:24:40     INFO -     r12 = 0x0000022a907efc08   r13 = 0x0000000000000000
00:24:40     INFO -     r14 = 0x0000002dd0f9f6e8   r15 = 0x00000000002625a0
00:24:40     INFO -     rip = 0x00007ff9f57e5424
00:24:40     INFO -     Found by: given as instruction pointer in context
00:24:40     INFO -  1  0x22a98e66ae0
00:24:40     INFO -     rbx = 0x0000000000000000   rbp = 0x00000000000003e8
00:24:40     INFO -     rsp = 0x0000002dd0f9f7d8   r12 = 0x0000022a907efc08
00:24:40     INFO -     r13 = 0x0000000000000000   r14 = 0x0000002dd0f9f6e8
00:24:40     INFO -     r15 = 0x00000000002625a0   rip = 0x0000022a98e66ae0
00:24:40     INFO -     Found by: call frame info
00:24:40     INFO -  2  nss3.dll + 0x12fef0
00:24:40     INFO -     rsp = 0x0000002dd0f9f7f8   rip = 0x00007ff9ddb7fef0
00:24:40     INFO -     Found by: stack scanning
00:24:40     INFO -  3  nss3.dll!PR_MD_INIT_THREAD [w95thred.c:0ceb5c6cc6b076e9b79f9d5f70ae901f8fd9644e : 126 + 0x13]
00:24:40     INFO -     rsp = 0x0000002dd0f9f820   rip = 0x00007ff9ddb8492a
00:24:40     INFO -     Found by: stack scanning
00:24:40     INFO -  4  kernel32.dll!__acrt_stdio_parse_mode<wchar_t>(wchar_t const * const) + 0x15c
00:24:40     INFO -     rsp = 0x0000002dd0f9f860   rip = 0x00007ff9f3445f00
00:24:40     INFO -     Found by: stack scanning
00:24:40     INFO -  5  0x22a9079a000
00:24:40     INFO -     rsp = 0x0000002dd0f9f8a0   rip = 0x0000022a9079a000
00:24:40     INFO -     Found by: call frame info
00:24:40     INFO -  6  ucrtbase.dll!o__strtoui64 + 0x20
00:24:40     INFO -     rsp = 0x0000002dd0f9f8a8   rip = 0x00007ff9f2900330
00:24:40     INFO -     Found by: stack scanning
00:24:40     INFO -  7  ucrtbase.dll!o__strtoui64 + 0x20
00:24:40     INFO -     rsp = 0x0000002dd0f9f8e8   rip = 0x00007ff9f2900330
00:24:40     INFO -     Found by: stack scanning
00:24:40     INFO -  8  nss3.dll!unsigned int pr_root(void *) [w95thred.c:0ceb5c6cc6b076e9b79f9d5f70ae901f8fd9644e : 137 + 0x6]
00:24:40     INFO -     rsp = 0x0000002dd0f9f910   rip = 0x00007ff9ddb84a9a
00:24:40     INFO -     Found by: stack scanning
00:24:40     INFO -  9  ucrtbase.dll!o__strtoui64 + 0x59
00:24:40     INFO -     rsp = 0x0000002dd0f9f940   rip = 0x00007ff9f2900369
00:24:40     INFO -     Found by: stack scanning
00:24:40     INFO - 10  kernel32.dll!setmbcp_nolock + 0x134
00:24:40     INFO -     rsp = 0x0000002dd0f9f970   rip = 0x00007ff9f3452774
00:24:40     INFO -     Found by: call frame info
00:24:40     INFO - 11  0x30
00:24:40     INFO -     rsp = 0x0000002dd0f9f9c0   rip = 0x0000000000000030
00:24:40     INFO -     Found by: call frame info
00:24:40     INFO - 12  ucrtbase.dll!__crt_state_management::wrapped_invoke<__crt_locale_pointers * (*)(int,char const *),int,char const *,__crt_locale_pointers *>(__crt_locale_pointers * (*)(int,char const *),int,char const *) + 0x34
00:24:40     INFO -     rsp = 0x0000002dd0f9f9c8   rip = 0x00007ff9f2900000
00:24:40     INFO -     Found by: stack scanning

Comment 1

[Intermittent Failures Robot]

9 failures in 2808 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* mozilla-inbound: 2
* autoland: 4
* mozilla-central: 2
* try: 1

Platform breakdown:
* windows10-64: 7
* windows10-64-devedition: 1
* windows10-64-nightly: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1511254&startday=2018-11-26&endday=2018-12-02&tree=all

Comment 2

[Intermittent Failures Robot]

3 failures in 1862 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* mozilla-beta: 1
* mozilla-inbound: 1
* autoland: 1

Platform breakdown:
* windows10-64: 2
* windows10-64-devedition: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1511254&startday=2018-12-03&endday=2018-12-09&tree=all

Comment 3

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup