Closed Bug 1742190 Opened 4 years ago Closed 3 years ago

Intermittent image/test/reftest/ico/ico-bmp-4bpp/ico-size-1x1-4bpp.ico | application timed out after 370 seconds with no output

Categories

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

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=358675345&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/FQyU7APrQAKuNVJ02JMQ0A/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/FQyU7APrQAKuNVJ02JMQ0A/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1


[task 2021-11-19T22:47:22.691Z] 22:47:22     INFO - REFTEST TEST-START | image/test/reftest/ico/ico-bmp-4bpp/ico-size-1x1-4bpp.ico == image/test/reftest/ico/ico-bmp-4bpp/ico-size-1x1-4bpp.png
[task 2021-11-19T22:47:22.692Z] 22:47:22     INFO - REFTEST TEST-LOAD | file:///builds/worker/workspace/build/tests/reftest/tests/image/test/reftest/ico/ico-bmp-4bpp/ico-size-1x1-4bpp.ico | 0 / 19 (0%)
[task 2021-11-19T22:47:22.720Z] 22:47:22     INFO - ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-11-19T22:53:32.735Z] 22:53:32    ERROR - REFTEST ERROR | image/test/reftest/ico/ico-bmp-4bpp/ico-size-1x1-4bpp.ico | application timed out after 370 seconds with no output
[task 2021-11-19T22:53:32.735Z] 22:53:32    ERROR - REFTEST ERROR | Force-terminating active process(es).
[task 2021-11-19T22:53:32.736Z] 22:53:32     INFO - REFTEST TEST-INFO | started process screentopng
[task 2021-11-19T22:53:32.898Z] 22:53:32     INFO - REFTEST TEST-INFO | screentopng: exit 0
[task 2021-11-19T22:53:32.967Z] 22:53:32    ERROR - TEST-UNEXPECTED-FAIL | image/test/reftest/ico/ico-bmp-4bpp/ico-size-1x1-4bpp.ico | application terminated with exit code 6
[task 2021-11-19T22:53:32.967Z] 22:53:32     INFO - REFTEST INFO | Downloading symbols from: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/JDe4_lIcQYew7SlXSzXbLQ/artifacts/public/build/target.crashreporter-symbols.zip
[task 2021-11-19T22:53:36.187Z] 22:53:36     INFO - REFTEST INFO | Copy/paste: /builds/worker/fetches/minidump_stackwalk/minidump_stackwalk /tmp/tmp4p0h6dce.mozrunner/minidumps/1ded829e-2862-0a9e-d02a-ee7db6e88b5f.dmp /tmp/tmpyda3yzbq https://symbols.mozilla.org/
[task 2021-11-19T22:53:37.431Z] 22:53:37     INFO - REFTEST INFO | Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/1ded829e-2862-0a9e-d02a-ee7db6e88b5f.dmp
[task 2021-11-19T22:53:37.434Z] 22:53:37     INFO - REFTEST INFO | Saved app info as /builds/worker/workspace/build/blobber_upload_dir/1ded829e-2862-0a9e-d02a-ee7db6e88b5f.extra
[task 2021-11-19T22:53:37.448Z] 22:53:37     INFO - REFTEST PROCESS-CRASH | image/test/reftest/ico/ico-bmp-4bpp/ico-size-1x1-4bpp.ico | application crashed [@ pthread_mutex_lock]
[task 2021-11-19T22:53:37.448Z] 22:53:37     INFO - Crash dump filename: /tmp/tmp4p0h6dce.mozrunner/minidumps/1ded829e-2862-0a9e-d02a-ee7db6e88b5f.dmp
[task 2021-11-19T22:53:37.448Z] 22:53:37     INFO - Operating system: Linux
[task 2021-11-19T22:53:37.448Z] 22:53:37     INFO -                   0.0.0 Linux 4.4.0-1014-aws #14taskcluster1-Ubuntu SMP Tue Apr 3 10:27:00 UTC 2018 x86_64
[task 2021-11-19T22:53:37.448Z] 22:53:37     INFO - CPU: amd64
[task 2021-11-19T22:53:37.448Z] 22:53:37     INFO -      family 6 model 85 stepping 7
[task 2021-11-19T22:53:37.448Z] 22:53:37     INFO -      4 CPUs
[task 2021-11-19T22:53:37.448Z] 22:53:37     INFO - 
[task 2021-11-19T22:53:37.448Z] 22:53:37     INFO - GPU: UNKNOWN
[task 2021-11-19T22:53:37.448Z] 22:53:37     INFO - 
[task 2021-11-19T22:53:37.448Z] 22:53:37     INFO - Crash reason:  SIGABRT
[task 2021-11-19T22:53:37.448Z] 22:53:37     INFO - Crash address: 0x3e8000005d9
[task 2021-11-19T22:53:37.448Z] 22:53:37     INFO - Process uptime: not available
[task 2021-11-19T22:53:37.448Z] 22:53:37     INFO - 
[task 2021-11-19T22:53:37.448Z] 22:53:37     INFO - Thread 0 tid 7389 (crashed) 0  libpthread.so.0!__lll_lock_wait + 0x1d
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -     rax = 0xfffffffffffffffc   rdx = 0x0000000000000002
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -     rcx = 0x00007f38135cc11d   rbx = 0x0000000000000000
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -     rsi = 0x0000000000000080   rdi = 0x00007f3813a02990
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -     rbp = 0x00007f37cd0fd1d0   rsp = 0x00007f37cd0fcf88
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -      r8 = 0x00007f3813a02990    r9 = 0x00007f37cacc4090
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -     r10 = 0x0000000000000000   r11 = 0x0000000000000202
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -     r12 = 0x00007f3813a02060   r13 = 0x00007f3812a897f0
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -     r14 = 0x00007f37cd0fd0e0   r15 = 0x00007f3812a89067
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -     rip = 0x00007f38135cc11d
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -     Found by: given as instruction pointer in context
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -  1  libpthread.so.0!pthread_mutex_lock [pthread_mutex_lock.c : 113 + 0x1c]
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -     rdx = 0x0000000000001cdd   rbx = 0x0000000000000000
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -     rbp = 0x00007f37cd0fd1d0   rsp = 0x00007f37cd0fcfa0
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -     r10 = 0xb1ed85f30117e668   r12 = 0x00007f3813a02060
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -     r13 = 0x00007f3812a897f0   r14 = 0x00007f37cd0fd0e0
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -     r15 = 0x00007f3812a89067   rip = 0x00007f38135c5098
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -     Found by: call frame info
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -  2  libc.so.6!__dl_iterate_phdr [dl-iteratephdr.c : 40 + 0x10]
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -     rbx = 0x0000000000000000   rbp = 0x00007f37cd0fd1d0
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -     rsp = 0x00007f37cd0fd030   r12 = 0x00007f3813a02060
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -     r13 = 0x00007f3812a897f0   r14 = 0x00007f37cd0fd0e0
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -     r15 = 0x00007f3812a89067   rip = 0x00007f38127ed02b
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -     Found by: call frame info
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -  3  libgcc_s.so.1 + 0x12aa1
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -     rbx = 0x0000000000000000   rbp = 0x00007f37cd0fd1d0
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -     rsp = 0x00007f37cd0fd0e0   r12 = 0x00007f37cd0fd428
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -     r13 = 0x00007f38135c9f90   r14 = 0x00007f3812c8f3b0
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -     r15 = 0x00007f3812a89067   rip = 0x00007f3812a8aaa1
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -     Found by: call frame info
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -  4  libgcc_s.so.1 + 0x11067
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -     rbp = 0x00007f37cd0fd1d0   rsp = 0x00007f37cd0fd0e8
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -     rip = 0x00007f3812a89067
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -     Found by: stack scanning
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -  5  firefox-bin!MozStackWalk + 0x6e
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -     rbp = 0x00007f37cd0fd1d0   rsp = 0x00007f37cd0fd130
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -     rip = 0x0000557dd7efeb8e
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO -     Found by: stack scanning
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO - 
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO - Loaded modules:
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO - 0x557dd7dfb000 - 0x557dd7f72fff  firefox-bin  ???  (main)
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO - 0x7f35d0500000 - 0x7f35d0570fff  memfd:mozilla-ipc (deleted)  ???
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO - 0x7f37cb444000 - 0x7f37cb4ddfff  libnssckbi.so  ???
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO - 0x7f37cb4e2000 - 0x7f37cb5cafff  libfreeblpriv3.so  ???
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO - 0x7f37cb5dd000 - 0x7f37cb652fff  libsoftokn3.so  ???
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO - 0x7f37cba62000 - 0x7f37cbdfefff  DejaVuSans.ttf  ???
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO - 0x7f37cca68000 - 0x7f37ccaa8fff  gschemas.compiled  ???
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO - 0x7f37ccaea000 - 0x7f37ccb46fff  DejaVuSerif.ttf  ???
[task 2021-11-19T22:53:37.449Z] 22:53:37     INFO - 0x7f37ccb47000 - 0x7f37ccbfffff  DejaVuSans.ttf  ???
[task 2021-11-19T22:53:37.450Z] 22:53:37     INFO - 0x7f37d063c000 - 0x7f37d06b4fff  memfd:mozilla-ipc (deleted)  ???
[task 2021-11-19T22:53:37.450Z] 22:53:37     INFO - 0x7f37d26fa000 - 0x7f37d28fffff  libpixbufloader-png.so  ???
[task 2021-11-19T22:53:37.450Z] 22:53:37     INFO - 0x7f37d2f01000 - 0x7f37d2f3bfff  memfd:mozilla-ipc (deleted)  ???
[task 2021-11-19T22:53:37.450Z] 22:53:37     INFO - 0x7f37d2fff000 - 0x7f37d30fefff  memfd:mozilla-ipc (deleted)  ???
[task 2021-11-19T22:53:37.450Z] 22:53:37     INFO - 0x7f37d34b9000 - 0x7f37d34fefff  webcompat@mozilla.org.xpi  ???
[task 2021-11-19T22:53:37.450Z] 22:53:37     INFO - 0x7f37d3805000 - 0x7f37d3805fff  cpr_string.gcda  ???
[task 2021-11-19T22:53:37.450Z] 22:53:37     INFO - 0x7f37d3806000 - 0x7f37d380bfff  memfd:mozilla-ipc (deleted)  ???
[task 2021-11-19T22:53:37.450Z] 22:53:37     INFO - 0x7f37d3810000 - 0x7f37d382dfff  memfd:mozilla-ipc (deleted)  ???
[task 2021-11-19T22:53:37.450Z] 22:53:37     INFO - 0x7f37d382e000 - 0x7f37d387bfff  screenshots@mozilla.org.xpi  ???
[task 2021-11-19T22:53:37.450Z] 22:53:37     INFO - 0x7f37d4102000 - 0x7f37d410afff  memfd:mozilla-ipc (deleted)  ???
[task 2021-11-19T22:53:37.450Z] 22:53:37     INFO - 0x7f37d410b000 - 0x7f37d410efff  SYSV00000000 (deleted)  ???
[task 2021-11-19T22:53:37.450Z] 22:53:37     INFO - 0x7f37d81ac000 - 0x7f37d83d5fff  libtinfo.so.5  ???
[task 2021-11-19T22:53:37.450Z] 22:53:37     INFO - 0x7f37d83d6000 - 0x7f37d8608fff  libedit.so.2  ???
[task 2021-11-19T22:53:37.450Z] 22:53:37     INFO - 0x7f37d860d000 - 0x7f37d8814fff  libdrm_nouveau.so.2  ???
[task 2021-11-19T22:53:37.450Z] 22:53:37     INFO - 0x7f37d8815000 - 0x7f37d8a1efff  libdrm_amdgpu.so.1  ???
[task 2021-11-19T22:53:37.450Z] 22:53:37     INFO - 0x7f37d8a1f000 - 0x7f37d8c38fff  libelf.so.1  ???
[task 2021-11-19T22:53:37.450Z] 22:53:37     INFO - 0x7f37d8c39000 - 0x7f37d8e44fff  libdrm_radeon.so.1  ???
<...>
[task 2021-11-19T22:53:37.465Z] 22:53:37     INFO - 0x7f38139f2000 - 0x7f38139fdfff  libplc4.so  ???
[task 2021-11-19T22:53:37.465Z] 22:53:37     INFO - 0x7fff7638c000 - 0x7fff7638dfff  linux-gate.so  ???
[task 2021-11-19T22:53:37.465Z] 22:53:37     INFO - REFTEST INFO | Copy/paste: /builds/worker/fetches/minidump_stackwalk/minidump_stackwalk /tmp/tmp4p0h6dce.mozrunner/minidumps/07c595ed-c90c-1a05-e6e6-ed052ad5f460.dmp /tmp/tmpyda3yzbq https://symbols.mozilla.org/
[task 2021-11-19T22:53:37.466Z] 22:53:37     INFO - REFTEST INFO | Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/07c595ed-c90c-1a05-e6e6-ed052ad5f460.dmp
[task 2021-11-19T22:53:37.466Z] 22:53:37     INFO - REFTEST PROCESS-CRASH | image/test/reftest/ico/ico-bmp-4bpp/ico-size-1x1-4bpp.ico | application crashed [None]
[task 2021-11-19T22:53:37.466Z] 22:53:37     INFO - Crash dump filename: /tmp/tmp4p0h6dce.mozrunner/minidumps/07c595ed-c90c-1a05-e6e6-ed052ad5f460.dmp
[task 2021-11-19T22:53:37.466Z] 22:53:37     INFO - stderr from minidump_stackwalk:
[task 2021-11-19T22:53:37.466Z] 22:53:37     INFO - 2021-11-19 22:53:37: minidump_stackwalk.cc:141: ERROR: MinidumpProcessor::Process failed
[task 2021-11-19T22:53:37.554Z] 22:53:37     INFO - REFTEST INFO | Process mode: e10s
[task 2021-11-19T22:53:37.554Z] 22:53:37  WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2021-11-19T22:53:37.555Z] 22:53:37     INFO - REFTEST INFO | Running tests in file:///builds/worker/workspace/build/tests/reftest/tests/layout/reftests/position-dynamic-changes/vertical/reftest_padding_parent.list
[task 2021-11-19T22:53:37.564Z] 22:53:37     INFO - REFTEST INFO | Running with e10s: True
[task 2021-11-19T22:53:37.565Z] 22:53:37     INFO - REFTEST INFO | Running with fission: False
[task 2021-11-19T22:53:37.565Z] 22:53:37     INFO - REFTEST INFO | Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -profile /tmp/tmp85qc1rh2.mozrunner
[task 2021-11-19T22:53:37.626Z] 22:53:37     INFO - [CodeCoverage] Setting handlers for process 7458.
[task 2021-11-19T22:53:38.408Z] 22:53:38     INFO - 1637362418407	Marionette	INFO	Marionette enabled
[task 2021-11-19T22:53:38.520Z] 22:53:38     INFO - 1637362418519	Marionette	TRACE	Received observer notification toplevel-window-ready
[task 2021-11-19T22:53:39.832Z] 22:53:39     INFO - [CodeCoverage] Setting handlers for process 7544.
[task 2021-11-19T22:53:40.001Z] 22:53:40     INFO - [CodeCoverage] Setting handlers for process 7547.
[task 2021-11-19T22:53:41.119Z] 22:53:41     INFO - [CodeCoverage] Setting handlers for process 7597.
[task 2021-11-19T22:53:42.410Z] 22:53:42     INFO - console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/tmp85qc1rh2.mozrunner/search.json.mozlz4", (void 0)))
[task 2021-11-19T22:53:43.816Z] 22:53:43     INFO - 1637362423815	Marionette	TRACE	Received observer notification marionette-startup-requested
[task 2021-11-19T22:53:43.817Z] 22:53:43     INFO - 1637362423816	Marionette	TRACE	Waiting until startup recorder finished recording startup scripts...
[task 2021-11-19T22:53:43.834Z] 22:53:43     INFO - 1637362423833	Marionette	TRACE	All scripts recorded.
[task 2021-11-19T22:53:43.837Z] 22:53:43     INFO - 1637362423836	Marionette	INFO	Listening on port 2828
[task 2021-11-19T22:53:43.838Z] 22:53:43     INFO - 1637362423837	Marionette	DEBUG	Marionette is listening
[task 2021-11-19T22:53:43.926Z] 22:53:43     INFO - 1637362423925	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:47086
[task 2021-11-19T22:53:43.934Z] 22:53:43     INFO - 1637362423933	Marionette	DEBUG	Closed connection 0
[task 2021-11-19T22:53:43.935Z] 22:53:43     INFO - 1637362423933	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:47088
[task 2021-11-19T22:53:43.952Z] 22:53:43     INFO - 1637362423951	Marionette	DEBUG	1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2021-11-19T22:53:43.988Z] 22:53:43     INFO - 1637362423986	Marionette	DEBUG	1 <- [1,1,null,{"sessionId":"5aaf3fb3-e81a-4cde-91d6-c1ffbc976850","capabilities":{"browserName":"firefox","browserVersion":"96.0a ... 2.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"proxy":{}}}]
[task 2021-11-19T22:53:43.993Z] 22:53:43     INFO - 1637362423992	Marionette	DEBUG	1 -> [0,2,"Addon:Install",{"path":"/builds/worker/workspace/build/tests/reftest/specialpowers","temporary":true}]
[task 2021-11-19T22:53:44.104Z] 22:53:44     INFO - 1637362424103	Marionette	DEBUG	1 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
[task 2021-11-19T22:53:44.110Z] 22:53:44     INFO - 1637362424109	Marionette	DEBUG	1 -> [0,3,"Addon:Install",{"path":"/builds/worker/workspace/build/tests/reftest/reftest","temporary":true}]
[task 2021-11-19T22:53:44.142Z] 22:53:44     INFO - 1637362424142	Marionette	TRACE	Received observer notification domwindowopened
[task 2021-11-19T22:53:44.145Z] 22:53:44     INFO - 1637362424144	Marionette	DEBUG	1 <- [1,3,null,{"value":"reftest@mozilla.org"}]
[task 2021-11-19T22:53:44.152Z] 22:53:44     INFO - 1637362424152	Marionette	DEBUG	1 -> [0,4,"WebDriver:DeleteSession",{}]
[task 2021-11-19T22:53:44.157Z] 22:53:44     INFO - 1637362424156	Marionette	DEBUG	1 <- [1,4,null,{"value":null}]
[task 2021-11-19T22:53:44.160Z] 22:53:44     INFO - 1637362424160	Marionette	DEBUG	Closed connection 1
[task 2021-11-19T22:53:44.233Z] 22:53:44     INFO - ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-11-19T22:53:44.235Z] 22:53:44     INFO - ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-11-19T22:53:45.013Z] 22:53:45     INFO - [CodeCoverage] Setting handlers for process 7657.
[task 2021-11-19T22:53:45.106Z] 22:53:45     INFO - REFTEST TEST-START | layout/reftests/position-dynamic-changes/vertical/topN-heightA-bottomA.html?padding_parent == layout/reftests/position-dynamic-changes/vertical/topN-heightA-bottomA-ref.html?padding_parent
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 4 years ago3 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.