Open Bug 1006234 Opened 7 years ago Updated 3 months ago

Intermittent test_hangui.xhtml,test_hangui.xul | Test timed out. | test_idle_hang.xul | plugin minidump exists | plugin extra file exists | NS_ERROR_FILE_NOT_FOUND: Component returned failure code at resource://gre/modules/KeyValueParser.jsm:37

Categories

(Core :: Plug-ins, defect, P3)

Product:
Core
Component:
Plug-ins
Platform:
x86_64
Windows 8.1
Type:
defect

Tracking

()

Status:
REOPENED
Tracking Flags:
Tracking Status
firefox32 --- wontfix
firefox33 --- fixed
firefox34 --- affected
firefox35 --- affected
firefox-esr24 --- unaffected
firefox-esr31 --- fixed
b2g-v2.0 --- fixed
b2g-v2.1 --- fixed

People

(Reporter: KWierso, Unassigned)

References

(Depends on 1 open bug)

Details

(Keywords: intermittent-failure)

Attachments

(1 file, 1 obsolete file)

Fix timeouts in test_hangui.xul
5.27 KB, patch
benjamin
: review+
Details | Diff | Splinter Review 
https://tbpl.mozilla.org/php/getParsedLog.php?id=39078218&tree=Mozilla-Inbound
WINNT 6.2 mozilla-inbound debug test mochitest-other on 2014-05-05 14:14:40 PDT for push 745c46904041

slave: t-w864-ix-037


14:28:59     INFO -  1885 INFO TEST-INFO | dumping last 1 message(s)
14:28:59     INFO -  1886 INFO TEST-INFO | if you need more context, please use SimpleTest.requestCompleteLog() in your test
14:28:59     INFO -  1887 INFO TEST-PASS | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_hangui.xul | Prime ChromeWorker
14:28:59     INFO -  TEST-INFO | screenshot: exit status 0
14:28:59     INFO -  1888 INFO TEST-UNEXPECTED-FAIL | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_hangui.xul | Test timed out.
14:29:00     INFO -  1889 INFO TEST-INFO | MEMORY STAT vsize after test: 857198592
14:29:00     INFO -  1890 INFO TEST-INFO | MEMORY STAT vsizeMaxContiguous after test: 1893662720
14:29:00     INFO -  1891 INFO TEST-INFO | MEMORY STAT residentFast after test: 74866688
14:29:00     INFO -  1892 INFO TEST-END | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_hangui.xul | finished in 301293ms
14:29:00     INFO -  ++DOMWINDOW == 24 (1EF56CE0) [pid = 2380] [serial = 1802] [outer = 18C611C8]
14:29:00     INFO -  1893 INFO TEST-START | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul
14:29:00     INFO -  ++DOMWINDOW == 25 (1EF5B150) [pid = 2380] [serial = 1803] [outer = 18C611C8]
14:29:00     INFO -  [Parent 2380] WARNING: NS_ENSURE_TRUE(obj) failed: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/content/base/src/nsObjectLoadingContent.cpp, line 3448
14:29:00     INFO -  [Parent 2380] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004001: file c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\content\xul\document\src\nsXULPrototypeCache.cpp, line 333
14:29:00     INFO -  --DOCSHELL 21BCE1F0 == 10 [pid = 2380] [id = 339]
14:29:00     INFO -  For application/x-test found plugin nptest.dll
14:29:07     INFO -  [Parent 2380] WARNING: Failed to send message!: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/dom/plugins/ipc/PluginScriptableObjectParent.cpp, line 166
14:29:07     INFO -  ###!!! [Parent][MessageChannel] Error: Channel timeout: cannot send/recv
14:29:07     INFO -  ###!!! [Parent][MessageChannel::Call] Error: Channel timeout: cannot send/recv
14:29:07     INFO -  [Parent 2380] WARNING: Failed to send message!: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/dom/plugins/ipc/PluginScriptableObjectParent.cpp, line 260
14:29:07     INFO -  ###!!! [Parent][MessageChannel::Call] Error: Channel timeout: cannot send/recv
14:29:07     INFO -  [Parent 2380] WARNING: Failed to send message!: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/dom/plugins/ipc/PluginScriptableObjectParent.cpp, line 118
14:29:07     INFO -  ###!!! [Parent][MessageChannel::Call] Error: Channel timeout: cannot send/recv
14:29:07     INFO -  [Parent 2380] WARNING: Failed to send message!: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/dom/plugins/ipc/PluginScriptableObjectParent.cpp, line 260
14:29:07     INFO -  ###!!! [Parent][MessageChannel::Call] Error: Channel timeout: cannot send/recv
14:29:07     INFO -  [Parent 2380] WARNING: Failed to send message!: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/dom/plugins/ipc/PluginScriptableObjectParent.cpp, line 118
14:29:07     INFO -  ###!!! [Parent][MessageChannel::Call] Error: Channel timeout: cannot send/recv
14:29:07     INFO -  [Parent 2380] WARNING: Failed to send message!: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/dom/plugins/ipc/PluginScriptableObjectParent.cpp, line 260
14:29:07     INFO -  ###!!! [Parent][MessageChannel::Call] Error: Channel timeout: cannot send/recv
14:29:07     INFO -  [Parent 2380] WARNING: Failed to send message!: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/dom/plugins/ipc/PluginScriptableObjectParent.cpp, line 118
14:29:07     INFO -  ###!!! [Parent][MessageChannel::Call] Error: Channel timeout: cannot send/recv
14:29:07     INFO -  [Parent 2380] WARNING: Failed to send message!: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/dom/plugins/ipc/PluginScriptableObjectParent.cpp, line 260
14:29:07     INFO -  ###!!! [Parent][MessageChannel::Call] Error: Channel timeout: cannot send/recv
14:29:07     INFO -  [Parent 2380] WARNING: Failed to send message!: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/dom/plugins/ipc/PluginScriptableObjectParent.cpp, line 118
14:29:07     INFO -  ###!!! [Parent][MessageChannel::Call] Error: Channel timeout: cannot send/recv
14:29:07     INFO -  [Parent 2380] WARNING: Failed to send message!: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/dom/plugins/ipc/PluginScriptableObjectParent.cpp, line 260
14:29:07     INFO -  ###!!! [Parent][MessageChannel::Call] Error: Channel timeout: cannot send/recv
14:29:07     INFO -  [Parent 2380] WARNING: Failed to send message!: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/dom/plugins/ipc/PluginScriptableObjectParent.cpp, line 118
14:29:07     INFO -  ###!!! [Parent][MessageChannel::Call] Error: Channel timeout: cannot send/recv
14:29:07     INFO -  [Parent 2380] WARNING: Failed to send message!: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/dom/plugins/ipc/PluginScriptableObjectParent.cpp, line 260
14:29:07     INFO -  ###!!! [Parent][MessageChannel::Call] Error: Channel timeout: cannot send/recv
14:29:07     INFO -  [Parent 2380] WARNING: Failed to send message!: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/dom/plugins/ipc/PluginScriptableObjectParent.cpp, line 118
14:29:07     INFO -  ###!!! [Parent][MessageChannel::Call] Error: Channel timeout: cannot send/recv
14:29:07     INFO -  [Parent 2380] WARNING: Failed to send message!: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/dom/plugins/ipc/PluginScriptableObjectParent.cpp, line 260
14:29:07     INFO -  ###!!! [Parent][MessageChannel::Call] Error: Channel timeout: cannot send/recv
14:29:07     INFO -  [Parent 2380] WARNING: Failed to send message!: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/dom/plugins/ipc/PluginScriptableObjectParent.cpp, line 118
14:29:07     INFO -  ###!!! [Parent][MessageChannel::Call] Error: Channel timeout: cannot send/recv
14:29:07     INFO -  [Parent 2380] WARNING: Failed to send message!: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/dom/plugins/ipc/PluginScriptableObjectParent.cpp, line 260
14:29:07     INFO -  ###!!! [Parent][MessageChannel::Call] Error: Channel timeout: cannot send/recv
14:29:07     INFO -  [Parent 2380] WARNING: Failed to send message!: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/dom/plugins/ipc/PluginScriptableObjectParent.cpp, line 118
14:29:07     INFO -  ###!!! [Parent][MessageChannel::Call] Error: Channel timeout: cannot send/recv
14:29:07     INFO -  [Parent 2380] WARNING: Failed to send message!: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/dom/plugins/ipc/PluginScriptableObjectParent.cpp, line 260
14:29:07     INFO -  ###!!! [Parent][MessageChannel::Call] Error: Channel timeout: cannot send/recv
14:29:07     INFO -  [Parent 2380] WARNING: Failed to send message!: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/dom/plugins/ipc/PluginScriptableObjectParent.cpp, line 118
14:29:07     INFO -  ###!!! [Parent][MessageChannel::Call] Error: Channel timeout: cannot send/recv
14:29:07     INFO -  [Parent 2380] WARNING: Failed to send message!: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/dom/plugins/ipc/PluginScriptableObjectParent.cpp, line 260
14:29:07     INFO -  ###!!! [Parent][MessageChannel::Call] Error: Channel timeout: cannot send/recv
14:29:07     INFO -  [Parent 2380] WARNING: Failed to send message!: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/dom/plugins/ipc/PluginScriptableObjectParent.cpp, line 118
14:29:07     INFO -  ###!!! [Parent][MessageChannel::Call] Error: Channel timeout: cannot send/recv
14:29:07     INFO -  [Parent 2380] WARNING: Failed to send message!: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/dom/plugins/ipc/PluginScriptableObjectParent.cpp, line 260
14:29:07     INFO -  ###!!! [Parent][MessageChannel::Call] Error: Channel timeout: cannot send/recv
14:29:07     INFO -  [Parent 2380] WARNING: Failed to send message!: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/dom/plugins/ipc/PluginScriptableObjectParent.cpp, line 118
14:29:07     INFO -  1894 INFO TEST-INFO | dumping last 6 message(s)
14:29:07     INFO -  1895 INFO TEST-INFO | if you need more context, please use SimpleTest.requestCompleteLog() in your test
14:29:07     INFO -  1896 INFO TEST-PASS | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | Observer fired
14:29:07     INFO -  1897 INFO TEST-PASS | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | Checking correct topic
14:29:07     INFO -  1898 INFO TEST-PASS | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | Checking null data
14:29:07     INFO -  1899 INFO TEST-PASS | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | got Propbag
14:29:07     INFO -  1900 INFO TEST-PASS | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | got writable Propbag
14:29:07     INFO -  1901 INFO TEST-PASS | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | got a non-empty plugin crash id
14:29:07     INFO -  1902 INFO TEST-UNEXPECTED-FAIL | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | plugin minidump exists
14:29:07     INFO -  1903 INFO TEST-UNEXPECTED-FAIL | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | plugin extra file exists
14:29:07     INFO -  1904 INFO TEST-INFO | dumping last 15 message(s)
14:29:07     INFO -  1905 INFO TEST-INFO | if you need more context, please use SimpleTest.requestCompleteLog() in your test
14:29:07     INFO -  1906 INFO TEST-PASS | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | Observer fired
14:29:07     INFO -  1907 INFO TEST-PASS | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | Checking correct topic
14:29:07     INFO -  1908 INFO TEST-PASS | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | Checking null data
14:29:07     INFO -  1909 INFO TEST-PASS | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | got Propbag
14:29:07     INFO -  1910 INFO TEST-PASS | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | got writable Propbag
14:29:07     INFO -  1911 INFO TEST-PASS | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | got a non-empty plugin crash id
14:29:07     INFO -  1912 INFO TEST-PASS | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | plugin minidump exists
14:29:07     INFO -  1913 INFO TEST-PASS | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | plugin extra file exists
14:29:07     INFO -  1914 INFO TEST-PASS | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | got field for additional minidumps
14:29:07     INFO -  1915 INFO TEST-PASS | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | browser in additional_minidumps
14:29:07     INFO -  1916 INFO TEST-PASS | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | additional dump 'browser' exists
14:29:07     INFO -  1917 INFO TEST-PASS | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | got extra field for plugin cpu usage
14:29:07     INFO -  1918 INFO TEST-PASS | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | plugin cpu usage is 0%
14:29:07     INFO -  1919 INFO TEST-PASS | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | got extra field for processor count
14:29:07     INFO -  1920 INFO TEST-PASS | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | number of processors is >0
14:29:07     INFO -  1921 INFO TEST-UNEXPECTED-FAIL | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | uncaught exception - NS_ERROR_FILE_NOT_FOUND: Component returned failure code: 0x80520012 (NS_ERROR_FILE_NOT_FOUND) [nsIFileInputStream.init] at resource://gre/modules/KeyValueParser.jsm:37
14:29:07     INFO -  JavaScript error: resource://gre/modules/KeyValueParser.jsm, line 37: NS_ERROR_FILE_NOT_FOUND: Component returned failure code: 0x80520012 (NS_ERROR_FILE_NOT_FOUND) [nsIFileInputStream.init]
14:29:07     INFO -  1922 INFO TEST-INFO | MEMORY STAT vsize after test: 864780288
14:29:07     INFO -  1923 INFO TEST-INFO | MEMORY STAT vsizeMaxContiguous after test: 1893662720
14:29:07     INFO -  1924 INFO TEST-INFO | MEMORY STAT residentFast after test: 123432960
14:29:07     INFO -  1925 INFO TEST-INFO | dumping last 9 message(s)
14:29:07     INFO -  1926 INFO TEST-INFO | if you need more context, please use SimpleTest.requestCompleteLog() in your test
14:29:07     INFO -  1927 INFO TEST-PASS | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | Plugin crashed notification received
14:29:07     INFO -  1928 INFO TEST-PASS | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | Observer should have fired first
14:29:07     INFO -  1929 INFO TEST-PASS | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | event is correct type
14:29:07     INFO -  1930 INFO TEST-PASS | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | Plugin crashed event target is plugin element
14:29:07     INFO -  1931 INFO TEST-PASS | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | plugin crashed event has the right interface
14:29:07     INFO -  1932 INFO TEST-PASS | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | got a non-empty dump ID
14:29:07     INFO -  1933 INFO TEST-PASS | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | got correct plugin name
14:29:07     INFO -  1934 INFO TEST-PASS | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | got a non-empty filename
14:29:07     INFO -  1935 INFO TEST-PASS | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | event said crash report was submitted
14:29:07     INFO -  1936 INFO TEST-UNEXPECTED-FAIL | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | [SimpleTest.finish()] this test already called finish!
14:29:07     INFO -  1937 ERROR TEST-UNEXPECTED-FAIL | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | called finish() multiple times
14:29:07     INFO -  1938 INFO TEST-END | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_idle_hang.xul | finished in 6496ms
14:29:07     INFO -  ++DOMWINDOW == 26 (1EF5E810) [pid = 2380] [serial = 1804] [outer = 18C611C8]
14:29:07     INFO -  1939 INFO TEST-START | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_npruntime.xul
14:29:07     INFO -  ++DOMWINDOW == 27 (1EF62360) [pid = 2380] [serial = 1805] [outer = 18C611C8]
14:29:07     INFO -  [Parent 2380] WARNING: NS_ENSURE_TRUE(obj) failed: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/content/base/src/nsObjectLoadingContent.cpp, line 3448
14:29:07     INFO -  For application/x-test found plugin nptest.dll
14:29:07     INFO -  [3412] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\xpcom\base\nsTraceRefcnt.cpp, line 143
14:29:07     INFO -  [3412] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\xpcom\base\nsTraceRefcnt.cpp, line 143
14:29:07     INFO -  [3412] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\xpcom\base\nsTraceRefcnt.cpp, line 143
14:29:07     INFO -  [3412] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\xpcom\base\nsTraceRefcnt.cpp, line 143
14:29:07     INFO -  ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to c:\users\cltbld~1.t-w\appdata\local\temp\tmpnggm9_\runtests_leaks.log
14:29:07     INFO -  1940 INFO TEST-INFO | MEMORY STAT vsize after test: 864780288
14:29:07     INFO -  1941 INFO TEST-INFO | MEMORY STAT vsizeMaxContiguous after test: 1893662720
14:29:07     INFO -  1942 INFO TEST-INFO | MEMORY STAT residentFast after test: 143581184
14:29:07     INFO -  1943 INFO TEST-END | chrome://mochitests/content/chrome/dom/plugins/test/mochitest/test_npruntime.xul | finished in 340ms




14:48:59     INFO -  [Parent 2380] WARNING: NS_ENSURE_TRUE(mDB) failed: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/netwerk/cache/nsDiskCacheDeviceSQL.cpp, line 1397
14:48:59     INFO -  [1100] WARNING: pipe error: 109: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 310
14:48:59     INFO -  NPP_Destroy
14:48:59     INFO -  NPP_Destroy
14:48:59     INFO -  NPP_Destroy
14:48:59     INFO -  NPP_Destroy
14:48:59     INFO -  nsStringStats
14:48:59     INFO -   => mAllocCount:            121
14:48:59     INFO -   => mReallocCount:            1
14:48:59     INFO -   => mFreeCount:             121
14:48:59     INFO -   => mShareCount:            163
14:48:59     INFO -   => mAdoptCount:              0
14:48:59     INFO -   => mAdoptFreeCount:          0
14:48:59     INFO -   => Process ID: 1100, Thread ID: 2660
14:54:29  WARNING -  TEST-UNEXPECTED-FAIL | Shutdown | application timed out after 330 seconds with no output
14:54:29     INFO -  Not taking screenshot here: see the one that was previously logged
14:54:29     INFO -  TEST-INFO | crashinject: exit status 0
14:54:30     INFO -  Single process terminated successfully
14:54:30     INFO -  TEST-INFO | Main app process: exit status c0000005
14:54:30  WARNING -  TEST-UNEXPECTED-FAIL | Shutdown | application terminated with exit code 3221225477
14:54:30     INFO -  INFO | runtests.py | Application ran for: 0:36:52.544000
14:54:30     INFO -  INFO | zombiecheck | Reading PID log: c:\users\cltbld~1.t-w\appdata\local\temp\tmpgep9jnpidlog
14:54:30     INFO -  ==> process 2380 launched child process 2572 ("C:\slave\test\build\application\firefox\plugin-container.exe" --channel=2380.19065598.1526832782 -greomni "C:\slave\test\build\application\firefox\omni.ja" -appomni "C:\slave\test\build\application\firefox\browser\omni.ja" -appdir "C:\slave\test\build\application\firefox\browser" - 2380 "\\.\pipe\gecko-crash-server-pipe.2380" tab)
14:54:30     INFO -  ==> process 2380 launched child process 3692 ("C:\slave\test\build\application\firefox\plugin-container.exe" --channel=2380.8bad908.1948721724 -greomni "C:\slave\test\build\application\firefox\omni.ja" -appomni "C:\slave\test\build\application\firefox\browser\omni.ja" -appdir "C:\slave\test\build\application\firefox\browser" - 2380 "\\.\pipe\gecko-crash-server-pipe.2380" tab)
14:54:30     INFO -  ==> process 2380 launched child process 680 ("C:\slave\test\build\application\firefox\plugin-container.exe" --channel=2380.191032e0.830966372 "c:\users\cltbld~1.t-w\appdata\local\temp\tmpnggm9_\plugins\nptest.dll" -greomni "C:\slave\test\build\application\firefox\omni.ja" -appomni "C:\slave\test\build\application\firefox\browser\omni.ja" -appdir "C:\slave\test\build\application\firefox\browser" - 2380 "\\.\pipe\gecko-crash-server-pipe.2380" plugin)
14:54:30     INFO -  ==> process 2380 launched child process 3824 ("C:\slave\test\build\application\firefox\plugin-container.exe" --channel=2380.19103da0.909766673 "c:\users\cltbld~1.t-w\appdata\local\temp\tmpnggm9_\plugins\nptest.dll" -greomni "C:\slave\test\build\application\firefox\omni.ja" -appomni "C:\slave\test\build\application\firefox\browser\omni.ja" -appdir "C:\slave\test\build\application\firefox\browser" - 2380 "\\.\pipe\gecko-crash-server-pipe.2380" plugin)
14:54:30     INFO -  ==> process 2380 launched child process 1792 ("C:\slave\test\build\application\firefox\plugin-container.exe" --channel=2380.19103438.519832611 "c:\users\cltbld~1.t-w\appdata\local\temp\tmpnggm9_\plugins\nptest.dll" -greomni "C:\slave\test\build\application\firefox\omni.ja" -appomni "C:\slave\test\build\application\firefox\browser\omni.ja" -appdir "C:\slave\test\build\application\firefox\browser" - 2380 "\\.\pipe\gecko-crash-server-pipe.2380" plugin)
14:54:30     INFO -  ==> process 2380 launched child process 2128 ("C:\slave\test\build\application\firefox\plugin-container.exe" --channel=2380.19105320.1376018101 "c:\users\cltbld~1.t-w\appdata\local\temp\tmpnggm9_\plugins\nptest.dll" -greomni "C:\slave\test\build\application\firefox\omni.ja" -appomni "C:\slave\test\build\application\firefox\browser\omni.ja" -appdir "C:\slave\test\build\application\firefox\browser" - 2380 "\\.\pipe\gecko-crash-server-pipe.2380" plugin)
14:54:30     INFO -  ==> process 2380 launched child process 3988 ("C:\slave\test\build\application\firefox\plugin-container.exe" --channel=2380.1910b940.94591179 "c:\users\cltbld~1.t-w\appdata\local\temp\tmpnggm9_\plugins\nptest.dll" -greomni "C:\slave\test\build\application\firefox\omni.ja" -appomni "C:\slave\test\build\application\firefox\browser\omni.ja" -appdir "C:\slave\test\build\application\firefox\browser" - 2380 "\\.\pipe\gecko-crash-server-pipe.2380" plugin)
14:54:30     INFO -  ==> process 2380 launched child process 708 ("C:\slave\test\build\application\firefox\plugin-container.exe" --channel=2380.19104b10.523786357 "c:\users\cltbld~1.t-w\appdata\local\temp\tmpnggm9_\plugins\nptest.dll" -greomni "C:\slave\test\build\application\firefox\omni.ja" -appomni "C:\slave\test\build\application\firefox\browser\omni.ja" -appdir "C:\slave\test\build\application\firefox\browser" - 2380 "\\.\pipe\gecko-crash-server-pipe.2380" plugin)
14:54:30     INFO -  ==> process 2380 launched child process 3412 ("C:\slave\test\build\application\firefox\plugin-container.exe" --channel=2380.19103ef8.982389106 "c:\users\cltbld~1.t-w\appdata\local\temp\tmpnggm9_\plugins\nptest.dll" -greomni "C:\slave\test\build\application\firefox\omni.ja" -appomni "C:\slave\test\build\application\firefox\browser\omni.ja" -appdir "C:\slave\test\build\application\firefox\browser" - 2380 "\\.\pipe\gecko-crash-server-pipe.2380" plugin)
14:54:30     INFO -  ==> process 2380 launched child process 2660 ("C:\slave\test\build\application\firefox\plugin-container.exe" --channel=2380.30083620.716426169 "c:\users\cltbld~1.t-w\appdata\local\temp\tmpnggm9_\plugins\nptest.dll" -greomni "C:\slave\test\build\application\firefox\omni.ja" -appomni "C:\slave\test\build\application\firefox\browser\omni.ja" -appdir "C:\slave\test\build\application\firefox\browser" - 2380 "\\.\pipe\gecko-crash-server-pipe.2380" plugin)
14:54:30     INFO -  ==> process 2380 launched child process 1756 ("C:\slave\test\build\application\firefox\plugin-container.exe" --channel=2380.2fe99478.381685022 "c:\users\cltbld~1.t-w\appdata\local\temp\tmpnggm9_\plugins\nptest.dll" -greomni "C:\slave\test\build\application\firefox\omni.ja" -appomni "C:\slave\test\build\application\firefox\browser\omni.ja" -appdir "C:\slave\test\build\application\firefox\browser" - 2380 "\\.\pipe\gecko-crash-server-pipe.2380" plugin)
14:54:30     INFO -  ==> process 2380 launched child process 4028 ("C:\slave\test\build\application\firefox\plugin-container.exe" --channel=2380.3027f940.1415269030 -greomni "C:\slave\test\build\application\firefox\omni.ja" -appomni "C:\slave\test\build\application\firefox\browser\omni.ja" -appdir "C:\slave\test\build\application\firefox\browser" - 2380 "\\.\pipe\gecko-crash-server-pipe.2380" tab)
14:54:30     INFO -  ==> process 2380 launched child process 3284 ("C:\slave\test\build\application\firefox\plugin-container.exe" --channel=2380.30283b10.1695436471 -greomni "C:\slave\test\build\application\firefox\omni.ja" -appomni "C:\slave\test\build\application\firefox\browser\omni.ja" -appdir "C:\slave\test\build\application\firefox\browser" - 2380 "\\.\pipe\gecko-crash-server-pipe.2380" tab)
14:54:30     INFO -  ==> process 2380 launched child process 212 ("C:\slave\test\build\application\firefox\plugin-container.exe" --channel=2380.30288b80.1001715479 -greomni "C:\slave\test\build\application\firefox\omni.ja" -appomni "C:\slave\test\build\application\firefox\browser\omni.ja" -appdir "C:\slave\test\build\application\firefox\browser" - 2380 "\\.\pipe\gecko-crash-server-pipe.2380" tab)
14:54:30     INFO -  ==> process 2380 launched child process 1632 ("C:\slave\test\build\application\firefox\plugin-container.exe" --channel=2380.30635e98.1897773373 -greomni "C:\slave\test\build\application\firefox\omni.ja" -appomni "C:\slave\test\build\application\firefox\browser\omni.ja" -appdir "C:\slave\test\build\application\firefox\browser" - 2380 "\\.\pipe\gecko-crash-server-pipe.2380" tab)
14:54:30     INFO -  ==> process 2380 launched child process 4068 ("C:\slave\test\build\application\firefox\plugin-container.exe" --channel=2380.3063b040.723755463 -greomni "C:\slave\test\build\application\firefox\omni.ja" -appomni "C:\slave\test\build\application\firefox\browser\omni.ja" -appdir "C:\slave\test\build\application\firefox\browser" - 2380 "\\.\pipe\gecko-crash-server-pipe.2380" tab)
14:54:30     INFO -  ==> process 2380 launched child process 3096 ("C:\slave\test\build\application\firefox\plugin-container.exe" --channel=2380.3063d9b0.1195135529 -greomni "C:\slave\test\build\application\firefox\omni.ja" -appomni "C:\slave\test\build\application\firefox\browser\omni.ja" -appdir "C:\slave\test\build\application\firefox\browser" - 2380 "\\.\pipe\gecko-crash-server-pipe.2380" tab)
14:54:30     INFO -  ==> process 2380 launched child process 1100 ("C:\slave\test\build\application\firefox\plugin-container.exe" --channel=2380.21ef6d80.747399907 "c:\users\cltbld~1.t-w\appdata\local\temp\tmpnggm9_\plugins\nptest.dll" -greomni "C:\slave\test\build\application\firefox\omni.ja" -appomni "C:\slave\test\build\application\firefox\browser\omni.ja" -appdir "C:\slave\test\build\application\firefox\browser" - 2380 "\\.\pipe\gecko-crash-server-pipe.2380" plugin)
14:54:37  WARNING -  PROCESS-CRASH | Shutdown | application crashed [@ CrashingThread(void *)]
14:54:37     INFO -  Crash dump filename: c:\users\cltbld~1.t-w\appdata\local\temp\tmpnggm9_\minidumps\decfefc2-f7c7-41f0-83b7-07e9b967d574.dmp
14:54:37     INFO -  Operating system: Windows NT
14:54:37     INFO -                    6.2.9200
14:54:37     INFO -  CPU: x86
14:54:37     INFO -       GenuineIntel family 6 model 30 stepping 5
14:54:37     INFO -       8 CPUs
14:54:37     INFO -  Crash reason:  EXCEPTION_ACCESS_VIOLATION_WRITE
14:54:37     INFO -  Crash address: 0x0
14:54:37     INFO -  Thread 39 (crashed)
14:54:37     INFO -   0  crashinjectdll.dll!CrashingThread(void *) [crashinjectdll.cpp:745c46904041 : 17 + 0x0]
14:54:37     INFO -      eip = 0x6adb1000   esp = 0x0af0f8f8   ebp = 0x0af0f900   ebx = 0x00000000
14:54:37     INFO -      esi = 0x00000000   edi = 0x00000000   eax = 0x75c28535   ecx = 0x00000000
14:54:37     INFO -      edx = 0x6adb1000   efl = 0x00010246
14:54:37     INFO -      Found by: given as instruction pointer in context
14:54:37     INFO -   1  kernel32.dll + 0x28542
14:54:37     INFO -      eip = 0x75c28543   esp = 0x0af0f8fc   ebp = 0x0af0f900
14:54:37     INFO -      Found by: call frame info
14:54:37     INFO -   2  ntdll.dll + 0x5ac68
14:54:37     INFO -      eip = 0x77b3ac69   esp = 0x0af0f908   ebp = 0x0af0f944
14:54:37     INFO -      Found by: previous frame's frame pointer
14:54:37     INFO -   3  ntdll.dll + 0x5ac3b
14:54:37     INFO -      eip = 0x77b3ac3c   esp = 0x0af0f94c   ebp = 0x0af0f95c
14:54:37     INFO -      Found by: previous frame's frame pointer
Can we please get a plugin expert to take a look at this? It's happening with pretty high frequency.
Flags: needinfo?(georg.fritzsche)
I wrote it, I'll take it.
Assignee: nobody → aklotz
Flags: needinfo?(georg.fritzsche)
Attached patch Fix timeouts in test_hangui.xml (obsolete) — — Splinter Review 
This should take care of it. When running it locally I noticed that the test's hook is seeing show events from windows that we're not interested in. As long as these events are occurring, we end up looping infinitely while waiting to see the window that we're interested in. Unfortunately if we're testing to ensure that a window _isn't_ shown, we never break.

The main improvement in this patch is to take timestamps before and after waiting for show events and making sure that we break if we exceed the timeout value. As a bonus, this test now completes 12s sooner.
Attachment #8480042 - Flags: review?(benjamin)
Please see comment 223.
Attachment #8480042 - Attachment is obsolete: true
Attachment #8480042 - Flags: review?(benjamin)
Attachment #8480060 - Flags: review?(benjamin)
Attachment #8480060 - Flags: review?(benjamin) → review+
try: https://tbpl.mozilla.org/?tree=Try&rev=531b5505e0fb (bug# on push is wrong but it's the right patch)
Keywords: checkin-needed
(Also the incorrect bug# was corrected in the patch before attaching)
https://hg.mozilla.org/mozilla-central/rev/3609f0637243
Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → FIXED
Target Milestone: --- → mozilla34
(In reply to TBPL Robot from comment #230)

:(
Status: RESOLVED → REOPENED
status-firefox34: fixed → affected
status-firefox35: --- → affected
Resolution: FIXED → ---
Target Milestone: mozilla34 → ---
In many of these logs there is a lot of extra output from Firefox between the start of test_hangui and the "Prime ChromeWorker" TEST-PASS line. At this point I suspect that a previous test in the suite has left behind some unfinished business that messes with test_hangui.