Closed Bug 1475861 Opened 7 years ago Closed 6 years ago

Intermittent dom/canvas/test/webgl-conf/generated/test_2_conformance__ogles__GL__swizzlers__swizzlers_113_to_120.html | Test timed out.

Categories

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

defect

Tracking

()

RESOLVED DUPLICATE of bug 1471001

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: apavel [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=188239545&repo=try https://queue.taskcluster.net/v1/task/XJBSOXLfSlKiupZxkMqsDA/runs/0/artifacts/public/logs/live_backing.log Central as Beta simulation 12:43:17 INFO - 769 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance__ogles__GL__swizzlers__swizzlers_113_to_120.html | getError was expected value: NO_ERROR : there should be no errors 12:43:17 INFO - 770 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance__ogles__GL__swizzlers__swizzlers_113_to_120.html | images are the same 12:43:17 INFO - 771 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance__ogles__GL__swizzlers__swizzlers_113_to_120.html | getError was expected value: NO_ERROR : there should be no errors 12:43:17 INFO - 772 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance__ogles__GL__swizzlers__swizzlers_113_to_120.html | images are the same 12:43:17 INFO - 773 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance__ogles__GL__swizzlers__swizzlers_113_to_120.html | getError was expected value: NO_ERROR : there should be no errors 12:43:17 INFO - Buffered messages logged at 12:38:04 12:43:17 INFO - 774 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance__ogles__GL__swizzlers__swizzlers_113_to_120.html | images are the same 12:43:17 INFO - 775 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance__ogles__GL__swizzlers__swizzlers_113_to_120.html | getError was expected value: NO_ERROR : there should be no errors 12:43:17 INFO - Buffered messages finished 12:43:17 ERROR - 776 INFO TEST-UNEXPECTED-FAIL | dom/canvas/test/webgl-conf/generated/test_2_conformance__ogles__GL__swizzlers__swizzlers_113_to_120.html | Test timed out. 12:43:17 INFO - reportError@SimpleTest/TestRunner.js:121:7 12:43:17 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7 12:43:17 INFO - GECKO(1936) | MEMORY STAT | vsize 1109MB | vsizeMaxContiguous 55MB | residentFast 261MB | heapAllocated 25MB 12:43:17 INFO - 777 INFO TEST-OK | dom/canvas/test/webgl-conf/generated/test_2_conformance__ogles__GL__swizzlers__swizzlers_113_to_120.html | took 315612ms 12:43:18 INFO - GECKO(1936) | ++DOMWINDOW == 9 (011CCC00) [pid = 4372] [serial = 1792] [outer = 011AD670] 12:43:18 INFO - 778 INFO None779 INFO TEST-START | dom/canvas/test/webgl-conf/generated/test_2_conformance__ogles__GL__tan__tan_001_to_006.html 12:43:18 INFO - GECKO(1936) | --DOCSHELL 0E1A5000 == 3 [pid = 4372] [id = {c01c7321-772c-449f-82f0-893d0bd72a5b}] 12:43:18 INFO - GECKO(1936) | --DOCSHELL 0FFA4400 == 2 [pid = 4372] [id = {dbcd38c0-80c6-45a4-b067-fb35d0ac853f}] 12:43:18 INFO - GECKO(1936) | ++DOMWINDOW == 10 (0AB04400) [pid = 4372] [serial = 1793] [outer = 011AD670] 12:43:18 INFO - GECKO(1936) | ++DOCSHELL 0AB06C00 == 3 [pid = 4372] [id = {5ddf6da4-1e32-49d0-89ee-672935674405}] 12:43:18 INFO - GECKO(1936) | ++DOMWINDOW == 11 (011AD7A0) [pid = 4372] [serial = 1794] [outer = 00000000] 12:43:18 INFO - GECKO(1936) | ++DOMWINDOW == 12 (0AB08000) [pid = 4372] [serial = 1795] [outer = 011AD7A0] 12:43:18 INFO - GECKO(1936) | ++DOCSHELL 0AB0E400 == 4 [pid = 4372] [id = {0bcf6b6a-12db-4b76-bfdb-468ccdb44887}] 12:43:18 INFO - GECKO(1936) | ++DOMWINDOW == 13 (011AD8D0) [pid = 4372] [serial = 1796] [outer = 00000000] 12:43:18 INFO - GECKO(1936) | ++DOMWINDOW == 14 (0AB09000) [pid = 4372] [serial = 1797] [outer = 011AD8D0] 12:43:18 INFO - GECKO(1936) | --DOMWINDOW == 13 (011AFC70) [pid = 4372] [serial = 1789] [outer = 00000000] [url = http://mochi.test:8888/tests/dom/canvas/test/webgl-conf/checkout/conformance/ogles/GL/swizzlers/swizzlers_113_to_120.html?webglVersion=2] 12:43:18 INFO - GECKO(1936) | ++DOMWINDOW == 14 (0ABBE000) [pid = 4372] [serial = 1798] [outer = 011AD8D0] 12:43:18 INFO - GECKO(1936) | --DOMWINDOW == 13 (102EB800) [pid = 4372] [serial = 1791] [outer = 00000000] [url = http://mochi.test:8888/tests/dom/canvas/test/webgl-conf/checkout/conformance/ogles/GL/swizzlers/swizzlers_113_to_120.html?webglVersion=2] 12:43:18 INFO - GECKO(1936) | --DOMWINDOW == 12 (0E68C000) [pid = 4372] [serial = 1786] [outer = 00000000] [url = http://mochi.test:8888/tests/dom/canvas/test/webgl-conf/generated/test_2_conformance__ogles__GL__swizzlers__swizzlers_113_to_120.html] 12:43:18 INFO - GECKO(1936) | Destroying context 249FE000 surface 10DE6EE0 on display 011652E0 12:43:19 INFO - GECKO(1936) | Initializing context 0BD8F000 surface 0CF8B1F0 on display 011652E0 12:43:20 INFO - GECKO(1936) | --DOMWINDOW == 11 (011AFB40) [pid = 4372] [serial = 1787] [outer = 00000000] [url = http://mochi.test:8888/tests/dom/canvas/test/webgl-conf/mochi-single.html?checkout/conformance/ogles/GL/swizzlers/swizzlers_113_to_120.html?webglVersion=2] 12:43:21 INFO - GECKO(1936) | MEMORY STAT | vsize 973MB | vsizeMaxContiguous 71MB | residentFast 290MB | heapAllocated 98MB 12:43:21 INFO - 780 INFO TEST-OK | dom/canvas/test/webgl-conf/generated/test_2_conformance__ogles__GL__tan__tan_001_to_006.html | took 3204ms
:jgilbert there are other failures of this sort that are timed out. Should we keep filing new bugs or make a general one? Examples: Bug 1475668, Bug 1475433, Bug 1474763, Bug 1404234, Bug 1474749, Bug 1474535, Bug 1474415, Bug 1463189.
Flags: needinfo?(jgilbert)
Whiteboard: [TV-bf notfound]
(In reply to Andreea Pavel [:apavel] from comment #1) > :jgilbert there are other failures of this sort that are timed out. Should > we keep filing new bugs or make a general one? > > Examples: Bug 1475668, Bug 1475433, Bug 1474763, Bug 1404234, Bug 1474749, > Bug 1474535, Bug 1474415, Bug 1463189. Chunk the tests harder. The timeouts for these are rarely due to test issues, so we cannot disable our way to success.
Flags: needinfo?(jgilbert)
Joel, please see the discussion above. Are the chunks changing, and if yes can the chunks be stabilized so always the same tests fail? Thank you.
Flags: needinfo?(jmaher)
I see webgl2-ext-e10s-X having 4 chunks, the rest appear to be single chunks. Tests should only move between chunks of the number of tests are changing. We run the tests in a "run-by-dir" fashion, so we take a list of directories and weight them based on the number of tests in there. In this case if we move tests between directories, disable a test or add a new test- there is a great chance we will rebalance the chunks. there are a couple ways to prevent this: 1) break these tests into smaller logical buckets and run each of them webgl-ext-xyz-e10s, etc. 2) for webgl tests, do not do run-by-dir and do straightforward chunking. This method would chunk based on total tests and the likelyhood of tests moving is reduced. Of course any add/disable of tests would cause tests to move slightly. the downfall here is that we could end up with some chunks having short runtimes and other chunks with very long runtimes- I don't think the risk is high. it appears that in most of the bugs we are running out of memory and causing a single test to timeout. I would advocate for smaller segments of tests. One example is just running the textures tests in a job by themselves, likewise with gls, gles.
Flags: needinfo?(jmaher)
Whiteboard: [TV-bf notfound]
Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → INCOMPLETE
Recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=200583710&repo=mozilla-central&lineNumber=7631 23:22:57 INFO - GECKO(1712) | Destroying context 0F00F000 surface 0E85CF70 on display 01390010 23:22:57 INFO - GECKO(1712) | --DOMWINDOW == 18 (0C50B400) [pid = 6060] [serial = 1772] [outer = 00000000] [url = http://mochi.test:8888/tests/dom/canvas/test/webgl-conf/generated/test_2_conformance__ogles__GL__swizzlers__swizzlers_097_to_104.html] 23:22:57 INFO - GECKO(1712) | --DOMWINDOW == 17 (0C5E0800) [pid = 6060] [serial = 1774] [outer = 00000000] [url = http://mochi.test:8888/tests/dom/canvas/test/webgl-conf/mochi-single.html?checkout/conformance/ogles/GL/swizzlers/swizzlers_097_to_104.html?webglVersion=2] 23:22:57 INFO - GECKO(1712) | --DOMWINDOW == 16 (0C5E1C00) [pid = 6060] [serial = 1776] [outer = 00000000] [url = about:blank] 23:22:57 INFO - GECKO(1712) | --DOMWINDOW == 15 (0C9A4000) [pid = 6060] [serial = 1777] [outer = 00000000] [url = http://mochi.test:8888/tests/dom/canvas/test/webgl-conf/checkout/conformance/ogles/GL/swizzlers/swizzlers_097_to_104.html?webglVersion=2] 23:22:57 INFO - GECKO(1712) | --DOMWINDOW == 14 (0F097C00) [pid = 6060] [serial = 1778] [outer = 00000000] [url = http://mochi.test:8888/tests/SimpleTest/iframe-between-tests.html] 23:22:57 INFO - GECKO(1712) | --DOMWINDOW == 13 (0C99EC00) [pid = 6060] [serial = 1779] [outer = 00000000] [url = http://mochi.test:8888/tests/dom/canvas/test/webgl-conf/generated/test_2_conformance__ogles__GL__swizzlers__swizzlers_105_to_112.html] 23:22:57 INFO - GECKO(1712) | --DOMWINDOW == 12 (0C5E0000) [pid = 6060] [serial = 1781] [outer = 00000000] [url = http://mochi.test:8888/tests/dom/canvas/test/webgl-conf/mochi-single.html?checkout/conformance/ogles/GL/swizzlers/swizzlers_105_to_112.html?webglVersion=2] 23:22:57 INFO - GECKO(1712) | --DOMWINDOW == 11 (0E04F800) [pid = 6060] [serial = 1783] [outer = 00000000] [url = about:blank] 23:22:57 INFO - GECKO(1712) | --DOMWINDOW == 10 (0BB81000) [pid = 6060] [serial = 1790] [outer = 00000000] [url = about:blank] 23:22:57 INFO - GECKO(1712) | --DOMWINDOW == 9 (0F33B000) [pid = 6060] [serial = 1765] [outer = 00000000] [url = http://mochi.test:8888/tests/dom/canvas/test/webgl-conf/generated/test_2_conformance__ogles__GL__swizzlers__swizzlers_089_to_096.html] 23:22:57 INFO - GECKO(1712) | --DOMWINDOW == 8 (0F576000) [pid = 6060] [serial = 1767] [outer = 00000000] [url = http://mochi.test:8888/tests/dom/canvas/test/webgl-conf/mochi-single.html?checkout/conformance/ogles/GL/swizzlers/swizzlers_089_to_096.html?webglVersion=2] 23:27:41 INFO - JavaScript error: jar:file:///Z:/task_1537484399/build/application/firefox/omni.ja!/components/nsPlacesExpiration.js, line 762: TypeError: this._db is undefined; can't access its "createAsyncStatement" property 23:27:54 INFO - TEST-INFO | started process screenshot 23:27:54 INFO - TEST-INFO | screenshot: exit 0 23:27:54 INFO - Buffered messages logged at 23:22:41 23:27:54 INFO - TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance__ogles__GL__swizzlers__swizzlers_113_to_120.html | A valid string reason is expected 23:27:54 INFO - TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance__ogles__GL__swizzlers__swizzlers_113_to_120.html | Reason cannot be empty 23:27:54 INFO - Buffered messages logged at 23:22:42 23:27:54 INFO - TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance__ogles__GL__swizzlers__swizzlers_113_to_120.html | images are the same 23:27:54 INFO - TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance__ogles__GL__swizzlers__swizzlers_113_to_120.html | getError was expected value: NO_ERROR : there should be no errors 23:27:54 INFO - Buffered messages finished 23:27:54 INFO - TEST-UNEXPECTED-FAIL | dom/canvas/test/webgl-conf/generated/test_2_conformance__ogles__GL__swizzlers__swizzlers_113_to_120.html | Test timed out. 23:27:54 INFO - reportError@SimpleTest/TestRunner.js:121:7 23:27:54 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7 23:27:54 INFO - setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5 23:27:54 INFO - setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5 23:27:54 INFO - setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5 23:27:54 INFO - setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5 23:27:54 INFO - setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5 23:27:54 INFO - setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5 23:27:54 INFO - setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5 23:27:54 INFO - setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5 23:27:54 INFO - setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5 23:27:54 INFO - setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5 23:27:54 INFO - setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5 23:27:54 INFO - setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5 23:27:54 INFO - setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5 23:27:54 INFO - setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5 23:27:54 INFO - setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5 23:27:54 INFO - setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5 23:27:54 INFO - setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5 23:27:54 INFO - setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5 23:27:54 INFO - setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5 23:27:54 INFO - setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5 23:27:54 INFO - setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5 23:27:54 INFO - setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5 23:27:54 INFO - setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5 23:27:54 INFO - setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5 23:27:54 INFO - setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5 23:27:54 INFO - setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5 23:27:54 INFO - setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5 23:27:54 INFO - setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5 23:27:54 INFO - setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5 23:27:54 INFO - setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5 23:27:54 INFO - TestRunner.runTests/<@SimpleTest/TestRunner.js:381:9 23:27:54 INFO - promise callback*TestRunner.runTests@SimpleTest/TestRunner.js:368:50 23:27:54 INFO - RunSet.runtests@SimpleTest/setup.js:194:3 23:27:54 INFO - RunSet.runall@SimpleTest/setup.js:173:5 23:27:54 INFO - hookupTests@SimpleTest/setup.js:266:5 23:27:54 INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:36:5 23:27:54 INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:49:11 23:27:54 INFO - EventHandlerNonNull*getTestManifest@http://mochi.test:8888/manifestLibrary.js:45:3 23:27:54 INFO - hookup@SimpleTest/setup.js:246:5 23:27:54 INFO - EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Ctask_1537484399%5Cappdata%5Clocal%5Ctemp&cleanupCrashes=true:11:1 23:27:55 INFO - GECKO(1712) | MEMORY STAT | vsize 919MB | vsizeMaxContiguous 29MB | residentFast 212MB | heapAllocated 14MB 23:27:55 INFO - TEST-OK | dom/canvas/test/webgl-conf/generated/test_2_conformance__ogles__GL__swizzlers__swizzlers_113_to_120.html | took 314803ms
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 6 years ago6 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 6 years ago6 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 6 years ago6 years ago
Resolution: --- → DUPLICATE
You need to log in before you can comment on or make changes to this bug.