Closed Bug 1608097 Opened 5 years ago Closed 5 years ago

Perma [tier2] dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Test timed out.

Categories

(Core :: Graphics: CanvasWebGL, defect)

defect
Not set
normal

Tracking

()

RESOLVED FIXED

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: intermittent-failure, regression, Whiteboard: [stockwell unknown])

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


[task 2020-01-09T13:17:15.824Z] 13:11:47 INFO - 85 INFO TEST-START | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html
[task 2020-01-09T13:17:15.824Z] 13:17:15 INFO - <snipped 4482 output lines - if you need more context, please use SimpleTest.requestCompleteLog() in your test>
[task 2020-01-09T13:17:15.825Z] 13:17:15 INFO - Buffered messages logged at 13:17:06
[task 2020-01-09T13:17:15.825Z] 13:17:15 INFO - 86 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 3, layer = 7 : all data initialized
[task 2020-01-09T13:17:15.825Z] 13:17:15 INFO - 87 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.825Z] 13:17:15 INFO - 88 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 4, layer = 0 : all data initialized
[task 2020-01-09T13:17:15.825Z] 13:17:15 INFO - 89 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.825Z] 13:17:15 INFO - 90 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 4, layer = 1 : all data initialized
[task 2020-01-09T13:17:15.825Z] 13:17:15 INFO - 91 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.825Z] 13:17:15 INFO - 92 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 4, layer = 2 : all data initialized
[task 2020-01-09T13:17:15.825Z] 13:17:15 INFO - 93 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.825Z] 13:17:15 INFO - 94 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 4, layer = 3 : all data initialized
[task 2020-01-09T13:17:15.825Z] 13:17:15 INFO - 95 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.825Z] 13:17:15 INFO - 96 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 4, layer = 4 : all data initialized
[task 2020-01-09T13:17:15.825Z] 13:17:15 INFO - 97 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.825Z] 13:17:15 INFO - 98 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 4, layer = 5 : all data initialized
[task 2020-01-09T13:17:15.825Z] 13:17:15 INFO - 99 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.825Z] 13:17:15 INFO - 100 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 4, layer = 6 : all data initialized
[task 2020-01-09T13:17:15.825Z] 13:17:15 INFO - 101 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.825Z] 13:17:15 INFO - 102 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 4, layer = 7 : all data initialized
[task 2020-01-09T13:17:15.825Z] 13:17:15 INFO - 103 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | getError was expected value: NO_ERROR :
[task 2020-01-09T13:17:15.825Z] 13:17:15 INFO - Buffered messages logged at 13:17:07
[task 2020-01-09T13:17:15.825Z] 13:17:15 INFO - 104 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.825Z] 13:17:15 INFO - 105 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 0, layer = 0 : all data initialized
[task 2020-01-09T13:17:15.825Z] 13:17:15 INFO - 106 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.825Z] 13:17:15 INFO - Buffered messages logged at 13:17:08
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 107 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 0, layer = 1 : all data initialized
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 108 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 109 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 0, layer = 2 : all data initialized
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 110 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 111 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 0, layer = 3 : all data initialized
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 112 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 113 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 0, layer = 4 : all data initialized
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 114 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 115 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 0, layer = 5 : all data initialized
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 116 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 117 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 0, layer = 6 : all data initialized
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 118 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 119 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 0, layer = 7 : all data initialized
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 120 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 121 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 1, layer = 0 : all data initialized
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 122 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 123 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 1, layer = 1 : all data initialized
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 124 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 125 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 1, layer = 2 : all data initialized
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 126 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 127 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 1, layer = 3 : all data initialized
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 128 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 129 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 1, layer = 4 : all data initialized
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 130 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 131 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 1, layer = 5 : all data initialized
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 132 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - Buffered messages logged at 13:17:09
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 133 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 1, layer = 6 : all data initialized
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 134 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 135 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 1, layer = 7 : all data initialized
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 136 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 137 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 2, layer = 0 : all data initialized
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 138 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 139 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 2, layer = 1 : all data initialized
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 140 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 141 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 2, layer = 2 : all data initialized
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 142 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 143 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 2, layer = 3 : all data initialized
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 144 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 145 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 2, layer = 4 : all data initialized
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 146 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 147 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 2, layer = 5 : all data initialized
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 148 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.842Z] 13:17:15 INFO - 149 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 2, layer = 6 : all data initialized
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 150 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 151 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 2, layer = 7 : all data initialized
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 152 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 153 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 3, layer = 0 : all data initialized
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 154 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 155 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 3, layer = 1 : all data initialized
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 156 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 157 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 3, layer = 2 : all data initialized
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 158 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 159 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 3, layer = 3 : all data initialized
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - Buffered messages logged at 13:17:10
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 160 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 161 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 3, layer = 4 : all data initialized
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 162 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 163 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 3, layer = 5 : all data initialized
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 164 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 165 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 3, layer = 6 : all data initialized
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 166 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 167 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 3, layer = 7 : all data initialized
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 168 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 169 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 4, layer = 0 : all data initialized
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 170 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 171 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 4, layer = 1 : all data initialized
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 172 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 173 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 4, layer = 2 : all data initialized
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 174 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 175 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 4, layer = 3 : all data initialized
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 176 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 177 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 4, layer = 4 : all data initialized
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 178 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 179 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 4, layer = 5 : all data initialized
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 180 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 181 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 4, layer = 6 : all data initialized
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 182 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | gl.checkFramebufferStatus(gl.FRAMEBUFFER) is gl.FRAMEBUFFER_COMPLETE
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 183 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Level = 4, layer = 7 : all data initialized
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 184 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | getError was expected value: NO_ERROR :
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - Buffered messages logged at 13:17:12
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 185 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | successfullyParsed is true
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - Buffered messages finished
[task 2020-01-09T13:17:15.854Z] 13:17:15 WARNING - 186 INFO TEST-UNEXPECTED-FAIL | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | Test timed out.
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:277:18
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - reportError@SimpleTest/TestRunner.js:121:22
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:18
[task 2020-01-09T13:17:15.854Z] 13:17:15 INFO - 187 INFO TEST-OK | dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html | took 329717ms
[task 2020-01-09T13:17:15.855Z] 13:17:15 INFO - 188 ERROR /tests/dom/canvas/test/webgl-conf/generated/test_2_conformance2__misc__uninitialized-test-2.html logged result after SimpleTest.finish(): Test timed out.

It seems that the test has gone from taking ~280 seconds to ~330. Investigating.

There's a couple of things going on here.

The most obvious is that we're doing a ton of forced GCs during these tests, but only on android. These are COMPONENT_UTILS GCs, in other words caused by something calling Cu.forceGC() or equivalent. This seems wrong but is a side issue.

The GCs themselves are also taking much longer following this change (the following are opt builds with GC profile logging enabled).

Pre:

 349 INFO TEST-START | dom/canvas/test/webgl-conf/generated/test_2_conformance__attribs__gl-matrix-attributes.html
 MajorGC:               Reason States FSNR  budget  total  bgnCB evct4m waitBG   prep   mark  sweep  cmpct  endCB  minor  evict brrier
 MinorGC:               Reason  PRate Size          total canIon mkVals mkClls mkSlts mcWCll mkGnrc ckTbls mkRntm mkDbgr swpCch collct  tenCB  sweep updtIn frSlts  clrSB  clear pretnr
 MinorGC:      COMPONENT_UTILS  11.3%   256            66      0      0      2      1      9      0      0     24      0      1     14      0      2      2      0      0      0      0
 MajorGC:      COMPONENT_UTILS 0 -> 0 F N               4      0      0      0      0      1      1      0      0      0      0      0
 MinorGC:      COMPONENT_UTILS  11.4%   256            98      0      0      2      2      8      0      0     53      0      1     15      0      2      2      0      0      0      0
 MajorGC:      COMPONENT_UTILS 0 -> 0 F N               4      0      0      0      0      1      1      0      0      0      0      0
 MinorGC:      COMPONENT_UTILS  11.3%   256            67      0      0      2      1      9      0      0     23      0      1     14      0      2      2      0      0      0      0
 MajorGC:      COMPONENT_UTILS 0 -> 0 F N               3      0      0      0      0      2      1      0      0      0      0      0
 MinorGC:      COMPONENT_UTILS  11.3%   256            65      0      0      2      1      9      0      0     22      0      1     13      0      2      2      0      1      0      0
 MajorGC:      COMPONENT_UTILS 0 -> 0 F N               4      0      0      0      0      1      1      0      0      0      0      0
 MinorGC:      COMPONENT_UTILS  11.4%   256            65      0      0      2      2      8      0      0     23      0      1     14      0      2      2      0      0      0      0
 MajorGC:      COMPONENT_UTILS 0 -> 0 F N               3      0      0      0      0      1      1      0      0      0      0      0
 MinorGC:      COMPONENT_UTILS  11.3%   256            41      0      0      1      1      4      0      0     15      0      0      8      0      1      1      0      0      0      0
 MajorGC:      COMPONENT_UTILS 0 -> 0 F N               2      0      0      0      0      1      0      0      0      0      0      0
 MinorGC:      COMPONENT_UTILS  11.3%   256            39      0      0      1      0      4      0      0     14      0      0      8      0      1      1      0      0      0      0
 MajorGC:      COMPONENT_UTILS 0 -> 0 F N               2      0      0      0      0      1      0      0      0      0      0      0
 MinorGC:      COMPONENT_UTILS  11.4%   256            38      0      0      1      1      4      0      0     14      0      0      7      0      1      1      0      0      0      0
 MajorGC:      COMPONENT_UTILS 0 -> 0 F N               2      0      0      0      0      1      0      0      0      0      0      0
 MinorGC:      COMPONENT_UTILS  11.3%   256            41      0      0      1      1      4      0      0     15      0      1      8      0      1      1      0      0      0      0
 MajorGC:      COMPONENT_UTILS 0 -> 0 F N               2      0      0      0      0      1      0      0      0      0      0      0
 MinorGC:      COMPONENT_UTILS  11.3%   256            39      0      0      1      1      4      0      0     15      0      0      7      0      1      1      0      0      0      0
 MajorGC:      COMPONENT_UTILS 0 -> 0 F N               2      0      0      0      0      1      0      0      0      0      0      0
 MinorGC:      COMPONENT_UTILS  11.4%   256            76      0      0      1      1      4      0      0     15      0      0     42      0      1      1      0      0      0      0
 MajorGC:      COMPONENT_UTILS 0 -> 0 F N               9      0      0      0      6      1      0      0      0      0      0      0
 MinorGC:      COMPONENT_UTILS  11.3%   256            53      0      0      1      1      4      0      0     15      0      1      8      0      2      1      0      0      0      0
 MajorGC:      COMPONENT_UTILS 0 -> 0 F N               2      0      0      0      0      1      0      0      0      0      0      0
 MinorGC:      COMPONENT_UTILS  11.3%   256            38      0      0      1      0      4      0      0     14      0      1      7      0      1      1      0      0      0      0
 MajorGC:      COMPONENT_UTILS 0 -> 0 F N               2      0      0      0      0      1      0      0      0      0      0      0
 MinorGC:      COMPONENT_UTILS  11.4%   256            40      0      0      1      1      4      0      0     15      0      0      8      0      1      1      0      0      0      0

Post:

 349 INFO TEST-START | dom/canvas/test/webgl-conf/generated/test_2_conformance__attribs__gl-matrix-attributes.html
 MajorGC:               Reason States FSNR  budget  total  bgnCB evct4m waitBG   prep   mark  sweep  cmpct  endCB  minor  evict brrier
 MinorGC:               Reason  PRate Size          total canIon mkVals mkClls mkSlts mcWCll mkGnrc ckTbls mkRntm mkDbgr swpCch collct  tenCB  sweep updtIn frSlts  clrSB  clear pretnr
 MinorGC:      COMPONENT_UTILS  11.3%   256           106      0      0      5      2     15      0      0     35      0      2     23      0      4      4      0      1      0      0
 MajorGC:      COMPONENT_UTILS 0 -> 0 F N               6      0      0      0      0      3      1      0      0      0      0      0
 MinorGC:      COMPONENT_UTILS  11.5%   256           107      0      0      4      3     15      0      0     36      0      2     23      0      4      4      0      1      0      0
 MajorGC:      COMPONENT_UTILS 0 -> 0 F N               6      0      0      0      0      3      1      0      0      0      0      0
 MinorGC:      COMPONENT_UTILS  11.4%   256            80      0      0      4      2     11      0      0     26      0      1     18      0      2      3      0      0      0      0
 MajorGC:      COMPONENT_UTILS 0 -> 0 F N               4      0      0      0      0      1      0      0      0      0      0      0
 MinorGC:      COMPONENT_UTILS  11.3%   256           108      0      0      4      2     16      0      0     36      0      2     24      0      4      3      0      1      0      0
 MajorGC:      COMPONENT_UTILS 0 -> 0 F N               6      0      0      0      0      3      1      0      0      0      0      0
 MinorGC:      COMPONENT_UTILS  11.5%   256           109      0      0      4      3     15      0      0     36      0      2     23      0      4      4      0      1      0      0
 MajorGC:      COMPONENT_UTILS 0 -> 0 F N               6      0      0      0      0      3      1      0      0      0      0      0
 MinorGC:      COMPONENT_UTILS  11.4%   256           105      0      0      4      3     15      0      0     35      0      2     23      0      4      4      0      1      0      0
 MajorGC:      COMPONENT_UTILS 0 -> 0 F N               6      0      0      0      0      3      1      0      0      0      0      0
 MinorGC:      COMPONENT_UTILS  11.3%   256           106      0      0      4      3     15      0      0     35      0      2     23      0      3      4      0      1      0      0
 MajorGC:      COMPONENT_UTILS 0 -> 0 F N               7      0      0      0      1      3      1      0      0      0      0      0
 MinorGC:      COMPONENT_UTILS  11.5%   256           106      0      0      4      3     15      0      0     35      0      2     23      0      4      4      0      1      0      0
 MajorGC:      COMPONENT_UTILS 0 -> 0 F N               6      0      0      0      0      3      1      0      0      0      0      0
 MinorGC:      COMPONENT_UTILS  11.4%   256           104      0      0      5      3     15      0      0     33      0      1     23      1      4      4      0      0      0      0
 MajorGC:      COMPONENT_UTILS 0 -> 0 F N               7      0      0      0      0      3      1      0      0      0      0      0
 MinorGC:      COMPONENT_UTILS  11.3%   256           106      0      0      4      2     16      0      0     35      0      2     23      0      4      4      0      1      0      0
 MajorGC:      COMPONENT_UTILS 0 -> 0 F N               6      0      0      0      0      3      1      0      0      0      0      0
 MinorGC:      COMPONENT_UTILS  11.5%   256           108      0      0      4      3     15      0      0     36      0      2     24      0      4      4      0      1      0      0
 MajorGC:      COMPONENT_UTILS 0 -> 0 F N               6      0      0      0      0      3      1      0      0      0      0      0
 MinorGC:      COMPONENT_UTILS  11.4%   256           105      0      0      4      3     15      0      0     35      0      2     23      0      4      4      0      1      0      0
 MajorGC:      COMPONENT_UTILS 0 -> 0 F N               6      0      0      0      0      3      1      0      0      0      0      0
 MinorGC:      COMPONENT_UTILS  11.3%   256           105      0      0      4      2     15      0      0     35      0      2     23      0      4      4      0      1      0      0
 MajorGC:      COMPONENT_UTILS 0 -> 0 F N               7      0      0      0      0      3      1      0      0      0      0      0
 MinorGC:      COMPONENT_UTILS  11.5%   256           105      0      0      4      3     15      0      0     34      0      2     23      0      4      4      0      1      0      0
 MajorGC:      COMPONENT_UTILS 0 -> 0 F N               6      0      0      0      0      3      1      0      0      0      0      0
 MinorGC:      COMPONENT_UTILS  11.4%   256           105      0      0      4      3     15      0      0     35      0      2     23      0      3      4      0      1      0      0

Surprisingly the minor GCs are also affected, even though they shouldn't have much to do with the mark bits which is what the patch changed. I currently don't know what to make of this.

The GC times in comment 7 are worse by a factor of two. Telemetry for aarch64/nightly, although noisy, doesn't show a significant difference so far. I still don't know what to make of this.

Running this test locally on Pixel 2 shows no difference for opt builds (51651ms pre vs 51488ms post).

I expect this to start passing again now that bug 1424934 has been backed out.

Flags: needinfo?(jcoppeard)
Status: NEW → RESOLVED
Closed: 5 years ago
Regressed by: 1424934
Resolution: --- → FIXED
Has Regression Range: --- → yes
Keywords: regression
See Also: → 1688465
You need to log in before you can comment on or make changes to this bug.