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

[task 2021-01-29T04:09:10.101Z] 04:09:10     INFO - TEST-START | dom/canvas/test/webgl-conf/generated/test_2_conformance2__attribs__gl-bindAttribLocation-aliasing-inactive.html
[task 2021-01-29T04:15:21.255Z] 04:15:21     INFO - Buffered messages logged at 04:09:11
[task 2021-01-29T04:15:21.255Z] 04:15:21     INFO - TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__attribs__gl-bindAttribLocation-aliasing-inactive.html | A valid string reason is expected 
[task 2021-01-29T04:15:21.255Z] 04:15:21     INFO - TEST-PASS | dom/canvas/test/webgl-conf/generated/test_2_conformance2__attribs__gl-bindAttribLocation-aliasing-inactive.html | Reason cannot be empty 
[task 2021-01-29T04:15:21.255Z] 04:15:21     INFO - Buffered messages finished
[task 2021-01-29T04:15:21.256Z] 04:15:21    ERROR - TEST-UNEXPECTED-TIMEOUT | dom/canvas/test/webgl-conf/generated/test_2_conformance2__attribs__gl-bindAttribLocation-aliasing-inactive.html | application timed out after 370 seconds with no output
[task 2021-01-29T04:15:21.256Z] 04:15:21    ERROR - Force-terminating active process(es).
[task 2021-01-29T04:15:21.256Z] 04:15:21     INFO - Determining child pids from psutil...
[task 2021-01-29T04:15:21.261Z] 04:15:21     INFO - [1200]
[task 2021-01-29T04:15:21.262Z] 04:15:21     INFO - ==> process 1200 launched child process 8664 ("Z:\task_1611892916\build\application\firefox\firefox.exe" -contentproc --channel="1200.0.1909506638\574222874" -parentBuildID 20210129023528 -prefsHandle 2168 -prefMapHandle 2160 -prefsLen 1 -prefMapSize 243788 -appdir "Z:\task_1611892916\build\application\firefox\browser" - 1200 "\\.\pipe\gecko-crash-server-pipe.1200" 2236 gpu)
[task 2021-01-29T04:15:21.262Z] 04:15:21     INFO - ==> process 1200 launched child process 10280 ("Z:\task_1611892916\build\application\firefox\firefox.exe" -contentproc --channel="1200.6.1604188608\1876054176" -childID 1 -isForBrowser -prefsHandle 2712 -prefMapHandle 2728 -prefsLen 1684 -prefMapSize 243788 -parentBuildID 20210129023528 -appdir "Z:\task_1611892916\build\application\firefox\browser" - 1200 "\\.\pipe\gecko-crash-server-pipe.1200" 2880 tab)
[task 2021-01-29T04:15:21.263Z] 04:15:21     INFO - ==> process 1200 launched child process 4940 ("Z:\task_1611892916\build\application\firefox\firefox.exe" -contentproc --channel="1200.13.409320803\961695748" -childID 2 -isForBrowser -prefsHandle 3160 -prefMapHandle 3100 -prefsLen 1789 -prefMapSize 243788 -parentBuildID 20210129023528 -appdir "Z:\task_1611892916\build\application\firefox\browser" - 1200 "\\.\pipe\gecko-crash-server-pipe.1200" 3180 tab)
[task 2021-01-29T04:15:21.263Z] 04:15:21     INFO - ==> process 1200 launched child process 2304 ("Z:\task_1611892916\build\application\firefox\firefox.exe" -contentproc --channel="1200.20.153582313\1223593136" -childID 3 -isForBrowser -prefsHandle 3304 -prefMapHandle 3308 -prefsLen 1829 -prefMapSize 243788 -parentBuildID 20210129023528 -appdir "Z:\task_1611892916\build\application\firefox\browser" - 1200 "\\.\pipe\gecko-crash-server-pipe.1200" 3376 tab)
[task 2021-01-29T04:15:21.264Z] 04:15:21     INFO - ==> process 1200 launched child process 4064 ("Z:\task_1611892916\build\application\firefox\firefox.exe" -contentproc --channel="1200.27.459720936\1783852073" -childID 4 -isForBrowser -prefsHandle 4216 -prefMapHandle 4228 -prefsLen 11487 -prefMapSize 243788 -parentBuildID 20210129023528 -appdir "Z:\task_1611892916\build\application\firefox\browser" - 1200 "\\.\pipe\gecko-crash-server-pipe.1200" 4240 tab)
[task 2021-01-29T04:15:21.264Z] 04:15:21     INFO - ==> process 1200 launched child process 11112 ("Z:\task_1611892916\build\application\firefox\firefox.exe" -contentproc --channel="1200.34.956416403\22632372" -childID 5 -isForBrowser -prefsHandle 1196 -prefMapHandle 2000 -prefsLen 11551 -prefMapSize 243788 -parentBuildID 20210129023528 -appdir "Z:\task_1611892916\build\application\firefox\browser" - 1200 "\\.\pipe\gecko-crash-server-pipe.1200" 4424 tab)
[task 2021-01-29T04:15:21.264Z] 04:15:21     INFO - Found child pids: set([4064, 2304, 10280, 4940, 1200, 11112, 8664])
[task 2021-01-29T04:15:21.264Z] 04:15:21     INFO - Killing process: 4064
[task 2021-01-29T04:15:21.265Z] 04:15:21     INFO - TEST-INFO | started process screenshot
[task 2021-01-29T04:15:21.368Z] 04:15:21     INFO - TEST-INFO | screenshot: exit 0
[task 2021-01-29T04:15:21.369Z] 04:15:21     INFO - mozcrash Writing a dump to c:\users\task_1611892916\appdata\local\temp\tmpkgpcdk.mozrunner\minidumps\c7639f89-c5b0-47b0-953f-0a6d314ffe9c.dmp for [4064]
[task 2021-01-29T04:15:21.740Z] 04:15:21     INFO - Killing process: 2304
[task 2021-01-29T04:15:21.741Z] 04:15:21     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-01-29T04:15:21.741Z] 04:15:21     INFO - mozcrash Writing a dump to c:\users\task_1611892916\appdata\local\temp\tmpkgpcdk.mozrunner\minidumps\dd5d8a9b-bc03-43ea-9f20-8cf48d7ba7f2.dmp for [2304]
[task 2021-01-29T04:15:21.901Z] 04:15:21     INFO - Killing process: 10280
[task 2021-01-29T04:15:21.901Z] 04:15:21     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-01-29T04:15:21.902Z] 04:15:21     INFO - mozcrash Writing a dump to c:\users\task_1611892916\appdata\local\temp\tmpkgpcdk.mozrunner\minidumps\bfb26840-1769-4820-a7e5-d04741a8feff.dmp for [10280]
[task 2021-01-29T04:15:22.113Z] 04:15:22     INFO - Killing process: 4940
[task 2021-01-29T04:15:22.113Z] 04:15:22     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-01-29T04:15:22.114Z] 04:15:22     INFO - mozcrash Writing a dump to c:\users\task_1611892916\appdata\local\temp\tmpkgpcdk.mozrunner\minidumps\026da903-157a-4833-9c3b-25db90c0d1e6.dmp for [4940]
[task 2021-01-29T04:15:22.281Z] 04:15:22     INFO - Killing process: 1200
[task 2021-01-29T04:15:22.282Z] 04:15:22     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-01-29T04:15:22.282Z] 04:15:22     INFO - mozcrash Writing a dump to c:\users\task_1611892916\appdata\local\temp\tmpkgpcdk.mozrunner\minidumps\b996ea91-ea5f-43f4-852c-6d672a37d55d.dmp for [1200]
[task 2021-01-29T04:15:22.803Z] 04:15:22     INFO - Killing process: 11112
[task 2021-01-29T04:15:22.804Z] 04:15:22     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-01-29T04:15:22.804Z] 04:15:22     INFO - mozcrash Writing a dump to c:\users\task_1611892916\appdata\local\temp\tmpkgpcdk.mozrunner\minidumps\3e229fe2-03c5-495e-a007-bf8c9a696837.dmp for [11112]
[task 2021-01-29T04:15:22.809Z] 04:15:22  WARNING - mozcrash unable to dump minidump file for pid 11112: -2147024597
[task 2021-01-29T04:15:22.809Z] 04:15:22  WARNING - mozcrash kill_pid(): unable to terminate pid 11112: 5
[task 2021-01-29T04:15:22.809Z] 04:15:22     INFO - Killing process: 8664
[task 2021-01-29T04:15:22.810Z] 04:15:22     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-01-29T04:15:22.810Z] 04:15:22     INFO - mozcrash Writing a dump to c:\users\task_1611892916\appdata\local\temp\tmpkgpcdk.mozrunner\minidumps\00f05d3c-b6a6-4c48-b434-8df0c87f2c90.dmp for [8664]
[task 2021-01-29T04:15:23.030Z] 04:15:23     INFO - psutil found pid 4940 dead
[task 2021-01-29T04:15:23.030Z] 04:15:23     INFO - psutil found pid 10280 dead
[task 2021-01-29T04:15:23.031Z] 04:15:23     INFO - psutil found pid 1200 dead
[task 2021-01-29T04:15:23.031Z] 04:15:23     INFO - psutil found pid 4064 dead
[task 2021-01-29T04:15:23.031Z] 04:15:23     INFO - psutil found pid 8664 dead
[task 2021-01-29T04:15:23.032Z] 04:15:23     INFO - psutil found pid 2304 dead
[task 2021-01-29T04:15:23.032Z] 04:15:23     INFO - psutil found pid 11112 dead
[task 2021-01-29T04:15:23.032Z] 04:15:23     INFO - Killing process: 4516
[task 2021-01-29T04:15:23.032Z] 04:15:23     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-01-29T04:15:23.033Z] 04:15:23     INFO - mozcrash Writing a dump to c:\users\task_1611892916\appdata\local\temp\tmpkgpcdk.mozrunner\minidumps\70d30560-a5d5-45a1-8186-c00449f3b8f6.dmp for [4516]
[task 2021-01-29T04:15:23.033Z] 04:15:23  WARNING - mozcrash unable to dump minidump file for pid 4516: -2147024597
[task 2021-01-29T04:15:23.033Z] 04:15:23  WARNING - mozcrash kill_pid(): unable to terminate pid 4516: 5
[task 2021-01-29T04:15:23.033Z] 04:15:23     INFO - psutil found pid 4516 dead
[task 2021-01-29T04:15:23.052Z] 04:15:23     INFO - TEST-INFO | Main app process: exit 1
[task 2021-01-29T04:15:23.052Z] 04:15:23     INFO - Buffered messages finished
[task 2021-01-29T04:15:23.052Z] 04:15:23    ERROR - TEST-UNEXPECTED-FAIL | dom/canvas/test/webgl-conf/generated/test_2_conformance2__attribs__gl-bindAttribLocation-aliasing-inactive.html | application terminated with exit code 1
[task 2021-01-29T04:15:23.053Z] 04:15:23     INFO - runtests.py | Application ran for: 0:06:18.083000
[task 2021-01-29T04:15:23.053Z] 04:15:23     INFO - zombiecheck | Reading PID log: c:\users\task_1611892916\appdata\local\temp\tmpnpu9vppidlog
[task 2021-01-29T04:15:23.053Z] 04:15:23     INFO - ==> process 1200 launched child process 8664 ("Z:\task_1611892916\build\application\firefox\firefox.exe" -contentproc --channel="1200.0.1909506638\574222874" -parentBuildID 20210129023528 -prefsHandle 2168 -prefMapHandle 2160 -prefsLen 1 -prefMapSize 243788 -appdir "Z:\task_1611892916\build\application\firefox\browser" - 1200 "\\.\pipe\gecko-crash-server-pipe.1200" 2236 gpu)
[task 2021-01-29T04:15:23.054Z] 04:15:23     INFO - ==> process 1200 launched child process 10280 ("Z:\task_1611892916\build\application\firefox\firefox.exe" -contentproc --channel="1200.6.1604188608\1876054176" -childID 1 -isForBrowser -prefsHandle 2712 -prefMapHandle 2728 -prefsLen 1684 -prefMapSize 243788 -parentBuildID 20210129023528 -appdir "Z:\task_1611892916\build\application\firefox\browser" - 1200 "\\.\pipe\gecko-crash-server-pipe.1200" 2880 tab)
[task 2021-01-29T04:15:23.054Z] 04:15:23     INFO - ==> process 1200 launched child process 4940 ("Z:\task_1611892916\build\application\firefox\firefox.exe" -contentproc --channel="1200.13.409320803\961695748" -childID 2 -isForBrowser -prefsHandle 3160 -prefMapHandle 3100 -prefsLen 1789 -prefMapSize 243788 -parentBuildID 20210129023528 -appdir "Z:\task_1611892916\build\application\firefox\browser" - 1200 "\\.\pipe\gecko-crash-server-pipe.1200" 3180 tab)
[task 2021-01-29T04:15:23.054Z] 04:15:23     INFO - ==> process 1200 launched child process 2304 ("Z:\task_1611892916\build\application\firefox\firefox.exe" -contentproc --channel="1200.20.153582313\1223593136" -childID 3 -isForBrowser -prefsHandle 3304 -prefMapHandle 3308 -prefsLen 1829 -prefMapSize 243788 -parentBuildID 20210129023528 -appdir "Z:\task_1611892916\build\application\firefox\browser" - 1200 "\\.\pipe\gecko-crash-server-pipe.1200" 3376 tab)
[task 2021-01-29T04:15:23.055Z] 04:15:23     INFO - ==> process 1200 launched child process 4064 ("Z:\task_1611892916\build\application\firefox\firefox.exe" -contentproc --channel="1200.27.459720936\1783852073" -childID 4 -isForBrowser -prefsHandle 4216 -prefMapHandle 4228 -prefsLen 11487 -prefMapSize 243788 -parentBuildID 20210129023528 -appdir "Z:\task_1611892916\build\application\firefox\browser" - 1200 "\\.\pipe\gecko-crash-server-pipe.1200" 4240 tab)
[task 2021-01-29T04:15:23.055Z] 04:15:23     INFO - ==> process 1200 launched child process 11112 ("Z:\task_1611892916\build\application\firefox\firefox.exe" -contentproc --channel="1200.34.956416403\22632372" -childID 5 -isForBrowser -prefsHandle 1196 -prefMapHandle 2000 -prefsLen 11551 -prefMapSize 243788 -parentBuildID 20210129023528 -appdir "Z:\task_1611892916\build\application\firefox\browser" - 1200 "\\.\pipe\gecko-crash-server-pipe.1200" 4424 tab)
[task 2021-01-29T04:15:23.055Z] 04:15:23     INFO - zombiecheck | Checking for orphan process with PID: 4064
[task 2021-01-29T04:15:23.055Z] 04:15:23     INFO - zombiecheck | Checking for orphan process with PID: 2304
[task 2021-01-29T04:15:23.056Z] 04:15:23     INFO - zombiecheck | Checking for orphan process with PID: 10280
[task 2021-01-29T04:15:23.056Z] 04:15:23     INFO - zombiecheck | Checking for orphan process with PID: 4940
[task 2021-01-29T04:15:23.056Z] 04:15:23     INFO - zombiecheck | Checking for orphan process with PID: 11112
[task 2021-01-29T04:15:23.056Z] 04:15:23     INFO - zombiecheck | Checking for orphan process with PID: 8664
[task 2021-01-29T04:15:23.057Z] 04:15:23     INFO - mozcrash Downloading symbols from: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Zu847zqdTNKV3zT9WjupkA/artifacts/public/build/target.crashreporter-symbols.zip
[task 2021-01-29T04:15:26.540Z] 04:15:26     INFO - mozcrash Copy/paste: Z:/task_1611892916/fetches\minidump_stackwalk\minidump_stackwalk.exe c:\users\task_1611892916\appdata\local\temp\tmpkgpcdk.mozrunner\minidumps\dd5d8a9b-bc03-43ea-9f20-8cf48d7ba7f2.dmp c:\users\task_1611892916\appdata\local\temp\tmpch0u5f
[task 2021-01-29T04:15:33.199Z] 04:15:33     INFO - mozcrash Saved minidump as Z:\task_1611892916\build\blobber_upload_dir\dd5d8a9b-bc03-43ea-9f20-8cf48d7ba7f2.dmp
[task 2021-01-29T04:15:33.219Z] 04:15:33     INFO - PROCESS-CRASH | dom/canvas/test/webgl-conf/generated/test_2_conformance2__attribs__gl-bindAttribLocation-aliasing-inactive.html | application crashed [None]
[task 2021-01-29T04:15:33.219Z] 04:15:33     INFO - Crash dump filename: c:\users\task_1611892916\appdata\local\temp\tmpkgpcdk.mozrunner\minidumps\dd5d8a9b-bc03-43ea-9f20-8cf48d7ba7f2.dmp
[task 2021-01-29T04:15:33.219Z] 04:15:33     INFO - Operating system: Windows NT
[task 2021-01-29T04:15:33.219Z] 04:15:33     INFO -                   10.0.17134 
[task 2021-01-29T04:15:33.220Z] 04:15:33     INFO - CPU: amd64
[task 2021-01-29T04:15:33.220Z] 04:15:33     INFO -      family 6 model 79 stepping 1
[task 2021-01-29T04:15:33.220Z] 04:15:33     INFO -      4 CPUs
[task 2021-01-29T04:15:33.220Z] 04:15:33     INFO - 
[task 2021-01-29T04:15:33.220Z] 04:15:33     INFO - GPU: UNKNOWN
[task 2021-01-29T04:15:33.220Z] 04:15:33     INFO - 
[task 2021-01-29T04:15:33.220Z] 04:15:33     INFO - No crash
[task 2021-01-29T04:15:33.221Z] 04:15:33     INFO - Process uptime: 374 seconds
[task 2021-01-29T04:15:33.221Z] 04:15:33     INFO - 
[task 2021-01-29T04:15:33.221Z] 04:15:33     INFO - Thread 0
[task 2021-01-29T04:15:33.221Z] 04:15:33     INFO -  0  ntdll.dll!NtWaitForAlertByThreadId + 0x14
[task 2021-01-29T04:15:33.221Z] 04:15:33     INFO -     rax = 0x00000000000001c8   rdx = 0x0000000000000000
[task 2021-01-29T04:15:33.221Z] 04:15:33     INFO -     rcx = 0x0000028160072060   rbx = 0x0000000000000000
[task 2021-01-29T04:15:33.221Z] 04:15:33     INFO -     rsi = 0x0000000000000000   rdi = 0x000000c0fd7ff701
[task 2021-01-29T04:15:33.222Z] 04:15:33     INFO -     rbp = 0x0000028160072060   rsp = 0x000000c0fd7fee48
[task 2021-01-29T04:15:33.222Z] 04:15:33     INFO -      r8 = 0x0000000000000000    r9 = 0x0000000000000000
[task 2021-01-29T04:15:33.222Z] 04:15:33     INFO -     r10 = 0x000000000000005b   r11 = 0x000002816e5680a0
[task 2021-01-29T04:15:33.222Z] 04:15:33     INFO -     r12 = 0x00007ffa227e1ae0   r13 = 0x0000028160074050
[task 2021-01-29T04:15:33.222Z] 04:15:33     INFO -     r14 = 0x00000281600720f0   r15 = 0x0000000000000000
[task 2021-01-29T04:15:33.223Z] 04:15:33     INFO -     rip = 0x00007ffa3ea3d7e4
[task 2021-01-29T04:15:33.223Z] 04:15:33     INFO -     Found by: given as instruction pointer in context
[task 2021-01-29T04:15:33.223Z] 04:15:33     INFO -  1  ntdll.dll!RtlSleepConditionVariableSRW + 0x109
[task 2021-01-29T04:15:33.223Z] 04:15:33     INFO -     rbx = 0x0000000000000000   rbp = 0x0000028160072060
[task 2021-01-29T04:15:33.223Z] 04:15:33     INFO -     rsp = 0x000000c0fd7fee50   r12 = 0x00007ffa227e1ae0
[task 2021-01-29T04:15:33.223Z] 04:15:33     INFO -     r13 = 0x0000028160074050   r14 = 0x00000281600720f0
[task 2021-01-29T04:15:33.223Z] 04:15:33     INFO -     r15 = 0x0000000000000000   rip = 0x00007ffa3e9bef19
[task 2021-01-29T04:15:33.224Z] 04:15:33     INFO -     Found by: call frame info
[task 2021-01-29T04:15:33.224Z] 04:15:33     INFO -  2  KERNELBASE.dll!LdrpLoadDependentModule + 0xe32
[task 2021-01-29T04:15:33.224Z] 04:15:33     INFO -     rbx = 0x0000000000000000   rbp = 0x0000028160072060
[task 2021-01-29T04:15:33.224Z] 04:15:33     INFO -     rsp = 0x000000c0fd7feec0   r12 = 0x00007ffa227e1ae0
[task 2021-01-29T04:15:33.224Z] 04:15:33     INFO -     r13 = 0x0000028160074050   r14 = 0x00000281600720f0
[task 2021-01-29T04:15:33.224Z] 04:15:33     INFO -     r15 = 0x0000000000000000   rip = 0x00007ffa3b5b2c72
[task 2021-01-29T04:15:33.224Z] 04:15:33     INFO -     Found by: call frame info
[task 2021-01-29T04:15:33.225Z] 04:15:33     INFO - 
[task 2021-01-29T04:15:33.225Z] 04:15:33     INFO - Thread 1
[task 2021-01-29T04:15:33.225Z] 04:15:33     INFO -  0  ntdll.dll!NtRemoveIoCompletion + 0x14```