Closed Bug 1606126 Opened 6 years ago Closed 5 years ago

Intermittent dom/plugins/test/mochitest/test_bug1028200-1.html | application terminated with exit code 1 | application crashed [@ nptest.dll + 0x7db0]

Categories

(Core Graveyard :: Plug-ins, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: rmaries [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=282760561&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Q0kwLQmWRv-0Aevoh1HsKA/runs/0/artifacts/public/logs/live_backing.log


[task 2019-12-27T17:08:55.045Z] 17:08:55 INFO - TEST-START | dom/plugins/test/mochitest/test_bug1028200-1.html
[task 2019-12-27T17:08:55.528Z] 17:08:55 INFO - GECKO(2712) | ###!!! [Parent][MessageChannel::Call] Error: Channel error: cannot send/recv
[task 2019-12-27T17:08:55.528Z] 17:08:55 INFO - GECKO(2712) | ###!!! [Parent][MessageChannel] Error: (msgtype=0x6F001E,name=PPluginInstance::Msg_AsyncSetWindow) Channel error: cannot send/recv
[task 2019-12-27T17:08:55.567Z] 17:08:55 INFO - GECKO(2712) | [Parent 776, Gecko_IOThread] WARNING: file z:/task_1577456491/build/src/ipc/chromium/src/base/process_util_win.cc, line 160
[task 2019-12-27T17:08:55.856Z] 17:08:55 INFO - GECKO(2712) | Exiting due to channel error.
[task 2019-12-27T17:08:55.856Z] 17:08:55 INFO - GECKO(2712) | Exiting due to channel error.
[task 2019-12-27T17:08:58.898Z] 17:08:58 INFO - TEST-INFO | Main app process: exit 1
[task 2019-12-27T17:08:58.898Z] 17:08:58 INFO - Buffered messages logged at 17:08:55
[task 2019-12-27T17:08:58.898Z] 17:08:58 INFO - TEST-PASS | dom/plugins/test/mochitest/test_bug1028200-1.html | A valid string reason is expected
[task 2019-12-27T17:08:58.898Z] 17:08:58 INFO - TEST-PASS | dom/plugins/test/mochitest/test_bug1028200-1.html | Reason cannot be empty
[task 2019-12-27T17:08:58.898Z] 17:08:58 INFO - add_task | Entering test
[task 2019-12-27T17:08:58.898Z] 17:08:58 INFO - add_task | Leaving test
[task 2019-12-27T17:08:58.898Z] 17:08:58 INFO - add_task | Entering test
[task 2019-12-27T17:08:58.899Z] 17:08:58 INFO - TEST-PASS | dom/plugins/test/mochitest/test_bug1028200-1.html | Element is fullscreen
[task 2019-12-27T17:08:58.899Z] 17:08:58 INFO - TEST-PASS | dom/plugins/test/mochitest/test_bug1028200-1.html | Plugin was crashed
[task 2019-12-27T17:08:58.899Z] 17:08:58 INFO - add_task | Leaving test
[task 2019-12-27T17:08:58.899Z] 17:08:58 INFO - add_task | Entering test
[task 2019-12-27T17:08:58.899Z] 17:08:58 INFO - TEST-FAIL | dom/plugins/test/mochitest/test_bug1028200-1.html | The author of the test has indicated that flaky timeouts are expected. Reason: This is needed in the event the fullscreen element fails to cancel fullscreen. The fullscreen element is expected to exit fullscreen but takes some time to register as having exited when using mozCancelFullScreen. So we can't just check that mozFullScreenElement is true or false after having called mozCancelFullScreen without the timeout because it will return the value prior to actually cancelling. A timeout is preferred here as opposed to polling methods similar to SimpleTest.waitForCondition in SimpleTest.js for reasons ofidiomaticity.
[task 2019-12-27T17:08:58.899Z] 17:08:58 INFO - Buffered messages finished
[task 2019-12-27T17:08:58.899Z] 17:08:58 ERROR - TEST-UNEXPECTED-FAIL | dom/plugins/test/mochitest/test_bug1028200-1.html | application terminated with exit code 1
[task 2019-12-27T17:08:58.899Z] 17:08:58 INFO - runtests.py | Application ran for: 0:00:09.402000
[task 2019-12-27T17:08:58.899Z] 17:08:58 INFO - zombiecheck | Reading PID log: c:\users\task_1577465781\appdata\local\temp\tmpoaigo0pidlog
[task 2019-12-27T17:08:58.900Z] 17:08:58 INFO - ==> process 776 launched child process 3560 ("Z:\task_1577465781\build\application\firefox\firefox.exe" -contentproc --channel="776.0.339945856\191937828" -parentBuildID 20191227140334 -prefsHandle 2244 -prefMapHandle 2236 -prefsLen 1 -prefMapSize 224455 -greomni "Z:\task_1577465781\build\application\firefox\omni.ja" -appomni "Z:\task_1577465781\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1577465781\build\application\firefox\browser" - 776 "\.\pipe\gecko-crash-server-pipe.776" 2300 gpu)
[task 2019-12-27T17:08:58.900Z] 17:08:58 INFO - ==> process 776 launched child process 11008 ("Z:\task_1577465781\build\application\firefox\firefox.exe" -contentproc --channel="776.6.869378480\89032729" -childID 1 -isForBrowser -prefsHandle 2208 -prefMapHandle 2204 -prefsLen 2195 -prefMapSize 224455 -parentBuildID 20191227140334 -greomni "Z:\task_1577465781\build\application\firefox\omni.ja" -appomni "Z:\task_1577465781\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1577465781\build\application\firefox\browser" - 776 "\.\pipe\gecko-crash-server-pipe.776" 2944 tab)
[task 2019-12-27T17:08:58.900Z] 17:08:58 INFO - ==> process 776 launched child process 3400 ("Z:\task_1577465781\build\application\firefox\firefox.exe" -contentproc --channel="776.13.1633643322\1411710638" -childID 2 -isForBrowser -prefsHandle 3076 -prefMapHandle 3080 -prefsLen 2292 -prefMapSize 224455 -parentBuildID 20191227140334 -greomni "Z:\task_1577465781\build\application\firefox\omni.ja" -appomni "Z:\task_1577465781\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1577465781\build\application\firefox\browser" - 776 "\.\pipe\gecko-crash-server-pipe.776" 3124 tab)
[task 2019-12-27T17:08:58.900Z] 17:08:58 INFO - ==> process 776 launched child process 5128 ("Z:\task_1577465781\build\application\firefox\firefox.exe" -contentproc --channel="776.20.1417408389\1704491856" -childID 3 -isForBrowser -prefsHandle 3372 -prefMapHandle 3368 -prefsLen 2353 -prefMapSize 224455 -parentBuildID 20191227140334 -greomni "Z:\task_1577465781\build\application\firefox\omni.ja" -appomni "Z:\task_1577465781\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1577465781\build\application\firefox\browser" - 776 "\.\pipe\gecko-crash-server-pipe.776" 3292 tab)
[task 2019-12-27T17:08:58.900Z] 17:08:58 INFO - ==> process 776 launched child process 9940 ("Z:\task_1577465781\build\application\firefox\plugin-container.exe" --channel="776.27.744169741\1572479003" "C:\Users\task_1577465781\AppData\Local\Temp\tmpzmarj_.mozrunner\plugins\nptest.dll" "C:\Users\task_1577465781\AppData\LocalLow\Mozilla\Temp-{ac54974d-16a2-402b-a20c-a7fad0c8cb63}" "Z:\task_1577465781\AppData\Roaming\Adobe\" -greomni "Z:\task_1577465781\build\application\firefox\omni.ja" -appomni "Z:\task_1577465781\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1577465781\build\application\firefox\browser" - 776 "\.\pipe\gecko-crash-server-pipe.776" 4636 plugin)
[task 2019-12-27T17:08:58.900Z] 17:08:58 INFO - zombiecheck | Checking for orphan process with PID: 3560
[task 2019-12-27T17:08:58.900Z] 17:08:58 INFO - zombiecheck | Checking for orphan process with PID: 11008
[task 2019-12-27T17:08:58.900Z] 17:08:58 INFO - zombiecheck | Checking for orphan process with PID: 3400
[task 2019-12-27T17:08:58.901Z] 17:08:58 INFO - zombiecheck | Checking for orphan process with PID: 5128
[task 2019-12-27T17:08:58.901Z] 17:08:58 INFO - zombiecheck | Checking for orphan process with PID: 9940
[task 2019-12-27T17:08:58.901Z] 17:08:58 INFO - mozcrash Downloading symbols from: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/eQxqTEnRQkGMjoIbnnhTkg/artifacts/public/build/target.crashreporter-symbols.zip
[task 2019-12-27T17:09:04.365Z] 17:09:04 INFO - mozcrash Copy/paste: Z:\task_1577465781\build\win32-minidump_stackwalk.exe c:\users\task_1577465781\appdata\local\temp\tmpzmarj_.mozrunner\minidumps\0bf60ad5-5d60-4904-b9e4-c8342c2172bf.dmp c:\users\task_1577465781\appdata\local\temp\tmpvrdb_r
[task 2019-12-27T17:09:11.586Z] 17:09:11 INFO - mozcrash Saved minidump as Z:\task_1577465781\build\blobber_upload_dir\0bf60ad5-5d60-4904-b9e4-c8342c2172bf.dmp
[task 2019-12-27T17:09:11.588Z] 17:09:11 INFO - mozcrash Saved app info as Z:\task_1577465781\build\blobber_upload_dir\0bf60ad5-5d60-4904-b9e4-c8342c2172bf.extra
[task 2019-12-27T17:09:11.597Z] 17:09:11 INFO - PROCESS-CRASH | dom/plugins/test/mochitest/test_bug1028200-1.html | application crashed [@ nptest.dll + 0x7db0]
[task 2019-12-27T17:09:11.597Z] 17:09:11 INFO - Crash dump filename: c:\users\task_1577465781\appdata\local\temp\tmpzmarj_.mozrunner\minidumps\0bf60ad5-5d60-4904-b9e4-c8342c2172bf.dmp
[task 2019-12-27T17:09:11.597Z] 17:09:11 INFO - Operating system: Windows NT
[task 2019-12-27T17:09:11.597Z] 17:09:11 INFO - 10.0.17134
[task 2019-12-27T17:09:11.597Z] 17:09:11 INFO - CPU: amd64
[task 2019-12-27T17:09:11.597Z] 17:09:11 INFO - family 6 model 85 stepping 4
[task 2019-12-27T17:09:11.597Z] 17:09:11 INFO - 8 CPUs
[task 2019-12-27T17:09:11.597Z] 17:09:11 INFO -
[task 2019-12-27T17:09:11.597Z] 17:09:11 INFO - GPU: UNKNOWN
[task 2019-12-27T17:09:11.598Z] 17:09:11 INFO -
[task 2019-12-27T17:09:11.598Z] 17:09:11 INFO - Crash reason: EXCEPTION_ILLEGAL_INSTRUCTION
[task 2019-12-27T17:09:11.598Z] 17:09:11 INFO - Crash address: 0x7ffe709d7db0
[task 2019-12-27T17:09:11.598Z] 17:09:11 INFO - Assertion: Unknown assertion type 0x00000000
[task 2019-12-27T17:09:11.598Z] 17:09:11 INFO - Process uptime: 1 seconds
[task 2019-12-27T17:09:11.598Z] 17:09:11 INFO -
[task 2019-12-27T17:09:11.598Z] 17:09:11 INFO - Thread 0 (crashed)
[task 2019-12-27T17:09:11.598Z] 17:09:11 INFO - 0 nptest.dll + 0x7db0
[task 2019-12-27T17:09:11.598Z] 17:09:11 INFO - rax = 0x000000000000001f rdx = 0x0000000e3ddfedd0
[task 2019-12-27T17:09:11.598Z] 17:09:11 INFO - rcx = 0x0000018dcd3a82a0 rbx = 0x0000018dcd336e70
[task 2019-12-27T17:09:11.598Z] 17:09:11 INFO - rsi = 0x00007ffe709d1440 rdi = 0x0000000e3ddfedd0
[task 2019-12-27T17:09:11.598Z] 17:09:11 INFO - rbp = 0x0000018dcd38d400 rsp = 0x0000000e3ddfed28
[task 2019-12-27T17:09:11.598Z] 17:09:11 INFO - r8 = 0x0000000000000000 r9 = 0x0000000e3ddfed70
[task 2019-12-27T17:09:11.598Z] 17:09:11 INFO - r10 = 0x0000000e3ddfed70 r11 = 0x00007ffe70a079d0
[task 2019-12-27T17:09:11.598Z] 17:09:11 INFO - r12 = 0x0000000e3ddfefb8 r13 = 0x0000000000000000
[task 2019-12-27T17:09:11.598Z] 17:09:11 INFO - r14 = 0x0000000e3ddfef58 r15 = 0x0000000e3ddfef50
[task 2019-12-27T17:09:11.598Z] 17:09:11 INFO - rip = 0x00007ffe709d7db0
[task 2019-12-27T17:09:11.598Z] 17:09:11 INFO - Found by: given as instruction pointer in context
[task 2019-12-27T17:09:11.598Z] 17:09:11 INFO - 1 xul.dll!mozilla::plugins::PluginScriptableObjectChild::AnswerInvoke(mozilla::plugins::PluginIdentifier const&, nsTArray<mozilla::plugins::Variant>&&, mozilla::plugins::Variant*, bool*) [PluginScriptableObjectChild.cpp:474211787da1410492d916a06c7bf5bced9426da : 731 + 0x26]
[task 2019-12-27T17:09:11.598Z] 17:09:11 INFO - rbp = 0x0000018dcd38d400 rsp = 0x0000000e3ddfed30
[task 2019-12-27T17:09:11.599Z] 17:09:11 INFO - rip = 0x00007ffe4b91f300
[task 2019-12-27T17:09:11.599Z] 17:09:11 INFO - Found by: stack scanning
[task 2019-12-27T17:09:11.599Z] 17:09:11 INFO - 2 xul.dll!mozilla::plugins::PPluginScriptableObjectChild::OnCallReceived(IPC::Message const&, IPC::Message*&) [PPluginScriptableObjectChild.cpp: : 1002 + 0x2c]
[task 2019-12-27T17:09:11.599Z] 17:09:11 INFO - rbp = 0x0000018dcd38d400 rsp = 0x0000000e3ddfef10
[task 2019-12-27T17:09:11.599Z] 17:09:11 INFO - rip = 0x00007ffe4a1676ae
[task 2019-12-27T17:09:11.599Z] 17:09:11 INFO - Found by: call frame info
[task 2019-12-27T17:09:11.599Z] 17:09:11 INFO - 3 xul.dll!mozilla::plugins::PPluginModuleChild::OnCallReceived(IPC::Message const&, IPC::Message*&) [PPluginModuleChild.cpp: : 1207 + 0xc]
[task 2019-12-27T17:09:11.599Z] 17:09:11 INFO - rbp = 0x0000018dcd38d400 rsp = 0x0000000e3ddff010
[task 2019-12-27T17:09:11.599Z] 17:09:11 INFO - rip = 0x00007ffe4a161236
[task 2019-12-27T17:09:11.599Z] 17:09:11 INFO - Found by: call frame info
[task 2019-12-27T17:09:11.599Z] 17:09:11 INFO - 4 xul.dll!mozilla::ipc::MessageChannel::DispatchInterruptMessage(mozilla::ipc::ActorLifecycleProxy*, IPC::Message&&, unsigned long long) [MessageChannel.cpp:474211787da1410492d916a06c7bf5bced9426da : 2244 + 0xe]
[task 2019-12-27T17:09:11.599Z] 17:09:11 INFO - rbp = 0x0000018dcd38d400 rsp = 0x0000000e3ddff0f0
[task 2019-12-27T17:09:11.599Z] 17:09:11 INFO - rip = 0x00007ffe49fbbc72
[task 2019-12-27T17:09:11.599Z] 17:09:11 INFO - Found by: call frame info
[task 2019-12-27T17:09:11.599Z] 17:09:11 INFO - 5 xul.dll!mozilla::ipc::MessageChannel::DispatchMessage(IPC::Message&&) [MessageChannel.cpp:474211787da1410492d916a06c7bf5bced9426da : 2132 + 0x11]
[task 2019-12-27T17:09:11.599Z] 17:09:11 INFO - rbp = 0x0000018dcd38d400 rsp = 0x0000000e3ddff200
[task 2019-12-27T17:09:11.599Z] 17:09:11 INFO - rip = 0x00007ffe494b3588
[task 2019-12-27T17:09:11.599Z] 17:09:11 INFO - Found by: call frame info
[task 2019-12-27T17:09:11.599Z] 17:09:11 INFO - 6 xul.dll!mozilla::ipc::MessageChannel::MessageTask::Run() [MessageChannel.cpp:474211787da1410492d916a06c7bf5bced9426da : 2004 + 0xc]
[task 2019-12-27T17:09:11.600Z] 17:09:11 INFO - rbp = 0x0000018dcd38d400 rsp = 0x0000000e3ddff340
[task 2019-12-27T17:09:11.600Z] 17:09:11 INFO - rip = 0x00007ffe494b293f
[task 2019-12-27T17:09:11.600Z] 17:09:11 INFO - Found by: call frame info
[task 2019-12-27T17:09:11.600Z] 17:09:11 INFO - 7 xul.dll!MessageLoop::DoWork() [message_loop.cc:474211787da1410492d916a06c7bf5bced9426da : 523 + 0x29]
[task 2019-12-27T17:09:11.600Z] 17:09:11 INFO - rbp = 0x0000018dcd38d400 rsp = 0x0000000e3ddff380
[task 2019-12-27T17:09:11.600Z] 17:09:11 INFO - rip = 0x00007ffe4937fdfb
[task 2019-12-27T17:09:11.600Z] 17:09:11 INFO - Found by: call frame info
[task 2019-12-27T17:09:11.600Z] 17:09:11 INFO - 8 xul.dll!base::MessagePumpForUI::DoRunLoop() [message_pump_win.cc:474211787da1410492d916a06c7bf5bced9426da : 203 + 0x9]
[task 2019-12-27T17:09:11.600Z] 17:09:11 INFO - rbp = 0x0000018dcd38d400 rsp = 0x0000000e3ddff400
[task 2019-12-27T17:09:11.600Z] 17:09:11 INFO - rip = 0x00007ffe4946a891
[task 2019-12-27T17:09:11.600Z] 17:09:11 INFO - Found by: call frame info
[task 2019-12-27T17:09:11.601Z] 17:09:11 INFO - 9 xul.dll!base::MessagePumpWin::Run(base::MessagePump::Delegate*) [message_pump_win.h:474211787da1410492d916a06c7bf5bced9426da : 79 + 0x4f]
[task 2019-12-27T17:09:11.601Z] 17:09:11 INFO - rbp = 0x0000018dcd38d400 rsp = 0x0000000e3ddff470
[task 2019-12-27T17:09:11.601Z] 17:09:11 INFO - rip = 0x00007ffe4937fa3f
[task 2019-12-27T17:09:11.601Z] 17:09:11 INFO - Found by: call frame info
[task 2019-12-27T17:09:11.601Z] 17:09:11 INFO - 10 xul.dll!MessageLoop::RunHandler() [message_loop.cc:474211787da1410492d916a06c7bf5bced9426da : 302 + 0x8]
[task 2019-12-27T17:09:11.601Z] 17:09:11 INFO - rbp = 0x0000018dcd38d400 rsp = 0x0000000e3ddff4d0
[task 2019-12-27T17:09:11.601Z] 17:09:11 INFO - rip = 0x00007ffe4937f9e1
[task 2019-12-27T17:09:11.601Z] 17:09:11 INFO - Found by: call frame info
[task 2019-12-27T17:09:11.601Z] 17:09:11 INFO - 11 xul.dll!MessageLoop::Run() [message_loop.cc:474211787da1410492d916a06c7bf5bced9426da : 290 + 0x5]
[task 2019-12-27T17:09:11.601Z] 17:09:11 INFO - rbp = 0x0000018dcd38d400 rsp = 0x0000000e3ddff520
[task 2019-12-27T17:09:11.601Z] 17:09:11 INFO - rip = 0x00007ffe4939a4c1
[task 2019-12-27T17:09:11.601Z] 17:09:11 INFO - Found by: call frame info
[task 2019-12-27T17:09:11.601Z] 17:09:11 INFO - 12 xul.dll!XRE_InitChildProcess(int, char**, XREChildData const*) [nsEmbedFunctions.cpp:474211787da1410492d916a06c7bf5bced9426da : 781 + 0x5]
[task 2019-12-27T17:09:11.601Z] 17:09:11 INFO - rbp = 0x0000018dcd38d400 rsp = 0x0000000e3ddff570
[task 2019-12-27T17:09:11.602Z] 17:09:11 INFO - rip = 0x00007ffe4caf776b
[task 2019-12-27T17:09:11.602Z] 17:09:11 INFO - Found by: call frame info
[task 2019-12-27T17:09:11.602Z] 17:09:11 INFO - 13 plugin-container.exe!content_process_main(mozilla::Bootstrap*, int, char**) [plugin-container.cpp:474211787da1410492d916a06c7bf5bced9426da : 56 + 0x13]
[task 2019-12-27T17:09:11.602Z] 17:09:11 INFO - rbp = 0x0000018dcd38d400 rsp = 0x0000000e3ddff7d0
[task 2019-12-27T17:09:11.602Z] 17:09:11 INFO - rip = 0x00007ff7c2af1382
[task 2019-12-27T17:09:11.602Z] 17:09:11 INFO - Found by: call frame info
[task 2019-12-27T17:09:11.602Z] 17:09:11 INFO - 14 plugin-container.exe!wmain(int, wchar_t**) [nsWindowsWMain.cpp:474211787da1410492d916a06c7bf5bced9426da : 138 + 0x43]
[task 2019-12-27T17:09:11.602Z] 17:09:11 INFO - rbp = 0x0000018dcd38d400 rsp = 0x0000000e3ddff830
[task 2019-12-27T17:09:11.602Z] 17:09:11 INFO - rip = 0x00007ff7c2af12e3
[task 2019-12-27T17:09:11.602Z] 17:09:11 INFO - Found by: call frame info
[task 2019-12-27T17:09:11.602Z] 17:09:11 INFO - 15 plugin-container.exe!__scrt_common_main_seh() [exe_common.inl : 288 + 0x22]
[task 2019-12-27T17:09:11.602Z] 17:09:11 INFO - rbp = 0x0000018dcd38d400 rsp = 0x0000000e3ddff900
[task 2019-12-27T17:09:11.602Z] 17:09:11 INFO - rip = 0x00007ff7c2b26048
[task 2019-12-27T17:09:11.602Z] 17:09:11 INFO - Found by: call frame info
[task 2019-12-27T17:09:11.602Z] 17:09:11 INFO - 16 kernel32.dll!BaseThreadInitThunk + 0x14
[task 2019-12-27T17:09:11.602Z] 17:09:11 INFO - rbp = 0x0000018dcd38d400 rsp = 0x0000000e3ddff940
[task 2019-12-27T17:09:11.603Z] 17:09:11 INFO - rip = 0x00007ffe88bf3034
[task 2019-12-27T17:09:11.603Z] 17:09:11 INFO - Found by: call frame info
[task 2019-12-27T17:09:11.603Z] 17:09:11 INFO - 17 ntdll.dll!SdbpCheckMatchingFiles + 0x81
[task 2019-12-27T17:09:11.603Z] 17:09:11 INFO - rbp = 0x0000018dcd38d400 rsp = 0x0000000e3ddff970
[task 2019-12-27T17:09:11.603Z] 17:09:11 INFO - rip = 0x00007ffe88e31461
[task 2019-12-27T17:09:11.603Z] 17:09:11 INFO - Found by: call frame info

Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → INCOMPLETE
Product: Core → Core Graveyard
You need to log in before you can comment on or make changes to this bug.