Closed Bug 1478934 Opened 7 years ago Closed 7 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | dom/canvas/test/webgl-conf/generated/test_conformance__ogles__GL__tan__tan_001_to_006.html | application timed out after 370 seconds with no output

Categories

(Core :: Graphics: CanvasWebGL, defect, P5)

defect

Tracking

()

RESOLVED DUPLICATE of bug 1479666

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: ccoroiu [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=190451710&repo=autoland https://queue.taskcluster.net/v1/task/bFHIDNtIRn2ENDi7QqmrEQ/runs/0/artifacts/public/logs/live_backing.log 05:25:46 INFO - 865 INFO TEST-START | dom/canvas/test/webgl-conf/generated/test_conformance__ogles__GL__tan__tan_001_to_006.html 05:25:46 INFO - GECKO(1788) | ++DOMWINDOW == 124 (27C77C00) [pid = 3596] [serial = 3032] [outer = 00EAC670] 05:25:46 INFO - GECKO(1788) | ++DOCSHELL 1574DC00 == 125 [pid = 3596] [id = {578d7b79-f408-415f-8ac3-e25d70516d32}] 05:25:46 INFO - GECKO(1788) | ++DOMWINDOW == 125 (00EAEB40) [pid = 3596] [serial = 3033] [outer = 00000000] 05:25:46 INFO - GECKO(1788) | ++DOMWINDOW == 126 (2B5D0C00) [pid = 3596] [serial = 3034] [outer = 00EAEB40] 05:25:46 INFO - GECKO(1788) | ++DOCSHELL 2D088800 == 126 [pid = 3596] [id = {48be00e6-03c9-45ee-8bd4-73a1c3fbe6a9}] 05:25:46 INFO - GECKO(1788) | ++DOMWINDOW == 127 (00EAEC70) [pid = 3596] [serial = 3035] [outer = 00000000] 05:25:46 INFO - GECKO(1788) | ++DOMWINDOW == 128 (2D089400) [pid = 3596] [serial = 3036] [outer = 00EAEC70] 05:25:46 INFO - GECKO(1788) | ++DOMWINDOW == 129 (2D088C00) [pid = 3596] [serial = 3037] [outer = 00EAEC70] 05:25:46 INFO - GECKO(1788) | Initializing context 2B5E2000 surface 1F012550 on display 00E91A60 05:31:54 INFO - JavaScript error: jar:file:///Z:/task_1532668128/build/application/firefox/omni.ja!/components/nsPlacesExpiration.js, line 762: TypeError: this._db is undefined 05:31:59 INFO - Buffered messages logged at 05:25:46 05:31:59 INFO - 866 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_conformance__ogles__GL__tan__tan_001_to_006.html | A valid string reason is expected 05:31:59 INFO - 867 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_conformance__ogles__GL__tan__tan_001_to_006.html | Reason cannot be empty 05:31:59 INFO - Buffered messages logged at 05:25:47 05:31:59 INFO - 868 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_conformance__ogles__GL__tan__tan_001_to_006.html | images are the same 05:31:59 INFO - 869 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_conformance__ogles__GL__tan__tan_001_to_006.html | getError was expected value: NO_ERROR : there should be no errors 05:31:59 INFO - 870 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_conformance__ogles__GL__tan__tan_001_to_006.html | images are the same 05:31:59 INFO - 871 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_conformance__ogles__GL__tan__tan_001_to_006.html | getError was expected value: NO_ERROR : there should be no errors 05:31:59 INFO - Buffered messages logged at 05:25:48 05:31:59 INFO - 872 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_conformance__ogles__GL__tan__tan_001_to_006.html | images are the same 05:31:59 INFO - 873 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_conformance__ogles__GL__tan__tan_001_to_006.html | getError was expected value: NO_ERROR : there should be no errors 05:31:59 INFO - 874 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_conformance__ogles__GL__tan__tan_001_to_006.html | images are the same 05:31:59 INFO - 875 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_conformance__ogles__GL__tan__tan_001_to_006.html | getError was expected value: NO_ERROR : there should be no errors 05:31:59 INFO - Buffered messages logged at 05:25:49 05:31:59 INFO - 876 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_conformance__ogles__GL__tan__tan_001_to_006.html | images are the same 05:31:59 INFO - 877 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_conformance__ogles__GL__tan__tan_001_to_006.html | getError was expected value: NO_ERROR : there should be no errors 05:31:59 INFO - Buffered messages finished 05:31:59 WARNING - TEST-UNEXPECTED-TIMEOUT | dom/canvas/test/webgl-conf/generated/test_conformance__ogles__GL__tan__tan_001_to_006.html | application timed out after 370 seconds with no output 05:31:59 INFO - 878 ERROR Force-terminating active process(es). 05:31:59 INFO - Determining child pids from psutil... 05:31:59 INFO - [3596, 1272, 3452] 05:31:59 INFO - ==> process 1788 launched child process 3452 ("Z:\task_1532668128\build\application\firefox\firefox.exe" -contentproc --channel="1788.0.132239160\1487458547" -childID 1 -isForBrowser -prefsHandle 2144 -prefMapHandle 2140 -prefsLen 1 -prefMapSize 183509 -schedulerPrefs 0001,2 -parentBuildID 20180727035816 -greomni "Z:\task_1532668128\build\application\firefox\omni.ja" -appomni "Z:\task_1532668128\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1532668128\build\application\firefox\browser" - 1788 "\\.\pipe\gecko-crash-server-pipe.1788" 2216 tab) 05:31:59 INFO - ==> process 1788 launched child process 3596 ("Z:\task_1532668128\build\application\firefox\firefox.exe" -contentproc --channel="1788.6.1783013374\1941094049" -childID 2 -isForBrowser -prefsHandle 2052 -prefMapHandle 1832 -prefsLen 41 -prefMapSize 183509 -schedulerPrefs 0001,2 -parentBuildID 20180727035816 -greomni "Z:\task_1532668128\build\application\firefox\omni.ja" -appomni "Z:\task_1532668128\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1532668128\build\application\firefox\browser" - 1788 "\\.\pipe\gecko-crash-server-pipe.1788" 2016 tab) 05:31:59 INFO - ==> process 1788 launched child process 1272 ("Z:\task_1532668128\build\application\firefox\firefox.exe" -contentproc --channel="1788.12.927489178\457998932" -childID 3 -isForBrowser -prefsHandle 2576 -prefMapHandle 2580 -prefsLen 1380 -prefMapSize 183509 -schedulerPrefs 0001,2 -parentBuildID 20180727035816 -greomni "Z:\task_1532668128\build\application\firefox\omni.ja" -appomni "Z:\task_1532668128\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1532668128\build\application\firefox\browser" - 1788 "\\.\pipe\gecko-crash-server-pipe.1788" 2636 tab) 05:31:59 INFO - Found child pids: set([1272, 3452, 3596]) 05:31:59 INFO - Killing process: 1272 05:31:59 INFO - TEST-INFO | started process screenshot 05:31:59 INFO - TEST-INFO | screenshot: exit 0 05:31:59 INFO - mozcrash kill_pid(): wait failed (-1) terminating pid 1272: error 5 05:31:59 INFO - Killing process: 3452 05:31:59 INFO - Not taking screenshot here: see the one that was previously logged 05:31:59 INFO - mozcrash kill_pid(): wait failed (-1) terminating pid 3452: error 5 05:31:59 INFO - Killing process: 3596 05:31:59 INFO - Not taking screenshot here: see the one that was previously logged 05:31:59 INFO - mozcrash kill_pid(): wait failed (-1) terminating pid 3596: error 5 05:31:59 INFO - psutil found pid 3596 dead 05:31:59 INFO - psutil found pid 1272 dead 05:31:59 INFO - psutil found pid 3452 dead 05:31:59 INFO - Killing process: 1788 05:31:59 INFO - Not taking screenshot here: see the one that was previously logged 05:32:00 INFO - mozcrash kill_pid(): wait failed (-1) terminating pid 1788: error 5 05:32:00 INFO - psutil found pid 1788 dead 05:32:00 INFO - TEST-INFO | Main app process: exit 1 05:32:00 INFO - Buffered messages finished 05:32:00 ERROR - 879 ERROR TEST-UNEXPECTED-FAIL | dom/canvas/test/webgl-conf/generated/test_conformance__ogles__GL__tan__tan_001_to_006.html | application terminated with exit code 1 05:32:00 INFO - runtests.py | Application ran for: 0:15:06.217000 05:32:00 INFO - zombiecheck | Reading PID log: c:\users\task_1532668128\appdata\local\temp\tmpdmqr7xpidlog 05:32:00 INFO - ==> process 1788 launched child process 3452 ("Z:\task_1532668128\build\application\firefox\firefox.exe" -contentproc --channel="1788.0.132239160\1487458547" -childID 1 -isForBrowser -prefsHandle 2144 -prefMapHandle 2140 -prefsLen 1 -prefMapSize 183509 -schedulerPrefs 0001,2 -parentBuildID 20180727035816 -greomni "Z:\task_1532668128\build\application\firefox\omni.ja" -appomni "Z:\task_1532668128\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1532668128\build\application\firefox\browser" - 1788 "\\.\pipe\gecko-crash-server-pipe.1788" 2216 tab) 05:32:00 INFO - ==> process 1788 launched child process 3596 ("Z:\task_1532668128\build\application\firefox\firefox.exe" -contentproc --channel="1788.6.1783013374\1941094049" -childID 2 -isForBrowser -prefsHandle 2052 -prefMapHandle 1832 -prefsLen 41 -prefMapSize 183509 -schedulerPrefs 0001,2 -parentBuildID 20180727035816 -greomni "Z:\task_1532668128\build\application\firefox\omni.ja" -appomni "Z:\task_1532668128\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1532668128\build\application\firefox\browser" - 1788 "\\.\pipe\gecko-crash-server-pipe.1788" 2016 tab) 05:32:00 INFO - ==> process 1788 launched child process 1272 ("Z:\task_1532668128\build\application\firefox\firefox.exe" -contentproc --channel="1788.12.927489178\457998932" -childID 3 -isForBrowser -prefsHandle 2576 -prefMapHandle 2580 -prefsLen 1380 -prefMapSize 183509 -schedulerPrefs 0001,2 -parentBuildID 20180727035816 -greomni "Z:\task_1532668128\build\application\firefox\omni.ja" -appomni "Z:\task_1532668128\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1532668128\build\application\firefox\browser" - 1788 "\\.\pipe\gecko-crash-server-pipe.1788" 2636 tab) 05:32:00 INFO - zombiecheck | Checking for orphan process with PID: 1272 05:32:00 INFO - zombiecheck | Checking for orphan process with PID: 3596 05:32:00 INFO - zombiecheck | Checking for orphan process with PID: 3452 05:32:00 INFO - mozcrash Copy/paste: Z:\task_1532668128\build\win32-minidump_stackwalk.exe c:\users\task_1532668128\appdata\local\temp\tmp8f1k6e.mozrunner\minidumps\420ec7a9-fd28-4e61-81cc-02a3fa921db8.dmp Z:\task_1532668128\build\symbols 05:32:11 INFO - mozcrash Saved minidump as Z:\task_1532668128\build\blobber_upload_dir\420ec7a9-fd28-4e61-81cc-02a3fa921db8.dmp 05:32:11 WARNING - PROCESS-CRASH | dom/canvas/test/webgl-conf/generated/test_conformance__ogles__GL__tan__tan_001_to_006.html | application crashed [None] 05:32:11 INFO - Crash dump filename: c:\users\task_1532668128\appdata\local\temp\tmp8f1k6e.mozrunner\minidumps\420ec7a9-fd28-4e61-81cc-02a3fa921db8.dmp 05:32:11 INFO - Operating system: Windows NT 05:32:11 INFO - 6.1.7601 Service Pack 1 05:32:11 INFO - CPU: x86 05:32:11 INFO - GenuineIntel family 6 model 45 stepping 7 05:32:11 INFO - 8 CPUs 05:32:11 INFO - GPU: UNKNOWN 05:32:11 INFO - No crash 05:32:11 INFO - Process uptime: 901 seconds 05:32:11 INFO - Thread 0 05:32:11 INFO - 0 ntdll.dll!KiFastSystemCallRet + 0x0 05:32:11 INFO - eip = 0x77ab70b4 esp = 0x0015ecf8 ebp = 0x0015ed50 ebx = 0x0015ed5c 05:32:11 INFO - esi = 0x00000000 edi = 0x00000000 eax = 0x00f01a00 ecx = 0x00000001 05:32:11 INFO - edx = 0x00000000 efl = 0x00000247 05:32:11 INFO - Found by: given as instruction pointer in context 05:32:11 INFO - 1 ntdll.dll!ZwWaitForKeyedEvent + 0xc 05:32:11 INFO - eip = 0x77ab69f4 esp = 0x0015ecfc ebp = 0x0015ed50 05:32:11 INFO - Found by: call frame info 05:32:11 INFO - 2 ntdll.dll!RtlSleepConditionVariableSRW + 0xda 05:32:11 INFO - eip = 0x77a74a1a esp = 0x0015ed00 ebp = 0x0015ed50 05:32:11 INFO - Found by: call frame info 05:32:11 INFO - 3 xul.dll!void nsTArray_Impl<mozilla::CycleCollectedJSContext::PendingIDBTransactionData,nsTArrayInfallibleAllocator>::~nsTArray_Impl() [nsTArray.h:5a3702fdbd1b42a9bcac1ed5eb06889652dd04c4 : 927 + 0x35] 05:32:11 INFO - eip = 0x54c07ef1 esp = 0x0015ed1c ebp = 0x0015ed50 05:32:11 INFO - Found by: stack scanning 05:32:11 INFO - 4 kernel32.dll!wcslen + 0xa4 05:32:11 INFO - eip = 0x77172419 esp = 0x0015ed58 ebp = 0x0015ed80 05:32:11 INFO - Found by: previous frame's frame pointer 05:32:11 INFO - 5 mozglue.dll!mozilla::detail::ConditionVariableImpl::wait_for(mozilla::detail::MutexImpl &,mozilla::BaseTimeDuration<mozilla::TimeDurationValueCalculator> const &) [ConditionVariable_windows.cpp:5a3702fdbd1b42a9bcac1ed5eb06889652dd04c4 : 67 + 0xf] 05:32:11 INFO - eip = 0x67c822a9 esp = 0x0015ed88 ebp = 0x0015edc0 05:32:11 INFO - Found by: call frame info
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → DUPLICATE
You need to log in before you can comment on or make changes to this bug.