Closed Bug 1429779 Opened 6 years ago Closed 6 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | dom/canvas/test/webgl-conf/generated/test_2_conformance__textures__misc__tex-image-and-sub-image-2d-with-array-buffer-view.html | application timed out after 370 seconds with no output

Categories

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

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, Whiteboard: [gfx-noted])

Filed by: apavel [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=155627837&repo=mozilla-inbound

https://queue.taskcluster.net/v1/task/NW_Xw4p3S0yEliL9EoB-Pw/runs/0/artifacts/public/logs/live_backing.log

13:33:29     INFO -  5645 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance__textures__misc__tex-image-and-sub-image-2d-with-array-buffer-view.html | top pixel should be 0,255,0,255
13:33:29     INFO -  5646 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance__textures__misc__tex-image-and-sub-image-2d-with-array-buffer-view.html | bottom left bottom pixels should be 255,0,0,255
13:33:29     INFO -  5647 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance__textures__misc__tex-image-and-sub-image-2d-with-array-buffer-view.html | bottom left top pixels should be 0,0,255,255
13:33:29     INFO -  5648 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance__textures__misc__tex-image-and-sub-image-2d-with-array-buffer-view.html | bottom right pixels should be 255,0,0,255
13:33:29     INFO -  5649 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance__textures__misc__tex-image-and-sub-image-2d-with-array-buffer-view.html | top pixels should be 0,255,0,255
13:33:29     INFO -  5650 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance__textures__misc__tex-image-and-sub-image-2d-with-array-buffer-view.html | bottom left bottom pixels should be 255,0,0,255
13:33:29     INFO -  5651 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance__textures__misc__tex-image-and-sub-image-2d-with-array-buffer-view.html | bottom left top pixels should be 0,0,255,255
13:33:29     INFO -  5652 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance__textures__misc__tex-image-and-sub-image-2d-with-array-buffer-view.html | bottom right pixels should be 255,0,0,255
13:33:29     INFO -  5653 INFO TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance__textures__misc__tex-image-and-sub-image-2d-with-array-buffer-view.html | top pixels should be 0,255,0,255
13:33:29     INFO -  Buffered messages finished
13:33:29  WARNING -  TEST-UNEXPECTED-TIMEOUT | dom/canvas/test/webgl-conf/generated/test_2_conformance__textures__misc__tex-image-and-sub-image-2d-with-array-buffer-view.html | application timed out after 370 seconds with no output
13:33:29     INFO -  5654 ERROR Force-terminating active process(es).
13:33:29     INFO -  Determining child pids from psutil...
13:33:29     INFO -  [8264, 7228]
13:33:29     INFO -  ==> process 9840 launched child process 7228 ("Z:\task_1515675988\build\application\firefox\firefox.exe" -contentproc --channel="9840.0.1693118945\8314171" -greomni "Z:\task_1515675988\build\application\firefox\omni.ja" -appomni "Z:\task_1515675988\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1515675988\build\application\firefox\browser"  "C:\Users\GenericWorker\AppData\LocalLow\Mozilla\Temp-{2adce473-6345-4e3c-b151-e84fcfa4df6c}" 9840 "\\.\pipe\gecko-crash-server-pipe.9840" gpu)
13:33:29     INFO -  ==> process 9840 launched child process 8264 ("Z:\task_1515675988\build\application\firefox\firefox.exe" -contentproc --channel="9840.3.796342106\1248932876" -childID 1 -isForBrowser -intPrefs 6:50|7:-1|35:1000|43:0|44:5|45:0|52:0|58:128|59:10000|64:0|66:400|67:1|68:0|69:0|70:100|75:0|76:120|77:120|159:2|160:1|164:60|165:30|166:1000|175:0|177:0|191:32|192:256|193:5|206:10000|227:24|228:32768|230:0|231:0|240:2|244:1048576|246:100|247:5000|249:600|251:1|259:20|276:5|280:0|289:60000|307:300|308:30| -boolPrefs 1:0|2:0|4:1|5:1|25:1|28:1|29:1|30:1|32:1|33:1|34:1|37:1|38:1|39:1|42:1|46:1|47:0|48:0|49:1|50:1|51:1|53:0|56:1|57:1|60:1|61:0|62:0|63:0|65:0|71:1|72:1|73:1|74:1|78:1|79:1|80:0|81:0|82:1|83:1|84:1|85:1|88:0|89:0|92:1|93:1|97:1|98:1|99:0|100:0|101:0|103:0|104:0|105:1|106:1|107:1|110:1|111:1|112:1|113:1|114:1|115:0|116:1|117:1|118:0|119:0|120:1|121:1|122:0|123:1|124:1|125:1|127:1|128:1|129:1|130:1|131:0|132:0|133:0|134:1|135:1|136:1|137:1|138:1|139:1|140:1|141:1|142:1|143:1|144:1|145:0|146:1|147:1|148:0|149:1|150:1|152:0|153:0|154:0|155:1|156:1|157:1|158:1|161:1|162:0|169:1|172:0|173:0|174:1|178:1|181:0|182:1|184:1|186:0|188:1|190:1|194:1|195:0|196:1|197:1|198:0|201:1|205:1|207:0|208:0|210:1|213:0|219:0|220:1|221:0|222:1|225:0|226:0|229:1|232:0|234:1|235:1|237:1|238:0|245:1|248:1|253:0|254:0|255:0|256:1|257:1|258:0|263:1|266:1|267:1|268:1|269:1|270:1|271:0|272:1|278:0|281:1|282:0|283:1|284:1|285:0|286:1|287:1|288:1|290:0|291:0|293:0|302:1|303:1|304:0|305:0|306:0| -stringPrefs "3:7;default|151:0;|212:4;0.01|223:332; ����!==> process 9840 launched child process 10596 ("Z:\task_1515675988\build\application\firefox\firefox.exe" -contentproc --channel="9840.13.440825695\748384113" -childID 2 -isForBrowser -intPrefs 6:50|7:-1|35:1000|43:0|44:5|45:0|52:0|58:128|59:10000|64:0|66:400|67:1|68:0|69:0|70:100|75:0|76:120|77:120|159:2|160:1|164:60|165:30|166:1000|175:0|177:0|191:32|192:256|193:5|206:10000|227:24|228:32768|230:0|231:0|240:2|244:1048576|246:100|247:5000|249:600|251:1|259:20|276:5|280:0|289:60000|307:300|308:30| -boolPrefs 1:0|2:0|4:1|5:1|25:1|28:1|29:1|30:1|32:1|33:1|34:1|37:1|38:1|39:1|42:1|46:1|47:0|48:0|49:1|50:1|51:1|53:0|56:1|57:1|60:1|61:0|62:0|63:0|65:0|71:1|72:1|73:1|74:1|78:1|79:1|80:0|81:0|82:1|83:1|84:1|85:1|88:0|89:0|92:1|93:1|97:1|98:1|99:0|100:0|101:0|103:0|104:0|105:1|106:1|107:1|110:1|111:1|112:1|113:1|114:1|115:0|116:1|117:1|118:0|119:0|120:1|121:1|122:0|123:1|124:1|125:1|127:1|128:1|129:1|130:1|131:0|132:0|133:0|134:1|135:1|136:1|137:1|138:1|139:1|140:1|141:1|142:1|143:1|144:1|145:0|146:1|147:1|148:0|149:1|150:1|152:0|153:0|154:0|155:1|156:1|157:1|158:1|161:1|162:0|169:1|172:0|173:0|174:1|178:1|181:0|182:1|184:1|186:0|188:1|190:1|194:1|195:0|196:1|197:1|198:0|201:1|205:1|207:0|208:0|210:1|213:0|219:0|220:1|221:0|222:1|225:0|226:0|229:1|232:0|234:1|235:1|237:1|238:0|245:1|248:1|253:0|254:0|255:0|256:1|257:1|258:0|263:1|266:1|267:1|268:1|269:1|270:1|271:0|272:1|278:0|281:1|282:0|283:1|284:1|285:0|286:1|287:1|288:1|290:0|291:0|293:0|302:1|303:1|304:0|305:0|306:0| -stringPrefs "3:7;default|151:0;|212:4;0.01|223:332; ����!
13:33:29     INFO -  Found child pids: set([8264, 7228])
13:33:29     INFO -  Killing process: 8264
13:33:29     INFO -  TEST-INFO | started process screenshot
13:33:29     INFO -  TEST-INFO | screenshot: exit 0
13:33:29     INFO -  mozcrash kill_pid(): wait failed (-1) terminating pid 8264: error 5
13:33:29     INFO -  Killing process: 7228
13:33:29     INFO -  Not taking screenshot here: see the one that was previously logged
13:33:29     INFO -  mozcrash kill_pid(): wait failed (-1) terminating pid 7228: error 5
13:33:29     INFO -  psutil found pid 7228 dead
13:33:29     INFO -  psutil found pid 8264 dead
13:33:29     INFO -  Killing process: 9840
13:33:29     INFO -  Not taking screenshot here: see the one that was previously logged
13:33:29     INFO -  mozcrash kill_pid(): wait failed (-1) terminating pid 9840: error 5
13:33:29     INFO -  psutil found pid 9840 dead
13:33:29     INFO -  TEST-INFO | Main app process: exit 1
13:33:29     INFO -  Buffered messages finished
13:33:29    ERROR -  5655 ERROR TEST-UNEXPECTED-FAIL | dom/canvas/test/webgl-conf/generated/test_2_conformance__textures__misc__tex-image-and-sub-image-2d-with-array-buffer-view.html | application terminated with exit code 1
13:33:29     INFO -  runtests.py | Application ran for: 0:10:29.720000
13:33:29     INFO -  zombiecheck | Reading PID log: c:\users\genericworker\appdata\local\temp\tmpv6pvtgpidlog
13:33:29     INFO -  ==> process 9840 launched child process 7228 ("Z:\task_1515675988\build\application\firefox\firefox.exe" -contentproc --channel="9840.0.1693118945\8314171" -greomni "Z:\task_1515675988\build\application\firefox\omni.ja" -appomni "Z:\task_1515675988\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1515675988\build\application\firefox\browser"  "C:\Users\GenericWorker\AppData\LocalLow\Mozilla\Temp-{2adce473-6345-4e3c-b151-e84fcfa4df6c}" 9840 "\\.\pipe\gecko-crash-server-pipe.9840" gpu)
13:33:29     INFO -  ==> process 9840 launched child process 8264 ("Z:\task_1515675988\build\application\firefox\firefox.exe" -contentproc --channel="9840.3.796342106\1248932876" -childID 1 -isForBrowser -intPrefs 6:50|7:-1|35:1000|43:0|44:5|45:0|52:0|58:128|59:10000|64:0|66:400|67:1|68:0|69:0|70:100|75:0|76:120|77:120|159:2|160:1|164:60|165:30|166:1000|175:0|177:0|191:32|192:256|193:5|206:10000|227:24|228:32768|230:0|231:0|240:2|244:1048576|246:100|247:5000|249:600|251:1|259:20|276:5|280:0|289:60000|307:300|308:30| -boolPrefs 1:0|2:0|4:1|5:1|25:1|28:1|29:1|30:1|32:1|33:1|34:1|37:1|38:1|39:1|42:1|46:1|47:0|48:0|49:1|50:1|51:1|53:0|56:1|57:1|60:1|61:0|62:0|63:0|65:0|71:1|72:1|73:1|74:1|78:1|79:1|80:0|81:0|82:1|83:1|84:1|85:1|88:0|89:0|92:1|93:1|97:1|98:1|99:0|100:0|101:0|103:0|104:0|105:1|106:1|107:1|110:1|111:1|112:1|113:1|114:1|115:0|116:1|117:1|118:0|119:0|120:1|121:1|122:0|123:1|124:1|125:1|127:1|128:1|129:1|130:1|131:0|132:0|133:0|134:1|135:1|136:1|137:1|138:1|139:1|140:1|141:1|142:1|143:1|144:1|145:0|146:1|147:1|148:0|149:1|150:1|152:0|153:0|154:0|155:1|156:1|157:1|158:1|161:1|162:0|169:1|172:0|173:0|174:1|178:1|181:0|182:1|184:1|186:0|188:1|190:1|194:1|195:0|196:1|197:1|198:0|201:1|205:1|207:0|208:0|210:1|213:0|219:0|220:1|221:0|222:1|225:0|226:0|229:1|232:0|234:1|235:1|237:1|238:0|245:1|248:1|253:0|254:0|255:0|256:1|257:1|258:0|263:1|266:1|267:1|268:1|269:1|270:1|271:0|272:1|278:0|281:1|282:0|283:1|284:1|285:0|286:1|287:1|288:1|290:0|291:0|293:0|302:1|303:1|304:0|305:0|306:0| -stringPrefs "3:7;default|151:0;|212:4;0.01|223:332; ����!==> process 9840 launched child process 10596 ("Z:\task_1515675988\build\application\firefox\firefox.exe" -contentproc --channel="9840.13.440825695\748384113" -childID 2 -isForBrowser -intPrefs 6:50|7:-1|35:1000|43:0|44:5|45:0|52:0|58:128|59:10000|64:0|66:400|67:1|68:0|69:0|70:100|75:0|76:120|77:120|159:2|160:1|164:60|165:30|166:1000|175:0|177:0|191:32|192:256|193:5|206:10000|227:24|228:32768|230:0|231:0|240:2|244:1048576|246:100|247:5000|249:600|251:1|259:20|276:5|280:0|289:60000|307:300|308:30| -boolPrefs 1:0|2:0|4:1|5:1|25:1|28:1|29:1|30:1|32:1|33:1|34:1|37:1|38:1|39:1|42:1|46:1|47:0|48:0|49:1|50:1|51:1|53:0|56:1|57:1|60:1|61:0|62:0|63:0|65:0|71:1|72:1|73:1|74:1|78:1|79:1|80:0|81:0|82:1|83:1|84:1|85:1|88:0|89:0|92:1|93:1|97:1|98:1|99:0|100:0|101:0|103:0|104:0|105:1|106:1|107:1|110:1|111:1|112:1|113:1|114:1|115:0|116:1|117:1|118:0|119:0|120:1|121:1|122:0|123:1|124:1|125:1|127:1|128:1|129:1|130:1|131:0|132:0|133:0|134:1|135:1|136:1|137:1|138:1|139:1|140:1|141:1|142:1|143:1|144:1|145:0|146:1|147:1|148:0|149:1|150:1|152:0|153:0|154:0|155:1|156:1|157:1|158:1|161:1|162:0|169:1|172:0|173:0|174:1|178:1|181:0|182:1|184:1|186:0|188:1|190:1|194:1|195:0|196:1|197:1|198:0|201:1|205:1|207:0|208:0|210:1|213:0|219:0|220:1|221:0|222:1|225:0|226:0|229:1|232:0|234:1|235:1|237:1|238:0|245:1|248:1|253:0|254:0|255:0|256:1|257:1|258:0|263:1|266:1|267:1|268:1|269:1|270:1|271:0|272:1|278:0|281:1|282:0|283:1|284:1|285:0|286:1|287:1|288:1|290:0|291:0|293:0|302:1|303:1|304:0|305:0|306:0| -stringPrefs "3:7;default|151:0;|212:4;0.01|223:332; ����!==> process 9840 launched child process 5640 ("Z:\task_1515675988\build\application\firefox\firefox.exe" -contentproc --channel="9840.20.682582491\409746544" -greomni "Z:\task_1515675988\build\application\firefox\omni.ja" -appomni "Z:\task_1515675988\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1515675988\build\application\firefox\browser"  "C:\Users\GenericWorker\AppData\LocalLow\Mozilla\Temp-{2adce473-6345-4e3c-b151-e84fcfa4df6c}" 9840 "\\.\pipe\gecko-crash-server-pipe.9840" gpu)
13:33:29     INFO -  zombiecheck | Checking for orphan process with PID: 8264
13:33:29     INFO -  zombiecheck | Checking for orphan process with PID: 7228
13:33:29     INFO -  mozcrash Downloading symbols from: https://queue.taskcluster.net/v1/task/LpydijnDTMCzG783KyYMZQ/artifacts/public/build/target.crashreporter-symbols.zip
13:33:33     INFO -  mozcrash Copy/paste: Z:\task_1515675988\build\win32-minidump_stackwalk.exe c:\users\genericworker\appdata\local\temp\tmprwozvk.mozrunner\minidumps\1619b6b9-b8b9-4077-9cc4-4613773f606b.dmp c:\users\genericworker\appdata\local\temp\tmpfxj4fc
13:33:41     INFO -  mozcrash Saved minidump as Z:\task_1515675988\build\blobber_upload_dir\1619b6b9-b8b9-4077-9cc4-4613773f606b.dmp
13:33:41  WARNING -  PROCESS-CRASH | dom/canvas/test/webgl-conf/generated/test_2_conformance__textures__misc__tex-image-and-sub-image-2d-with-array-buffer-view.html | application crashed [None]
13:33:41     INFO -  Crash dump filename: c:\users\genericworker\appdata\local\temp\tmprwozvk.mozrunner\minidumps\1619b6b9-b8b9-4077-9cc4-4613773f606b.dmp
13:33:41     INFO -  Operating system: Windows NT
13:33:41     INFO -                    10.0.15063
13:33:41     INFO -  CPU: amd64
13:33:41     INFO -       family 6 model 45 stepping 7
13:33:41     INFO -       8 CPUs
13:33:41     INFO -  GPU: UNKNOWN
13:33:41     INFO -  No crash
13:33:41     INFO -  Process uptime: 629 seconds
13:33:41     INFO -  Thread 0
13:33:41     INFO -   0  ntdll.dll!NtWaitForMultipleObjects + 0x14
13:33:41     INFO -      rax = 0x00000000000000f0   rdx = 0x0000000000000000
13:33:41     INFO -      rcx = 0x000000a5c11fea50   rbx = 0x0000000000000001
13:33:41     INFO -      rsi = 0x0000000000000000   rdi = 0x0000000000000001
13:33:41     INFO -      rbp = 0x000002957bc5c6e0   rsp = 0x000000a5c11fe6e8
13:33:41     INFO -       r8 = 0x000002950b133b00    r9 = 0x000000a5c11fec20
13:33:41     INFO -      r10 = 0x000002950750428c   r11 = 0x00007ffe98ed94d0
13:33:41     INFO -      r12 = 0x00000000ffffffff   r13 = 0x000000a5c11fea30
13:33:41     INFO -      r14 = 0x0000000000000000   r15 = 0x0000000000000000
13:33:41     INFO -      rip = 0x00007ffec9925ef4
13:33:41     INFO -      Found by: given as instruction pointer in context
13:33:41     INFO -   1  KERNELBASE.dll!SbCleanupTrace + 0x28
13:33:41     INFO -      rbx = 0x0000000000000001   rbp = 0x000002957bc5c6e0
13:33:41     INFO -      rsp = 0x000000a5c11fe6f0   r12 = 0x00000000ffffffff
13:33:41     INFO -      r13 = 0x000000a5c11fea30   r14 = 0x0000000000000000
13:33:41     INFO -      r15 = 0x0000000000000000   rip = 0x00007ffec6b0dd20
13:33:41     INFO -      Found by: call frame info
13:33:41     INFO -   2  0x8
13:33:41     INFO -      rbx = 0x0000000000000001   rbp = 0x000002957bc5c6e0
13:33:41     INFO -      rsp = 0x000000a5c11fe720   r12 = 0x00000000ffffffff
13:33:41     INFO -      r13 = 0x000000a5c11fea30   r14 = 0x0000000000000000
13:33:41     INFO -      r15 = 0x0000000000000000   rip = 0x0000000000000008
13:33:41     INFO -      Found by: call frame info
13:33:41     INFO -   3  xul.dll!js::Call(JSContext *,JS::Handle<JS::Value>,JS::Handle<JS::Value>,js::AnyInvokeArgs const &,JS::MutableHandle<JS::Value>) [Interpreter.cpp:59a7ff5e790d : 541 + 0x8]
13:33:41     INFO -      rbp = 0x000002957bc5c6e0   rsp = 0x000000a5c11fe750
13:33:41     INFO -      rip = 0x00007ffe98127425
13:33:41     INFO -      Found by: stack scanning
13:33:41     INFO -  Thread 1
> 13:33:41     INFO -  No crash
> 13:33:41     INFO -  Process uptime: 629 seconds
> 13:33:41     INFO -  Thread 0
> 13:33:41     INFO -   0  ntdll.dll!NtWaitForMultipleObjects + 0x14
> 13:33:41     INFO -      rax = 0x00000000000000f0   rdx = 0x0000000000000000
> 13:33:41     INFO -      rcx = 0x000000a5c11fea50   rbx = 0x0000000000000001
> 13:33:41     INFO -      rsi = 0x0000000000000000   rdi = 0x0000000000000001
> 13:33:41     INFO -      rbp = 0x000002957bc5c6e0   rsp = 0x000000a5c11fe6e8
> 13:33:41     INFO -       r8 = 0x000002950b133b00    r9 = 0x000000a5c11fec20
> 13:33:41     INFO -      r10 = 0x000002950750428c   r11 = 0x00007ffe98ed94d0
> 13:33:41     INFO -      r12 = 0x00000000ffffffff   r13 = 0x000000a5c11fea30
> 13:33:41     INFO -      r14 = 0x0000000000000000   r15 = 0x0000000000000000
> 13:33:41     INFO -      rip = 0x00007ffec9925ef4
> 13:33:41     INFO -      Found by: given as instruction pointer in context
> 13:33:41     INFO -   1  KERNELBASE.dll!SbCleanupTrace + 0x28
> 13:33:41     INFO -      rbx = 0x0000000000000001   rbp = 0x000002957bc5c6e0
> 13:33:41     INFO -      rsp = 0x000000a5c11fe6f0   r12 = 0x00000000ffffffff
> 13:33:41     INFO -      r13 = 0x000000a5c11fea30   r14 = 0x0000000000000000
> 13:33:41     INFO -      r15 = 0x0000000000000000   rip = 0x00007ffec6b0dd20
> 13:33:41     INFO -      Found by: call frame info
> 13:33:41     INFO -   2  0x8
> 13:33:41     INFO -      rbx = 0x0000000000000001   rbp = 0x000002957bc5c6e0
> 13:33:41     INFO -      rsp = 0x000000a5c11fe720   r12 = 0x00000000ffffffff
> 13:33:41     INFO -      r13 = 0x000000a5c11fea30   r14 = 0x0000000000000000
> 13:33:41     INFO -      r15 = 0x0000000000000000   rip = 0x0000000000000008
> 13:33:41     INFO -      Found by: call frame info
> 13:33:41     INFO -   3  xul.dll!js::Call(JSContext *,JS::Handle<JS::Value>,JS::Handle<JS::Value>,js::AnyInvokeArgs const &,JS::MutableHandle<JS::Value>) [Interpreter.cpp:59a7ff5e790d : 541 + 0x8]
> 13:33:41     INFO -      rbp = 0x000002957bc5c6e0   rsp = 0x000000a5c11fe750
> 13:33:41     INFO -      rip = 0x00007ffe98127425
> 13:33:41     INFO -      Found by: stack scanning

The stacktrace is probably corrupted because rip = 0x0000000000000008, which is unlikely to have avoided a segfault.
Whiteboard: [gfx-noted]
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.