Closed Bug 1827278 Opened 2 years ago Closed 2 years ago

Intermittent [tier 2] dom/canvas/test/webgl-conf/generated/test_2_conformance2__textures__canvas__tex-3d-rgb8ui-rgb_integer-unsigned_byte.html | single tracking bug

Categories

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

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, intermittent-testcase)

Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=411865504&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/fVIE-c2sTe2Fa-mxm_Nc4g/runs/0/artifacts/public/logs/live_backing.log


[task 2023-04-10T19:37:49.871Z] 19:37:49     INFO - TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__textures__canvas__tex-3d-rgb8ui-rgb_integer-unsigned_byte.html | Reason cannot be empty 
[task 2023-04-10T19:37:49.871Z] 19:37:49     INFO - Buffered messages finished
[task 2023-04-10T19:37:49.872Z] 19:37:49    ERROR - TEST-UNEXPECTED-TIMEOUT | dom/canvas/test/webgl-conf/generated/test_2_conformance2__textures__canvas__tex-3d-rgb8ui-rgb_integer-unsigned_byte.html | application timed out after 370 seconds with no output
[task 2023-04-10T19:37:49.872Z] 19:37:49  WARNING - Force-terminating active process(es).
[task 2023-04-10T19:37:49.873Z] 19:37:49     INFO - Determining child pids from psutil...
[task 2023-04-10T19:37:49.874Z] 19:37:49     INFO - [5588]
[task 2023-04-10T19:37:49.875Z] 19:37:49     INFO - ==> process 5588 launched child process 9704 ("Z:\task_168115264731673\build\application\firefox\firefox.exe" -contentproc --channel="5588.0.1846557357\284973676" -parentBuildID 20230410174346 -prefsHandle 2192 -prefMapHandle 2184 -prefsLen 21766 -prefMapSize 251912 -appDir "Z:\task_168115264731673\build\application\firefox\browser" - {180d342f-5420-4479-8166-f00b6e2f6b17} 5588 "\\.\pipe\gecko-crash-server-pipe.5588" 2272 22500bf1658 gpu)
[task 2023-04-10T19:37:49.877Z] 19:37:49     INFO - ==> process 5588 launched child process 10024 ("Z:\task_168115264731673\build\application\firefox\firefox.exe" -contentproc --channel="5588.1.1619584001\433337150" -childID 1 -isForBrowser -prefsHandle 2884 -prefMapHandle 2868 -prefsLen 21554 -prefMapSize 251912 -jsInitHandle 1312 -jsInitLen 247672 -parentBuildID 20230410174346 -win32kLockedDown -appDir "Z:\task_168115264731673\build\application\firefox\browser" - {72f17741-0371-4e0f-8ade-9216908e7ed4} 5588 "\\.\pipe\gecko-crash-server-pipe.5588" 2892 225048b4558 tab)
[task 2023-04-10T19:37:49.878Z] 19:37:49     INFO - ==> process 5588 launched child process 10004 ("Z:\task_168115264731673\build\application\firefox\firefox.exe" -contentproc --channel="5588.2.1458067541\1408403219" -childID 2 -isForBrowser -prefsHandle 3352 -prefMapHandle 3348 -prefsLen 23129 -prefMapSize 251912 -jsInitHandle 1312 -jsInitLen 247672 -parentBuildID 20230410174346 -win32kLockedDown -appDir "Z:\task_168115264731673\build\application\firefox\browser" - {986d3368-5b13-4568-8a79-155787f017fa} 5588 "\\.\pipe\gecko-crash-server-pipe.5588" 3364 22572442158 tab)
[task 2023-04-10T19:37:49.879Z] 19:37:49     INFO - ==> process 5588 launched child process 1512 ("Z:\task_168115264731673\build\application\firefox\firefox.exe" -contentproc --channel="5588.3.111353559\548949511" -childID 3 -isForBrowser -prefsHandle 3580 -prefMapHandle 3572 -prefsLen 22751 -prefMapSize 251912 -jsInitHandle 1312 -jsInitLen 247672 -parentBuildID 20230410174346 -win32kLockedDown -appDir "Z:\task_168115264731673\build\application\firefox\browser" - {4ac9c197-2152-451e-a2f8-a92fd3a8b993} 5588 "\\.\pipe\gecko-crash-server-pipe.5588" 3592 22507373e58 tab)
[task 2023-04-10T19:37:49.880Z] 19:37:49     INFO - ==> process 5588 launched child process 7516 ("Z:\task_168115264731673\build\application\firefox\firefox.exe" -contentproc --channel="5588.4.763445815\2105390783" -childID 4 -isForBrowser -prefsHandle 3916 -prefMapHandle 3900 -prefsLen 22914 -prefMapSize 251912 -jsInitHandle 1312 -jsInitLen 247672 -parentBuildID 20230410174346 -win32kLockedDown -appDir "Z:\task_168115264731673\build\application\firefox\browser" - {a21d6b71-4873-45ae-b397-81350bfb469d} 5588 "\\.\pipe\gecko-crash-server-pipe.5588" 3052 225055a0558 tab)
[task 2023-04-10T19:37:49.881Z] 19:37:49     INFO - ==> process 5588 launched child process 7612 ("Z:\task_168115264731673\build\application\firefox\firefox.exe" -contentproc --channel="5588.5.779129015\2115753272" -childID 5 -isForBrowser -prefsHandle 3052 -prefMapHandle 4044 -prefsLen 22979 -prefMapSize 251912 -jsInitHandle 1312 -jsInitLen 247672 -parentBuildID 20230410174346 -win32kLockedDown -appDir "Z:\task_168115264731673\build\application\firefox\browser" - {50c9d747-c471-4e75-a6c1-f37cb44c5345} 5588 "\\.\pipe\gecko-crash-server-pipe.5588" 4032 2250794a358 tab)
[task 2023-04-10T19:37:49.882Z] 19:37:49     INFO - ==> process 5588 launched child process 9712 ("Z:\task_168115264731673\build\application\firefox\firefox.exe" -contentproc --channel="5588.6.326452346\818958003" -childID 6 -isForBrowser -prefsHandle 4116 -prefMapHandle 4120 -prefsLen 22979 -prefMapSize 251912 -jsInitHandle 1312 -jsInitLen 247672 -parentBuildID 20230410174346 -win32kLockedDown -appDir "Z:\task_168115264731673\build\application\firefox\browser" - {d55ba4ca-88b0-4ca8-9a5e-044a2adc94e8} 5588 "\\.\pipe\gecko-crash-server-pipe.5588" 4104 2250794b858 tab)
[task 2023-04-10T19:37:49.883Z] 19:37:49     INFO - ==> process 5588 launched child process 8288 ("Z:\task_168115264731673\build\application\firefox\firefox.exe" -contentproc --channel="5588.7.263063690\1320936067" -parentBuildID 20230410174346 -sandboxingKind 0 -prefsHandle 4560 -prefMapHandle 4564 -prefsLen 30421 -prefMapSize 251912 -win32kLockedDown -appDir "Z:\task_168115264731673\build\application\firefox\browser" - {6c754b6d-a205-44bc-9536-7bd0fab9e86a} 5588 "\\.\pipe\gecko-crash-server-pipe.5588" 4716 2250a56de58 utility)
[task 2023-04-10T19:37:49.884Z] 19:37:49     INFO - ==> process 5588 launched child process 8156 ("Z:\task_168115264731673\build\application\firefox\firefox.exe" -contentproc --channel="5588.8.452942310\1378250811" -childID 7 -isForBrowser -prefsHandle 4176 -prefMapHandle 4140 -prefsLen 30421 -prefMapSize 251912 -jsInitHandle 1312 -jsInitLen 247672 -parentBuildID 20230410174346 -win32kLockedDown -appDir "Z:\task_168115264731673\build\application\firefox\browser" - {a95a2266-fa56-4796-9f3c-1aa75f0d36fc} 5588 "\\.\pipe\gecko-crash-server-pipe.5588" 4836 2250a56d858 tab)
[task 2023-04-10T19:37:49.885Z] 19:37:49     INFO - ==> process 5588 launched child process 6200 ("Z:\task_168115264731673\build\application\firefox\firefox.exe" -contentproc --channel="5588.9.751529385\644708249" -childID 8 -isForBrowser -prefsHandle 5524 -prefMapHandle 5520 -prefsLen 28601 -prefMapSize 251912 -jsInitHandle 1312 -jsInitLen 247672 -parentBuildID 20230410174346 -win32kLockedDown -appDir "Z:\task_168115264731673\build\application\firefox\browser" - {7eae6111-3056-42be-8e3e-151c3298a32f} 5588 "\\.\pipe\gecko-crash-server-pipe.5588" 5412 2250d0dda58 tab)
[task 2023-04-10T19:37:49.885Z] 19:37:49     INFO - Found child pids: {8288, 9704, 10024, 1512, 8156, 9712, 5588, 10004, 6200, 7612, 7516}
[task 2023-04-10T19:37:49.886Z] 19:37:49     INFO - Failed to get child procs
[task 2023-04-10T19:37:49.886Z] 19:37:49     INFO - Killing process: 8288
[task 2023-04-10T19:37:49.886Z] 19:37:49     INFO - TEST-INFO | started process screenshot
[task 2023-04-10T19:37:50.130Z] 19:37:50     INFO - TEST-INFO | screenshot: exit 0
[task 2023-04-10T19:37:50.130Z] 19:37:50     INFO - mozcrash Writing a dump to C:\Users\task_168115264731673\AppData\Local\Temp\tmps6aznwhn.mozrunner\minidumps\f318c7ff-dce4-4ada-b737-4a621f561ea1.dmp for [8288]
[task 2023-04-10T19:37:50.188Z] 19:37:50     INFO - Killing process: 9704
[task 2023-04-10T19:37:50.189Z] 19:37:50     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-04-10T19:37:50.190Z] 19:37:50     INFO - mozcrash Writing a dump to C:\Users\task_168115264731673\AppData\Local\Temp\tmps6aznwhn.mozrunner\minidumps\1e4f0de3-90c2-4fb8-a7ae-5e676fac0049.dmp for [9704]
[task 2023-04-10T19:37:50.454Z] 19:37:50     INFO - Killing process: 10024
[task 2023-04-10T19:37:50.455Z] 19:37:50     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-04-10T19:37:50.455Z] 19:37:50     INFO - mozcrash Writing a dump to C:\Users\task_168115264731673\AppData\Local\Temp\tmps6aznwhn.mozrunner\minidumps\f9dcc1b1-d0f4-4a9e-a917-503dc4b0ad00.dmp for [10024]
[task 2023-04-10T19:37:50.456Z] 19:37:50  WARNING - mozcrash unable to get handle for pid 10024: 87
[task 2023-04-10T19:37:50.456Z] 19:37:50  WARNING - mozcrash kill_pid(): unable to get handle for pid 10024: 87
[task 2023-04-10T19:37:50.457Z] 19:37:50     INFO - Killing process: 1512
[task 2023-04-10T19:37:50.457Z] 19:37:50     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-04-10T19:37:50.458Z] 19:37:50     INFO - mozcrash Writing a dump to C:\Users\task_168115264731673\AppData\Local\Temp\tmps6aznwhn.mozrunner\minidumps\70a33535-ad4b-45c3-978e-2b88704bd9e6.dmp for [1512]
[task 2023-04-10T19:37:50.458Z] 19:37:50  WARNING - mozcrash unable to get handle for pid 1512: 87
[task 2023-04-10T19:37:50.458Z] 19:37:50  WARNING - mozcrash kill_pid(): unable to get handle for pid 1512: 87
[task 2023-04-10T19:37:50.459Z] 19:37:50     INFO - Killing process: 8156
[task 2023-04-10T19:37:50.460Z] 19:37:50     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-04-10T19:37:50.460Z] 19:37:50     INFO - mozcrash Writing a dump to C:\Users\task_168115264731673\AppData\Local\Temp\tmps6aznwhn.mozrunner\minidumps\a936c24d-cc6e-4dfd-b5e9-a35be6516a53.dmp for [8156]
[task 2023-04-10T19:37:50.527Z] 19:37:50     INFO - Killing process: 9712
[task 2023-04-10T19:37:50.528Z] 19:37:50     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-04-10T19:37:50.528Z] 19:37:50     INFO - mozcrash Writing a dump to C:\Users\task_168115264731673\AppData\Local\Temp\tmps6aznwhn.mozrunner\minidumps\edb5f3b2-4248-4588-8f90-6ac8e4fe4b97.dmp for [9712]
[task 2023-04-10T19:37:50.584Z] 19:37:50     INFO - Killing process: 5588
[task 2023-04-10T19:37:50.584Z] 19:37:50     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-04-10T19:37:50.585Z] 19:37:50     INFO - mozcrash Writing a dump to C:\Users\task_168115264731673\AppData\Local\Temp\tmps6aznwhn.mozrunner\minidumps\c3aedf9e-c079-49a0-bbf9-f107ac3b0d72.dmp for [5588]
[task 2023-04-10T19:37:50.897Z] 19:37:50     INFO - Killing process: 10004
[task 2023-04-10T19:37:50.897Z] 19:37:50     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-04-10T19:37:50.898Z] 19:37:50     INFO - mozcrash Writing a dump to C:\Users\task_168115264731673\AppData\Local\Temp\tmps6aznwhn.mozrunner\minidumps\a5cd71ac-7196-44b0-9b46-bb7dcd112624.dmp for [10004]
[task 2023-04-10T19:37:50.898Z] 19:37:50  WARNING - mozcrash unable to get handle for pid 10004: 87
[task 2023-04-10T19:37:50.900Z] 19:37:50  WARNING - mozcrash kill_pid(): unable to get handle for pid 10004: 87
[task 2023-04-10T19:37:50.901Z] 19:37:50     INFO - Killing process: 6200
[task 2023-04-10T19:37:50.902Z] 19:37:50     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-04-10T19:37:50.903Z] 19:37:50     INFO - mozcrash Writing a dump to C:\Users\task_168115264731673\AppData\Local\Temp\tmps6aznwhn.mozrunner\minidumps\3706f13a-18ec-4014-bd6d-57683dbf8880.dmp for [6200]
[task 2023-04-10T19:37:50.904Z] 19:37:50  WARNING - mozcrash unable to dump minidump file for pid 6200: 1
[task 2023-04-10T19:37:50.904Z] 19:37:50  WARNING - mozcrash kill_pid(): unable to terminate pid 6200: 5
[task 2023-04-10T19:37:50.905Z] 19:37:50     INFO - Killing process: 7612
[task 2023-04-10T19:37:50.906Z] 19:37:50     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-04-10T19:37:50.906Z] 19:37:50     INFO - mozcrash Writing a dump to C:\Users\task_168115264731673\AppData\Local\Temp\tmps6aznwhn.mozrunner\minidumps\8aa09825-800d-400c-a29c-b5210fe06bfa.dmp for [7612]
[task 2023-04-10T19:37:50.907Z] 19:37:50  WARNING - mozcrash unable to get handle for pid 7612: 87
[task 2023-04-10T19:37:50.907Z] 19:37:50  WARNING - mozcrash kill_pid(): unable to get handle for pid 7612: 87
[task 2023-04-10T19:37:50.908Z] 19:37:50     INFO - Killing process: 7516
[task 2023-04-10T19:37:50.908Z] 19:37:50     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-04-10T19:37:50.909Z] 19:37:50     INFO - mozcrash Writing a dump to C:\Users\task_168115264731673\AppData\Local\Temp\tmps6aznwhn.mozrunner\minidumps\c84c113c-a709-4194-85dd-05d34e25e851.dmp for [7516]
[task 2023-04-10T19:37:50.910Z] 19:37:50  WARNING - mozcrash unable to get handle for pid 7516: 87
[task 2023-04-10T19:37:50.910Z] 19:37:50  WARNING - mozcrash kill_pid(): unable to get handle for pid 7516: 87
[task 2023-04-10T19:37:50.910Z] 19:37:50     INFO - Killing process: 9512
[task 2023-04-10T19:37:50.911Z] 19:37:50     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-04-10T19:37:50.911Z] 19:37:50     INFO - mozcrash Writing a dump to C:\Users\task_168115264731673\AppData\Local\Temp\tmps6aznwhn.mozrunner\minidumps\a86b1994-5ed0-477b-a5c0-19674dcbb240.dmp for [9512]
[task 2023-04-10T19:37:50.912Z] 19:37:50  WARNING - mozcrash unable to dump minidump file for pid 9512: 1
[task 2023-04-10T19:37:50.912Z] 19:37:50  WARNING - mozcrash kill_pid(): unable to terminate pid 9512: 5
[task 2023-04-10T19:37:50.913Z] 19:37:50     INFO - psutil found pid 9512 dead
[task 2023-04-10T19:37:50.953Z] 19:37:50     INFO - TEST-INFO | Main app process: exit 1
[task 2023-04-10T19:37:50.953Z] 19:37:50     INFO - Buffered messages finished
[task 2023-04-10T19:37:50.954Z] 19:37:50    ERROR - TEST-UNEXPECTED-FAIL | dom/canvas/test/webgl-conf/generated/test_2_conformance2__textures__canvas__tex-3d-rgb8ui-rgb_integer-unsigned_byte.html | application terminated with exit code 1
[task 2023-04-10T19:37:50.955Z] 19:37:50     INFO - runtests.py | Application ran for: 0:09:08.679356
[task 2023-04-10T19:37:50.956Z] 19:37:50     INFO - zombiecheck | Reading PID log: C:\Users\task_168115264731673\AppData\Local\Temp\tmpgp3ymu6hpidlog
[task 2023-04-10T19:37:50.956Z] 19:37:50     INFO - ==> process 5588 launched child process 9704 ("Z:\task_168115264731673\build\application\firefox\firefox.exe" -contentproc --channel="5588.0.1846557357\284973676" -parentBuildID 20230410174346 -prefsHandle 2192 -prefMapHandle 2184 -prefsLen 21766 -prefMapSize 251912 -appDir "Z:\task_168115264731673\build\application\firefox\browser" - {180d342f-5420-4479-8166-f00b6e2f6b17} 5588 "\\.\pipe\gecko-crash-server-pipe.5588" 2272 22500bf1658 gpu)
[task 2023-04-10T19:37:50.958Z] 19:37:50     INFO - ==> process 5588 launched child process 10024 ("Z:\task_168115264731673\build\application\firefox\firefox.exe" -contentproc --channel="5588.1.1619584001\433337150" -childID 1 -isForBrowser -prefsHandle 2884 -prefMapHandle 2868 -prefsLen 21554 -prefMapSize 251912 -jsInitHandle 1312 -jsInitLen 247672 -parentBuildID 20230410174346 -win32kLockedDown -appDir "Z:\task_168115264731673\build\application\firefox\browser" - {72f17741-0371-4e0f-8ade-9216908e7ed4} 5588 "\\.\pipe\gecko-crash-server-pipe.5588" 2892 225048b4558 tab)
[task 2023-04-10T19:37:50.959Z] 19:37:50     INFO - ==> process 5588 launched child process 10004 ("Z:\task_168115264731673\build\application\firefox\firefox.exe" -contentproc --channel="5588.2.1458067541\1408403219" -childID 2 -isForBrowser -prefsHandle 3352 -prefMapHandle 3348 -prefsLen 23129 -prefMapSize 251912 -jsInitHandle 1312 -jsInitLen 247672 -parentBuildID 20230410174346 -win32kLockedDown -appDir "Z:\task_168115264731673\build\application\firefox\browser" - {986d3368-5b13-4568-8a79-155787f017fa} 5588 "\\.\pipe\gecko-crash-server-pipe.5588" 3364 22572442158 tab)
[task 2023-04-10T19:37:50.960Z] 19:37:50     INFO - ==> process 5588 launched child process 1512 ("Z:\task_168115264731673\build\application\firefox\firefox.exe" -contentproc --channel="5588.3.111353559\548949511" -childID 3 -isForBrowser -prefsHandle 3580 -prefMapHandle 3572 -prefsLen 22751 -prefMapSize 251912 -jsInitHandle 1312 -jsInitLen 247672 -parentBuildID 20230410174346 -win32kLockedDown -appDir "Z:\task_168115264731673\build\application\firefox\browser" - {4ac9c197-2152-451e-a2f8-a92fd3a8b993} 5588 "\\.\pipe\gecko-crash-server-pipe.5588" 3592 22507373e58 tab)
[task 2023-04-10T19:37:50.961Z] 19:37:50     INFO - ==> process 5588 launched child process 7516 ("Z:\task_168115264731673\build\application\firefox\firefox.exe" -contentproc --channel="5588.4.763445815\2105390783" -childID 4 -isForBrowser -prefsHandle 3916 -prefMapHandle 3900 -prefsLen 22914 -prefMapSize 251912 -jsInitHandle 1312 -jsInitLen 247672 -parentBuildID 20230410174346 -win32kLockedDown -appDir "Z:\task_168115264731673\build\application\firefox\browser" - {a21d6b71-4873-45ae-b397-81350bfb469d} 5588 "\\.\pipe\gecko-crash-server-pipe.5588" 3052 225055a0558 tab)
[task 2023-04-10T19:37:50.962Z] 19:37:50     INFO - ==> process 5588 launched child process 7612 ("Z:\task_168115264731673\build\application\firefox\firefox.exe" -contentproc --channel="5588.5.779129015\2115753272" -childID 5 -isForBrowser -prefsHandle 3052 -prefMapHandle 4044 -prefsLen 22979 -prefMapSize 251912 -jsInitHandle 1312 -jsInitLen 247672 -parentBuildID 20230410174346 -win32kLockedDown -appDir "Z:\task_168115264731673\build\application\firefox\browser" - {50c9d747-c471-4e75-a6c1-f37cb44c5345} 5588 "\\.\pipe\gecko-crash-server-pipe.5588" 4032 2250794a358 tab)
[task 2023-04-10T19:37:50.963Z] 19:37:50     INFO - ==> process 5588 launched child process 9712 ("Z:\task_168115264731673\build\application\firefox\firefox.exe" -contentproc --channel="5588.6.326452346\818958003" -childID 6 -isForBrowser -prefsHandle 4116 -prefMapHandle 4120 -prefsLen 22979 -prefMapSize 251912 -jsInitHandle 1312 -jsInitLen 247672 -parentBuildID 20230410174346 -win32kLockedDown -appDir "Z:\task_168115264731673\build\application\firefox\browser" - {d55ba4ca-88b0-4ca8-9a5e-044a2adc94e8} 5588 "\\.\pipe\gecko-crash-server-pipe.5588" 4104 2250794b858 tab)
[task 2023-04-10T19:37:50.964Z] 19:37:50     INFO - ==> process 5588 launched child process 8288 ("Z:\task_168115264731673\build\application\firefox\firefox.exe" -contentproc --channel="5588.7.263063690\1320936067" -parentBuildID 20230410174346 -sandboxingKind 0 -prefsHandle 4560 -prefMapHandle 4564 -prefsLen 30421 -prefMapSize 251912 -win32kLockedDown -appDir "Z:\task_168115264731673\build\application\firefox\browser" - {6c754b6d-a205-44bc-9536-7bd0fab9e86a} 5588 "\\.\pipe\gecko-crash-server-pipe.5588" 4716 2250a56de58 utility)
[task 2023-04-10T19:37:50.965Z] 19:37:50     INFO - ==> process 5588 launched child process 8156 ("Z:\task_168115264731673\build\application\firefox\firefox.exe" -contentproc --channel="5588.8.452942310\1378250811" -childID 7 -isForBrowser -prefsHandle 4176 -prefMapHandle 4140 -prefsLen 30421 -prefMapSize 251912 -jsInitHandle 1312 -jsInitLen 247672 -parentBuildID 20230410174346 -win32kLockedDown -appDir "Z:\task_168115264731673\build\application\firefox\browser" - {a95a2266-fa56-4796-9f3c-1aa75f0d36fc} 5588 "\\.\pipe\gecko-crash-server-pipe.5588" 4836 2250a56d858 tab)
[task 2023-04-10T19:37:50.967Z] 19:37:50     INFO - ==> process 5588 launched child process 6200 ("Z:\task_168115264731673\build\application\firefox\firefox.exe" -contentproc --channel="5588.9.751529385\644708249" -childID 8 -isForBrowser -prefsHandle 5524 -prefMapHandle 5520 -prefsLen 28601 -prefMapSize 251912 -jsInitHandle 1312 -jsInitLen 247672 -parentBuildID 20230410174346 -win32kLockedDown -appDir "Z:\task_168115264731673\build\application\firefox\browser" - {7eae6111-3056-42be-8e3e-151c3298a32f} 5588 "\\.\pipe\gecko-crash-server-pipe.5588" 5412 2250d0dda58 tab)
[task 2023-04-10T19:37:50.967Z] 19:37:50     INFO - ==> process 5588 launched child process 2684 ("Z:\task_168115264731673\build\application\firefox\firefox.exe" -contentproc --channel="5588.10.259101989\1654151175" -parentBuildID 20230410174346 -prefsHandle 2508 -prefMapHandle 3784 -prefsLen 32165 -prefMapSize 251912 -appDir "Z:\task_168115264731673\build\application\firefox\browser" - {da535d10-17e6-44c2-a1e4-20d5e35de142} 5588 "\\.\pipe\gecko-crash-server-pipe.5588" 2732 22500bec958 gpu)
[task 2023-04-10T19:37:50.968Z] 19:37:50     INFO - zombiecheck | Checking for orphan process with PID: 8288
[task 2023-04-10T19:37:50.968Z] 19:37:50     INFO - zombiecheck | Checking for orphan process with PID: 2684
[task 2023-04-10T19:37:50.969Z] 19:37:50     INFO - zombiecheck | Checking for orphan process with PID: 9704
[task 2023-04-10T19:37:50.969Z] 19:37:50     INFO - zombiecheck | Checking for orphan process with PID: 10024
[task 2023-04-10T19:37:50.969Z] 19:37:50     INFO - zombiecheck | Checking for orphan process with PID: 1512
[task 2023-04-10T19:37:50.970Z] 19:37:50     INFO - zombiecheck | Checking for orphan process with PID: 8156
[task 2023-04-10T19:37:50.970Z] 19:37:50     INFO - zombiecheck | Checking for orphan process with PID: 9712
[task 2023-04-10T19:37:50.971Z] 19:37:50     INFO - zombiecheck | Checking for orphan process with PID: 10004
[task 2023-04-10T19:37:50.971Z] 19:37:50     INFO - zombiecheck | Checking for orphan process with PID: 6200
[task 2023-04-10T19:37:50.972Z] 19:37:50     INFO - zombiecheck | Checking for orphan process with PID: 7612
[task 2023-04-10T19:37:50.972Z] 19:37:50     INFO - zombiecheck | Checking for orphan process with PID: 7516
[task 2023-04-10T19:37:50.973Z] 19:37:50     INFO - mozcrash Downloading symbols from: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/TiSpmG3hRWC-9kQnz3mXPQ/artifacts/public/build/target.crashreporter-symbols.zip
[task 2023-04-10T19:38:39.725Z] 19:38:39     INFO - mozcrash Copy/paste: Z:/task_168115264731673/fetches\minidump-stackwalk\minidump-stackwalk.exe --symbols-url=https://symbols.mozilla.org/ --cyborg=C:\Users\task_168115264731673\AppData\Local\Temp\tmp84tblktr\f318c7ff-dce4-4ada-b737-4a621f561ea1.trace C:\Users\task_168115264731673\AppData\Local\Temp\tmps6aznwhn.mozrunner\minidumps\f318c7ff-dce4-4ada-b737-4a621f561ea1.dmp C:\Users\task_168115264731673\AppData\Local\Temp\tmpu5yvix9l
[task 2023-04-10T19:38:54.910Z] 19:38:54     INFO -  Traceback (most recent call last):
[task 2023-04-10T19:38:54.911Z] 19:38:54     INFO -    File "Z:\task_168115264731673\build\tests\mochitest\runtests.py", line 3697, in doTests
[task 2023-04-10T19:38:54.911Z] 19:38:54     INFO -      ret, _ = self.runApp(
[task 2023-04-10T19:38:54.911Z] 19:38:54     INFO -    File "Z:\task_168115264731673\build\tests\mochitest\runtests.py", line 2966, in runApp
[task 2023-04-10T19:38:54.912Z] 19:38:54     INFO -      crash_count = mozcrash.log_crashes(
[task 2023-04-10T19:38:54.912Z] 19:38:54     INFO -    File "Z:\task_168115264731673\build\venv\lib\site-packages\mozcrash\mozcrash.py", line 165, in log_crashes
[task 2023-04-10T19:38:54.913Z] 19:38:54     INFO -      for info in CrashInfo(
[task 2023-04-10T19:38:54.913Z] 19:38:54     INFO -    File "Z:\task_168115264731673\build\venv\lib\site-packages\mozcrash\mozcrash.py", line 326, in __iter__
[task 2023-04-10T19:38:54.913Z] 19:38:54     INFO -      rv = self._process_dump_file(path, extra)
[task 2023-04-10T19:38:54.913Z] 19:38:54     INFO -    File "Z:\task_168115264731673\build\venv\lib\site-packages\mozcrash\mozcrash.py", line 401, in _process_dump_file
[task 2023-04-10T19:38:54.914Z] 19:38:54     INFO -      pmatch = re.search(r"(.*)\(.*\)", signature)
[task 2023-04-10T19:38:54.914Z] 19:38:54     INFO -    File "c:\mozilla-build\python3\lib\re.py", line 201, in search
[task 2023-04-10T19:38:54.914Z] 19:38:54     INFO -      return _compile(pattern, flags).search(string)
[task 2023-04-10T19:38:54.915Z] 19:38:54     INFO -  TypeError: expected string or bytes-like object
[task 2023-04-10T19:38:54.915Z] 19:38:54    ERROR - Automation Error: Received unexpected exception while running application
[task 2023-04-10T19:38:54.915Z] 19:38:54    ERROR - 
[task 2023-04-10T19:38:54.915Z] 19:38:54     INFO - Stopping web server
[task 2023-04-10T19:38:54.916Z] 19:38:54     INFO - Server shut down.
[task 2023-04-10T19:38:54.927Z] 19:38:54     INFO - Web server killed.
[task 2023-04-10T19:38:54.928Z] 19:38:54     INFO - Stopping web socket server
[task 2023-04-10T19:38:54.958Z] 19:38:54     INFO - Stopping ssltunnel
[task 2023-04-10T19:38:54.989Z] 19:38:54  WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2023-04-10T19:38:54.990Z] 19:38:54     INFO - runtests.py | Running tests: end.
[task 2023-04-10T19:38:55.216Z] 19:38:55     INFO - Buffered messages finished
[task 2023-04-10T19:38:55.217Z] 19:38:55     INFO -  0 INFO TEST-START | Shutdown
[task 2023-04-10T19:38:55.217Z] 19:38:55     INFO -  1 INFO Passed:  0
[task 2023-04-10T19:38:55.217Z] 19:38:55     INFO -  2 INFO Failed:  0
[task 2023-04-10T19:38:55.218Z] 19:38:55     INFO -  3 INFO Todo:    0
[task 2023-04-10T19:38:55.218Z] 19:38:55     INFO -  4 INFO Mode:    e10s
[task 2023-04-10T19:38:55.218Z] 19:38:55     INFO -  5 INFO SimpleTest FINISHED
[task 2023-04-10T19:38:55.218Z] 19:38:55     INFO - Buffered messages finished
[task 2023-04-10T19:38:55.219Z] 19:38:55     INFO - SUITE-END | took 616s
[task 2023-04-10T19:38:55.269Z] 19:38:55     INFO - Return code: 1
[task 2023-04-10T19:38:55.270Z] 19:38:55     INFO - TinderboxPrint: mochitest-mochitest-webgl2-ext<br/>117/0/0
[task 2023-04-10T19:38:55.270Z] 19:38:55  WARNING - setting return code to 2
[task 2023-04-10T19:38:55.270Z] 19:38:55     INFO - The mochitest suite: mochitest-webgl2-ext ran with return status: FAILURE
[task 2023-04-10T19:38:55.270Z] 19:38:55     INFO - Running post-action listener: _package_coverage_data
[task 2023-04-10T19:38:55.270Z] 19:38:55     INFO - Running post-action listener: _resource_record_post_action
[task 2023-04-10T19:38:55.270Z] 19:38:55     INFO - Running post-action listener: process_java_coverage_data
[task 2023-04-10T19:38:55.270Z] 19:38:55     INFO - [mozharness: 2023-04-10 19:38:55.270731Z] Finished run-tests step (success)
[task 2023-04-10T19:38:55.270Z] 19:38:55     INFO - [mozharness: 2023-04-10 19:38:55.270731Z] Running uninstall step.
[task 2023-04-10T19:38:55.270Z] 19:38:55     INFO - Running pre-action listener: _resource_record_pre_action
[task 2023-04-10T19:38:55.271Z] 19:38:55     INFO - Running main action method: uninstall
[task 2023-04-10T19:38:55.271Z] 19:38:55     INFO - Skipping uninstall for non-MSIX test
[task 2023-04-10T19:38:55.271Z] 19:38:55     INFO - Running post-action listener: _resource_record_post_action
[task 2023-04-10T19:38:55.271Z] 19:38:55     INFO - [mozharness: 2023-04-10 19:38:55.271241Z] Finished uninstall step (success)
[task 2023-04-10T19:38:55.271Z] 19:38:55     INFO - Running post-run listener: _resource_record_post_run
[task 2023-04-10T19:38:55.447Z] 19:38:55     INFO - instance_metadata.json not found; unable to determine instance type
[task 2023-04-10T19:38:55.450Z] 19:38:55     INFO - Validating Perfherder data against Z:\task_168115264731673\mozharness\external_tools\performance-artifact-schema.json
[task 2023-04-10T19:38:55.453Z] 19:38:55     INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": "mochitest.mochitest-webgl2-ext.1.overall", "extraOptions": ["e10s", "buildbot-unknown"], "subtests": [{"name": "cpu_percent", "value": 14.957139265962788}, {"name": "io_write_bytes", "value": 2066769920}, {"name": "io.read_bytes", "value": 251317760}, {"name": "io_write_time", "value": 68}, {"name": "io_read_time", "value": 55}]}, {"name": "mochitest.mochitest-webgl2-ext.1.start-pulseaudio", "subtests": [{"name": "time", "value": 0.00052642822265625}, {"name": "cpu_percent", "value": 0}]}, {"name": "mochitest.mochitest-webgl2-ext.1.install", "subtests": [{"name": "time", "value": 2.9599199295043945}, {"name": "cpu_percent", "value": 20.66666666666667}]}, {"name": "mochitest.mochitest-webgl2-ext.1.stage-files", "subtests": [{"name": "time", "value": 0.0}, {"name": "cpu_percent", "value": 0}]}, {"name": "mochitest.mochitest-webgl2-ext.1.run-tests", "subtests": [{"name": "time", "value": 661.342524766922}, {"name": "cpu_percent", "value": 14.931426767676763}]}, {"name": "mochitest.mochitest-webgl2-ext.1.uninstall", "subtests": [{"name": "time", "value": 0.0005099773406982422}, {"name": "cpu_percent", "value": 0}]}]}
[task 2023-04-10T19:38:55.453Z] 19:38:55     INFO - Total resource usage - Wall time: 663s; CPU: Can't collect data; Read bytes: 251317760; Write bytes: 2066769920; Read time: 55; Write time: 68
[task 2023-04-10T19:38:55.453Z] 19:38:55     INFO - TinderboxPrint: I/O read bytes / time<br/>251,317,760 / 55
[task 2023-04-10T19:38:55.453Z] 19:38:55     INFO - TinderboxPrint: I/O write bytes / time<br/>2,066,769,920 / 68
[task 2023-04-10T19:38:55.453Z] 19:38:55     INFO - TinderboxPrint: CPU idle<br/>6,774.5 (85.0%)
[task 2023-04-10T19:38:55.453Z] 19:38:55     INFO - TinderboxPrint: CPU system<br/>431.1 (5.4%)
[task 2023-04-10T19:38:55.453Z] 19:38:55     INFO - TinderboxPrint: CPU user<br/>753.0 (9.4%)
[task 2023-04-10T19:38:55.453Z] 19:38:55     INFO - start-pulseaudio - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2023-04-10T19:38:55.454Z] 19:38:55     INFO - install - Wall time: 3s; CPU: 21%; Read bytes: 185856; Write bytes: 34959360; Read time: 0; Write time: 2
[task 2023-04-10T19:38:55.454Z] 19:38:55     INFO - stage-files - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2023-04-10T19:38:55.462Z] 19:38:55     INFO - run-tests - Wall time: 661s; CPU: 15%; Read bytes: 250841600; Write bytes: 2004621312; Read time: 55; Write time: 65
[task 2023-04-10T19:38:55.462Z] 19:38:55     INFO - uninstall - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2023-04-10T19:38:55.691Z] 19:38:55  WARNING - returning nonzero exit status 2
[taskcluster 2023-04-10T19:38:55.758Z]    Exit Code: 2
[taskcluster 2023-04-10T19:38:55.758Z]    User Time: 0s
[taskcluster 2023-04-10T19:38:55.758Z]  Kernel Time: 0s
[taskcluster 2023-04-10T19:38:55.758Z]    Wall Time: 15m55.2250588s
[taskcluster 2023-04-10T19:38:55.758Z]       Result: FAILED
[taskcluster 2023-04-10T19:38:55.758Z] === Task Finished ===
[taskcluster 2023-04-10T19:38:55.759Z] Task Duration: 15m55.2299621s
[taskcluster 2023-04-10T19:38:55.891Z] Uploading artifact public/logs/localconfig.json from file logs\localconfig.json with content encoding "gzip", mime type "application/json" and expiry 2024-04-09T17:47:21.062Z
[taskcluster 2023-04-10T19:38:56.380Z] Uploading artifact public/test_info/manifests.list from file build\blobber_upload_dir\manifests.list with content encoding "gzip", mime type "application/octet-stream" and expiry 2024-04-09T17:47:21.062Z
[taskcluster 2023-04-10T19:38:56.578Z] Uploading artifact public/test_info/mochitest-webgl2-ext_errorsummary.log from file build\blobber_upload_dir\mochitest-webgl2-ext_errorsummary.log with content encoding "gzip", mime type "text/plain" and expiry 2024-04-09T17:47:21.062Z
[taskcluster 2023-04-10T19:38:56.775Z] Uploading artifact public/test_info/mozilla-test-fail-screenshot_wga72fbw.png from file build\blobber_upload_dir\mozilla-test-fail-screenshot_wga72fbw.png with content encoding "identity", mime type "image/png" and expiry 2024-04-09T17:47:21.062Z
[taskcluster 2023-04-10T19:38:57.617Z] Uploading artifact public/test_info/resource-usage.json from file build\blobber_upload_dir\resource-usage.json with content encoding "gzip", mime type "application/json" and expiry 2024-04-09T17:47:21.062Z
[taskcluster 2023-04-10T19:38:57.840Z] Uploading artifact public/test_info/system-info.log from file build\blobber_upload_dir\system-info.log with content encoding "gzip", mime type "text/plain" and expiry 2024-04-09T17:47:21.062Z
[taskcluster 2023-04-10T19:38:58.071Z] Uploading link artifact public/logs/live.log to artifact public/logs/live_backing.log with expiry 2024-04-09T17:47:21.062Z
[taskcluster:error] exit status 2
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.