Filed by: csabou [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=172452902&repo=mozilla-inbound https://queue.taskcluster.net/v1/task/TLJvOg0nRFmLK9L_t8Pz5Q/runs/0/artifacts/public/logs/live_backing.log 10:58:23 INFO - 145 INFO TEST-START | browser/components/contextualidentity/test/browser/browser_windowOpen.js 10:58:24 INFO - GECKO(4556) | MEMORY STAT | vsize 797MB | vsizeMaxContiguous 775MB | residentFast 266MB | heapAllocated 112MB 10:58:24 INFO - 146 INFO TEST-OK | browser/components/contextualidentity/test/browser/browser_windowOpen.js | took 336ms 10:58:24 INFO - 147 INFO checking window state 10:58:25 INFO - GECKO(4556) | Completed ShutdownLeaks collections in process 4008 10:58:25 INFO - GECKO(4556) | Completed ShutdownLeaks collections in process 4432 10:58:25 INFO - GECKO(4556) | Completed ShutdownLeaks collections in process 5448 10:58:25 INFO - GECKO(4556) | Completed ShutdownLeaks collections in process 3952 10:58:26 INFO - GECKO(4556) | Completed ShutdownLeaks collections in process 4556 10:58:26 INFO - 148 INFO TEST-START | Shutdown 10:58:26 INFO - 149 INFO Browser Chrome Test Summary 10:58:26 INFO - 150 INFO Passed: 189 10:58:26 INFO - 151 INFO Failed: 0 10:58:26 INFO - 152 INFO Todo: 0 10:58:26 INFO - 153 INFO Mode: e10s 10:58:26 INFO - 154 INFO *** End BrowserChrome Test Results *** 10:58:26 INFO - GECKO(4556) | !!! error running onStopped callback: TypeError: callback is not a function 10:58:26 INFO - GECKO(4556) | [Parent 4556, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 10:58:26 INFO - GECKO(4556) | [Child 4008, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 10:58:26 INFO - GECKO(4556) | [Parent 4556, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 10:58:26 INFO - GECKO(4556) | [Child 3952, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 10:58:26 INFO - GECKO(4556) | [Child 3952, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 10:58:26 INFO - GECKO(4556) | [Parent 4556, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 10:58:26 INFO - GECKO(4556) | [Child 5448, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 10:58:26 INFO - GECKO(4556) | [Child 5448, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 10:58:26 INFO - GECKO(4556) | [Parent 4556, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 10:58:26 INFO - GECKO(4556) | [Child 4432, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 10:58:26 INFO - GECKO(4556) | [Child 4432, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 10:58:26 INFO - GECKO(4556) | [Parent 4556, Gecko_IOThread] WARNING: file z:/build/build/src/ipc/chromium/src/base/process_util_win.cc, line 188 10:58:26 INFO - GECKO(4556) | JavaScript error: jar:file:///Z:/task_1523096912/build/application/firefox/browser/omni.ja!/components/nsBrowserGlue.js, line 1019: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIAppShellService.hiddenDOMWindow] 10:58:36 INFO - GECKO(4556) | WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"ServiceWorkerRegistrar: Flushing data","state":"(none)","filename":"z:/build/build/src/dom/serviceworkers/ServiceWorkerRegistrar.cpp","lineNumber":1060,"stack":"ServiceWorkerRegistrar: Flushing data"}] Barrier: profile-before-change 10:59:06 INFO - GECKO(4556) | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 10:59:27 INFO - GECKO(4556) | FATAL ERROR: AsyncShutdown timeout in profile-before-change Conditions: [{"name":"ServiceWorkerRegistrar: Flushing data","state":"(none)","filename":"z:/build/build/src/dom/serviceworkers/ServiceWorkerRegistrar.cpp","lineNumber":1060,"stack":"ServiceWorkerRegistrar: Flushing data"}] At least one completion condition failed to complete within a reasonable amount of time. Causing a crash to ensure that we do not leave the user with an unresponsive process draining resources. 10:59:27 INFO - GECKO(4556) | [Parent 4556, Main Thread] ###!!! ABORT: file z:/build/build/src/dom/serviceworkers/ServiceWorkerRegistrar.cpp, line 1060 10:59:27 INFO - TEST-INFO | Main app process: exit 1 10:59:27 INFO - Buffered messages finished 10:59:27 ERROR - 155 ERROR TEST-UNEXPECTED-FAIL | browser/components/contextualidentity/test/browser/browser_windowOpen.js | application terminated with exit code 1 10:59:27 INFO - runtests.py | Application ran for: 0:01:21.339000 10:59:27 INFO - zombiecheck | Reading PID log: c:\users\task_1523096912\appdata\local\temp\tmpeqvn4spidlog 10:59:27 INFO - ==> process 4556 launched child process 4432 ("Z:\task_1523096912\build\application\firefox\firefox.exe" -contentproc --channel="4556.0.1035734674\1642936759" -childID 1 -isForBrowser -prefsHandle 1720 -prefsLen 16537 -schedulerPrefs 0001,2 -greomni "Z:\task_1523096912\build\application\firefox\omni.ja" -appomni "Z:\task_1523096912\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1523096912\build\application\firefox\browser" - 4556 "\\.\pipe\gecko-crash-server-pipe.4556" 1872 tab) 10:59:27 INFO - ==> process 4556 launched child process 5448 ("Z:\task_1523096912\build\application\firefox\firefox.exe" -contentproc --channel="4556.6.1164582943\420738564" -childID 2 -isForBrowser -prefsHandle 2176 -prefsLen 16537 -schedulerPrefs 0001,2 -greomni "Z:\task_1523096912\build\application\firefox\omni.ja" -appomni "Z:\task_1523096912\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1523096912\build\application\firefox\browser" - 4556 "\\.\pipe\gecko-crash-server-pipe.4556" 2184 tab) 10:59:27 INFO - ==> process 4556 launched child process 3952 ("Z:\task_1523096912\build\application\firefox\firefox.exe" -contentproc --channel="4556.12.1284832786\216749467" -childID 3 -isForBrowser -prefsHandle 2900 -prefsLen 22087 -schedulerPrefs 0001,2 -greomni "Z:\task_1523096912\build\application\firefox\omni.ja" -appomni "Z:\task_1523096912\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1523096912\build\application\firefox\browser" - 4556 "\\.\pipe\gecko-crash-server-pipe.4556" 2916 tab) 10:59:27 INFO - ==> process 4556 launched child process 4008 ("Z:\task_1523096912\build\application\firefox\firefox.exe" -contentproc --channel="4556.18.1435825241\1814938070" -childID 4 -isForBrowser -prefsHandle 3212 -prefsLen 22262 -schedulerPrefs 0001,2 -greomni "Z:\task_1523096912\build\application\firefox\omni.ja" -appomni "Z:\task_1523096912\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1523096912\build\application\firefox\browser" - 4556 "\\.\pipe\gecko-crash-server-pipe.4556" 3476 tab) 10:59:27 INFO - ==> process 4556 launched child process 4920 ("Z:\task_1523096912\build\application\firefox\plugin-container.exe" --channel="4556.25.1002298960\1748889998" "Z:\task_1523096912\build\application\firefox\gmp-clearkey\0.1" -greomni "Z:\task_1523096912\build\application\firefox\omni.ja" -appomni "Z:\task_1523096912\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1523096912\build\application\firefox\browser" - 4556 "\\.\pipe\gecko-crash-server-pipe.4556" 3696 geckomediaplugin) 10:59:27 INFO - ==> process 4556 launched child process 3396 ("Z:\task_1523096912\build\application\firefox\plugin-container.exe" --channel="4556.28.1654171620\1209183888" "Z:\task_1523096912\build\application\firefox\gmp-clearkey\0.1" -greomni "Z:\task_1523096912\build\application\firefox\omni.ja" -appomni "Z:\task_1523096912\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1523096912\build\application\firefox\browser" - 4556 "\\.\pipe\gecko-crash-server-pipe.4556" 3936 geckomediaplugin) 10:59:27 INFO - ==> process 4556 launched child process 6040 ("Z:\task_1523096912\build\application\firefox\plugin-container.exe" --channel="4556.30.1468921686\309158209" "Z:\task_1523096912\build\application\firefox\gmp-clearkey\0.1" -greomni "Z:\task_1523096912\build\application\firefox\omni.ja" -appomni "Z:\task_1523096912\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1523096912\build\application\firefox\browser" - 4556 "\\.\pipe\gecko-crash-server-pipe.4556" 3980 geckomediaplugin) 10:59:27 INFO - ==> process 4556 launched child process 6136 ("Z:\task_1523096912\build\application\firefox\plugin-container.exe" --channel="4556.32.1808691484\1628106030" "Z:\task_1523096912\build\application\firefox\gmp-clearkey\0.1" -greomni "Z:\task_1523096912\build\application\firefox\omni.ja" -appomni "Z:\task_1523096912\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1523096912\build\application\firefox\browser" - 4556 "\\.\pipe\gecko-crash-server-pipe.4556" 3876 geckomediaplugin) 10:59:27 INFO - ==> process 4556 launched child process 3760 ("Z:\task_1523096912\build\application\firefox\plugin-container.exe" --channel="4556.34.1841082459\1711177869" "C:\Users\task_1523096912\AppData\Local\Temp\tmpfj7fzg.mozrunner\plugins\npswftest.dll" -greomni "Z:\task_1523096912\build\application\firefox\omni.ja" -appomni "Z:\task_1523096912\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1523096912\build\application\firefox\browser" - 4556 "\\.\pipe\gecko-crash-server-pipe.4556" 4284 plugin) 10:59:27 INFO - zombiecheck | Checking for orphan process with PID: 3952 10:59:27 INFO - zombiecheck | Checking for orphan process with PID: 3396 10:59:27 INFO - zombiecheck | Checking for orphan process with PID: 5448 10:59:27 INFO - zombiecheck | Checking for orphan process with PID: 4432 10:59:27 INFO - zombiecheck | Checking for orphan process with PID: 4008 10:59:27 INFO - zombiecheck | Checking for orphan process with PID: 6040 10:59:27 INFO - zombiecheck | Checking for orphan process with PID: 6136 10:59:27 INFO - zombiecheck | Checking for orphan process with PID: 4920 10:59:27 INFO - zombiecheck | Checking for orphan process with PID: 3760 10:59:27 INFO - mozcrash Downloading symbols from: https://queue.taskcluster.net/v1/task/GsIN2YLYRQ2AvTULjdRRfA/artifacts/public/build/target.crashreporter-symbols.zip 10:59:31 INFO - mozcrash Copy/paste: Z:\task_1523096912\build\win32-minidump_stackwalk.exe c:\users\task_1523096912\appdata\local\temp\tmpfj7fzg.mozrunner\minidumps\f555806a-4c70-41e3-a2bc-403eb2486473.dmp c:\users\task_1523096912\appdata\local\temp\tmp5rjrpd 10:59:45 INFO - mozcrash Saved minidump as Z:\task_1523096912\build\blobber_upload_dir\f555806a-4c70-41e3-a2bc-403eb2486473.dmp 10:59:45 INFO - mozcrash Saved app info as Z:\task_1523096912\build\blobber_upload_dir\f555806a-4c70-41e3-a2bc-403eb2486473.extra 10:59:45 WARNING - PROCESS-CRASH | browser/components/contextualidentity/test/browser/browser_windowOpen.js | application crashed [@ mozalloc_abort(char const * const)] 10:59:45 INFO - Crash dump filename: c:\users\task_1523096912\appdata\local\temp\tmpfj7fzg.mozrunner\minidumps\f555806a-4c70-41e3-a2bc-403eb2486473.dmp 10:59:45 INFO - Operating system: Windows NT 10:59:45 INFO - 6.1.7601 Service Pack 1 10:59:45 INFO - CPU: x86 10:59:45 INFO - GenuineIntel family 6 model 63 stepping 2 10:59:45 INFO - 8 CPUs 10:59:45 INFO - GPU: UNKNOWN 10:59:45 INFO - Crash reason: EXCEPTION_BREAKPOINT 10:59:45 INFO - Crash address: 0x64bb7056 10:59:45 INFO - Process uptime: 81 seconds 10:59:45 INFO - Thread 0 (crashed)