Closed Bug 1441519 Opened 7 years ago Closed 7 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | dom/canvas/test/crossorigin/test_video_crossorigin.html | application timed out after 370 seconds with no output

Categories

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

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

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

Filed by: ccoroiu [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=164594694&repo=autoland https://queue.taskcluster.net/v1/task/cLH0hRCOTvqZ2pEXoJ64PQ/runs/0/artifacts/public/logs/live_backing.log [task 2018-02-27T12:02:53.845Z] 12:02:53 INFO - TEST-PASS | dom/canvas/test/crossorigin/test_video_crossorigin.html | Load 'http://example.com/tests/dom/canvas/test/crossorigin/video.sjs?name=tests/dom/media/test/320x240.ogv&type=video/ogg&cors=use-credentials' with crossOrigin='use-credentials' [task 2018-02-27T12:02:53.846Z] 12:02:53 INFO - TEST-PASS | dom/canvas/test/crossorigin/test_video_crossorigin.html | drawImage 'http://example.com/tests/dom/canvas/test/crossorigin/video.sjs?name=tests/dom/media/test/320x240.ogv&type=video/ogg&cors=use-credentials' then getImageData with crossOrigin='use-credentials' worked [task 2018-02-27T12:02:53.847Z] 12:02:53 INFO - TEST-PASS | dom/canvas/test/crossorigin/test_video_crossorigin.html | drawImage 'http://example.com/tests/dom/canvas/test/crossorigin/video.sjs?name=tests/dom/media/test/320x240.ogv&type=video/ogg&cors=use-credentials' then captureStream with crossOrigin='use-credentials' worked [task 2018-02-27T12:02:53.848Z] 12:02:53 INFO - TEST-PASS | dom/canvas/test/crossorigin/test_video_crossorigin.html | drawImage 'http://example.com/tests/dom/canvas/test/crossorigin/video.sjs?name=tests/dom/media/test/320x240.ogv&type=video/ogg&cors=use-credentials' then getImageData with crossOrigin='use-credentials' worked [task 2018-02-27T12:02:53.849Z] 12:02:53 INFO - TEST-PASS | dom/canvas/test/crossorigin/test_video_crossorigin.html | drawImage 'http://example.com/tests/dom/canvas/test/crossorigin/video.sjs?name=tests/dom/media/test/320x240.ogv&type=video/ogg&cors=use-credentials' then captureStream with crossOrigin='use-credentials' worked [task 2018-02-27T12:02:53.850Z] 12:02:53 INFO - TEST-PASS | dom/canvas/test/crossorigin/test_video_crossorigin.html | createTexture from 'http://example.com/tests/dom/canvas/test/crossorigin/video.sjs?name=tests/dom/media/test/320x240.ogv&type=video/ogg&cors=use-credentials' with crossOrigin='use-credentials' worked [task 2018-02-27T12:02:53.851Z] 12:02:53 INFO - [finished http://example.com/tests/dom/canvas/test/crossorigin/video.sjs?name=tests/dom/media/test/320x240.ogv&type=video/ogg&cors=use-credentials-16] remaining= [task 2018-02-27T12:02:53.852Z] 12:02:53 INFO - TEST-PASS | dom/canvas/test/crossorigin/test_video_crossorigin.html | [finished http://example.com/tests/dom/canvas/test/crossorigin/video.sjs?name=tests/dom/media/test/320x240.ogv&type=video/ogg&cors=use-credentials-16 t=1.864] Length of array should match number of running tests [task 2018-02-27T12:02:53.853Z] 12:02:53 INFO - Finished at Tue Feb 27 2018 11:56:35 GMT+0000 (UTC) (1519732595.922s) [task 2018-02-27T12:02:53.854Z] 12:02:53 INFO - Running time: 2.272s [task 2018-02-27T12:02:53.855Z] 12:02:53 INFO - Buffered messages finished [task 2018-02-27T12:02:53.856Z] 12:02:53 ERROR - TEST-UNEXPECTED-TIMEOUT | dom/canvas/test/crossorigin/test_video_crossorigin.html | application timed out after 370 seconds with no output [task 2018-02-27T12:02:53.857Z] 12:02:53 ERROR - Force-terminating active process(es). [task 2018-02-27T12:02:53.858Z] 12:02:53 INFO - Determining child pids from psutil... [task 2018-02-27T12:02:53.859Z] 12:02:53 INFO - [1263, 1293] [task 2018-02-27T12:02:53.860Z] 12:02:53 INFO - ==> process 1212 launched child process 1232 [task 2018-02-27T12:02:53.860Z] 12:02:53 INFO - ==> process 1212 launched child process 1263 [task 2018-02-27T12:02:53.861Z] 12:02:53 INFO - ==> process 1212 launched child process 1293 [task 2018-02-27T12:02:53.862Z] 12:02:53 INFO - Found child pids: set([1232, 1293, 1263]) [task 2018-02-27T12:02:53.863Z] 12:02:53 INFO - Failed to get child procs [task 2018-02-27T12:02:53.864Z] 12:02:53 INFO - Killing process: 1232 [task 2018-02-27T12:02:53.865Z] 12:02:53 INFO - TEST-INFO | started process screentopng [task 2018-02-27T12:02:54.104Z] 12:02:54 INFO - TEST-INFO | screentopng: exit 0 [task 2018-02-27T12:02:54.105Z] 12:02:54 INFO - Can't trigger Breakpad, process no longer exists [task 2018-02-27T12:02:54.106Z] 12:02:54 INFO - Killing process: 1293 [task 2018-02-27T12:02:54.107Z] 12:02:54 INFO - Not taking screenshot here: see the one that was previously logged [task 2018-02-27T12:02:54.108Z] 12:02:54 INFO - Killing process: 1263 [task 2018-02-27T12:02:54.109Z] 12:02:54 INFO - Not taking screenshot here: see the one that was previously logged [task 2018-02-27T12:02:54.111Z] 12:02:54 INFO - Killing process: 1212 [task 2018-02-27T12:02:54.112Z] 12:02:54 INFO - Not taking screenshot here: see the one that was previously logged [task 2018-02-27T12:02:54.237Z] 12:02:54 INFO - psutil found pid 1212 dead [task 2018-02-27T12:02:54.462Z] 12:02:54 WARNING - runtests.py | Failed to get app exit code - running/crashed? [task 2018-02-27T12:02:54.462Z] 12:02:54 INFO - TEST-INFO | Main app process: exit 0 [task 2018-02-27T12:02:54.462Z] 12:02:54 INFO - runtests.py | Application ran for: 0:06:33.867119 [task 2018-02-27T12:02:54.463Z] 12:02:54 INFO - zombiecheck | Reading PID log: /tmp/tmp9UXYXypidlog [task 2018-02-27T12:02:54.463Z] 12:02:54 INFO - ==> process 1212 launched child process 1232 [task 2018-02-27T12:02:54.463Z] 12:02:54 INFO - ==> process 1212 launched child process 1263 [task 2018-02-27T12:02:54.463Z] 12:02:54 INFO - ==> process 1212 launched child process 1293 [task 2018-02-27T12:02:54.463Z] 12:02:54 INFO - zombiecheck | Checking for orphan process with PID: 1232 [task 2018-02-27T12:02:54.464Z] 12:02:54 INFO - zombiecheck | Checking for orphan process with PID: 1293 [task 2018-02-27T12:02:54.465Z] 12:02:54 INFO - zombiecheck | Checking for orphan process with PID: 1263 [task 2018-02-27T12:02:54.466Z] 12:02:54 INFO - mozcrash Copy/paste: /usr/local/bin/linux64-minidump_stackwalk /tmp/tmpk36fUY.mozrunner/minidumps/01b429ca-2d09-c1ef-0790-dd00e9086938.dmp /builds/worker/workspace/build/symbols [task 2018-02-27T12:03:02.809Z] 12:03:02 INFO - mozcrash Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/01b429ca-2d09-c1ef-0790-dd00e9086938.dmp [task 2018-02-27T12:03:02.809Z] 12:03:02 INFO - mozcrash Saved app info as /builds/worker/workspace/build/blobber_upload_dir/01b429ca-2d09-c1ef-0790-dd00e9086938.extra [task 2018-02-27T12:03:02.879Z] 12:03:02 INFO - PROCESS-CRASH | Main app process exited normally | application crashed [@ libc-2.23.so + 0xfb74d] [task 2018-02-27T12:03:02.880Z] 12:03:02 INFO - Crash dump filename: /tmp/tmpk36fUY.mozrunner/minidumps/01b429ca-2d09-c1ef-0790-dd00e9086938.dmp [task 2018-02-27T12:03:02.882Z] 12:03:02 INFO - Operating system: Linux [task 2018-02-27T12:03:02.882Z] 12:03:02 INFO - 0.0.0 Linux 4.4.0-98-generic #121~14.04.1-Ubuntu SMP Wed Oct 11 11:54:55 UTC 2017 x86_64 [task 2018-02-27T12:03:02.883Z] 12:03:02 INFO - CPU: amd64 [task 2018-02-27T12:03:02.883Z] 12:03:02 INFO - family 6 model 62 stepping 4 [task 2018-02-27T12:03:02.884Z] 12:03:02 INFO - 2 CPUs [task 2018-02-27T12:03:02.884Z] 12:03:02 INFO - [task 2018-02-27T12:03:02.884Z] 12:03:02 INFO - GPU: UNKNOWN [task 2018-02-27T12:03:02.885Z] 12:03:02 INFO - [task 2018-02-27T12:03:02.885Z] 12:03:02 INFO - Crash reason: SIGABRT [task 2018-02-27T12:03:02.885Z] 12:03:02 INFO - Crash address: 0x3e8000003da [task 2018-02-27T12:03:02.886Z] 12:03:02 INFO - Process uptime: not available [task 2018-02-27T12:03:02.887Z] 12:03:02 INFO - [task 2018-02-27T12:03:02.887Z] 12:03:02 INFO - Thread 0 (crashed) [task 2018-02-27T12:03:02.888Z] 12:03:02 INFO - 0 libc-2.23.so + 0xfb74d [task 2018-02-27T12:03:02.888Z] 12:03:02 INFO - rax = 0xfffffffffffffffc rdx = 0x00000000ffffffff [task 2018-02-27T12:03:02.888Z] 12:03:02 INFO - rcx = 0x00007f424713874d rbx = 0x00007f4246ec03a0 [task 2018-02-27T12:03:02.888Z] 12:03:02 INFO - rsi = 0x0000000000000005 rdi = 0x00007f42180d9be0 [task 2018-02-27T12:03:02.889Z] 12:03:02 INFO - rbp = 0x00007ffdbe68c020 rsp = 0x00007ffdbe68bff0 [task 2018-02-27T12:03:02.890Z] 12:03:02 INFO - r8 = 0x0000000000000005 r9 = 0x0000000000000001 [task 2018-02-27T12:03:02.890Z] 12:03:02 INFO - r10 = 0x00007f4217f23580 r11 = 0x0000000000000293 [task 2018-02-27T12:03:02.890Z] 12:03:02 INFO - r12 = 0x00007f42180d9be0 r13 = 0x00000000ffffffff [task 2018-02-27T12:03:02.890Z] 12:03:02 INFO - r14 = 0x00007f4237fddd17 r15 = 0x0000000000000005 [task 2018-02-27T12:03:02.891Z] 12:03:02 INFO - rip = 0x00007f424713874d [task 2018-02-27T12:03:02.891Z] 12:03:02 INFO - Found by: given as instruction pointer in context [task 2018-02-27T12:03:02.891Z] 12:03:02 INFO - 1 libxul.so!PollWrapper [nsAppShell.cpp:dc2be3e41727114241cf787748ba95c86b9ed786 : 53 + 0x10] [task 2018-02-27T12:03:02.892Z] 12:03:02 INFO - rbp = 0x00007ffdbe68c020 rsp = 0x00007ffdbe68c000 [task 2018-02-27T12:03:02.892Z] 12:03:02 INFO - rip = 0x00007f4237fddd47 [task 2018-02-27T12:03:02.893Z] 12:03:02 INFO - Found by: stack scanning [task 2018-02-27T12:03:02.893Z] 12:03:02 INFO - 2 libglib-2.0.so.0.4800.2 + 0x4a38c [task 2018-02-27T12:03:02.894Z] 12:03:02 INFO - rbp = 0x0000000000000005 rsp = 0x00007ffdbe68c030 [task 2018-02-27T12:03:02.894Z] 12:03:02 INFO - rip = 0x00007f4241fa738c [task 2018-02-27T12:03:02.895Z] 12:03:02 INFO - Found by: call frame info [task 2018-02-27T12:03:02.895Z] 12:03:02 INFO - 3 libglib-2.0.so.0.4800.2 + 0x4a49c [task 2018-02-27T12:03:02.895Z] 12:03:02 INFO - rsp = 0x00007ffdbe68c090 rip = 0x00007f4241fa749c [task 2018-02-27T12:03:02.896Z] 12:03:02 INFO - Found by: stack scanning
Whiteboard: gfx-noted
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.