Open Bug 1470951 Opened 3 years ago Updated 4 days ago

Intermittent REFTEST ERROR | None | application timed out after 370 seconds with no output

Categories

(Testing :: Reftest, defect, P5)

Version 3
defect

Tracking

(Not tracked)

REOPENED

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, Whiteboard: [stockwell unknown])

Filed by: archaeopteryx [at] coole-files.de

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

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

https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://queue.taskcluster.net/v1/task/eBWdBZcUR6OhbjbN1qJyGA/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

[task 2018-06-24T02:52:37.001Z] 02:52:37     INFO - Calling ['/builds/worker/workspace/build/venv/bin/python', '-u', '/builds/worker/workspace/build/tests/reftest/runreftest.py', '--total-chunks', '3', '--this-chunk', '1', '--appname=/builds/worker/workspace/build/application/firefox/firefox', '--utility-path=tests/bin', '--extra-profile-file=tests/bin/plugins', '--symbols-path=https://queue.taskcluster.net/v1/task/Oj4eI0xfQACrq8UAVDiUww/artifacts/public/build/target.crashreporter-symbols.zip', '--log-raw=/builds/worker/workspace/build/blobber_upload_dir/jsreftest_raw.log', '--log-errorsummary=/builds/worker/workspace/build/blobber_upload_dir/jsreftest_errorsummary.log', '--cleanup-crashes', '--marionette-startup-timeout=180', '--sandbox-read-whitelist=/builds/worker/workspace/build', '--log-raw=-', '--extra-profile-file=tests/jsreftest/tests/user.js', '--suite=jstestbrowser', '--', 'tests/jsreftest/tests/jstests.list'] with output_timeout 1000
[task 2018-06-24T02:52:38.402Z] 02:52:38     INFO - REFTEST INFO | INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2018-06-24T02:52:38.551Z] 02:52:38     INFO - REFTEST INFO | INFO | runtests.py | ASan running in default memory configuration
[task 2018-06-24T02:52:38.552Z] 02:52:38     INFO - REFTEST INFO | Running with e10s: True
[task 2018-06-24T02:52:38.552Z] 02:52:38     INFO - REFTEST INFO | Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -profile /tmp/tmpKI8u0a.mozrunner
[task 2018-06-24T02:52:40.673Z] 02:52:40     INFO - 1529808760667	Marionette	DEBUG	Received observer notification profile-after-change
[task 2018-06-24T02:52:40.815Z] 02:52:40     INFO - 1529808760807	Marionette	DEBUG	Received observer notification command-line-startup
[task 2018-06-24T02:52:40.817Z] 02:52:40     INFO - 1529808760808	Marionette	DEBUG	Received observer notification nsPref:changed
[task 2018-06-24T02:52:40.818Z] 02:52:40     INFO - 1529808760808	Marionette	DEBUG	Init aborted (running=false, enabled=true, finalUIStartup=false)
[task 2018-06-24T02:52:44.583Z] 02:52:44     INFO - 1529808764578	Marionette	DEBUG	Received observer notification sessionstore-windows-restored
[task 2018-06-24T02:52:44.584Z] 02:52:44     INFO - 1529808764579	Marionette	DEBUG	Waiting for delayed startup...
[task 2018-06-24T02:52:45.906Z] 02:52:45     INFO - 1529808765903	Marionette	INFO	Listening on port 2828
[task 2018-06-24T02:52:45.906Z] 02:52:45     INFO - 1529808765904	Marionette	DEBUG	Remote service is active
[task 2018-06-24T02:52:45.963Z] 02:52:45     INFO - 1529808765956	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:33818
[task 2018-06-24T02:52:46.032Z] 02:52:46     INFO - 1529808766025	Marionette	DEBUG	Closed connection 0
[task 2018-06-24T02:52:46.034Z] 02:52:46     INFO - 1529808766028	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:33820
[task 2018-06-24T02:52:46.152Z] 02:52:46     INFO - 1529808766140	Marionette	TRACE	1 -> [0,1,"WebDriver:NewSession",{}]
[task 2018-06-24T02:52:46.632Z] 02:52:46     INFO - 1529808766620	Marionette	DEBUG	[2147483649] Frame script loaded
[task 2018-06-24T02:52:46.633Z] 02:52:46     INFO - 1529808766625	Marionette	DEBUG	[2147483649] Frame script registered
[task 2018-06-24T02:52:46.669Z] 02:52:46     INFO - 1529808766662	Marionette	TRACE	1 <- [1,1,null,{"sessionId":"6be8a701-fcca-42f0-9a0d-e93ddacdccc0","capabilities":{"browserName":"firefox","browserVersion":"62.0" ... essID":927,"moz:profile":"/tmp/tmpKI8u0a.mozrunner","moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2018-06-24T02:52:46.693Z] 02:52:46     INFO - 1529808766690	Marionette	TRACE	1 -> [0,2,"Addon:Install",{"path":"/builds/worker/workspace/build/tests/reftest/specialpowers","temporary":true}]
[task 2018-06-24T02:52:46.891Z] 02:52:46     INFO - 1529808766882	Marionette	TRACE	1 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
[task 2018-06-24T02:52:46.913Z] 02:52:46     INFO - 1529808766910	Marionette	TRACE	1 -> [0,3,"Addon:Install",{"path":"/builds/worker/workspace/build/tests/reftest/reftest","temporary":true}]
[task 2018-06-24T02:52:47.010Z] 02:52:47     INFO - 1529808767003	Marionette	TRACE	1 <- [1,3,null,{"value":"reftest@mozilla.org"}]
[task 2018-06-24T02:52:47.228Z] 02:52:47     INFO - 1529808767223	Marionette	TRACE	1 -> [0,4,"WebDriver:DeleteSession",{}]
[task 2018-06-24T02:52:47.232Z] 02:52:47     INFO - 1529808767228	Marionette	TRACE	1 <- [1,4,null,{"value":null}]
[task 2018-06-24T02:52:47.270Z] 02:52:47     INFO - 1529808767265	Marionette	DEBUG	Closed connection 1
[task 2018-06-24T02:52:49.262Z] 02:52:49     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FB,name=PContent::Msg_AccumulateChildKeyedHistograms) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:52:49.264Z] 02:52:49     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FF,name=PContent::Msg_RecordDiscardedData) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:52:53.728Z] 02:52:53     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FA,name=PContent::Msg_AccumulateChildHistograms) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:52:53.730Z] 02:52:53     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FF,name=PContent::Msg_RecordDiscardedData) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:52:58.069Z] 02:52:58     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FA,name=PContent::Msg_AccumulateChildHistograms) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:52:58.071Z] 02:52:58     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FF,name=PContent::Msg_RecordDiscardedData) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:53:00.064Z] 02:53:00     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FA,name=PContent::Msg_AccumulateChildHistograms) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:53:00.066Z] 02:53:00     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FB,name=PContent::Msg_AccumulateChildKeyedHistograms) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:53:00.067Z] 02:53:00     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FF,name=PContent::Msg_RecordDiscardedData) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:53:04.386Z] 02:53:04     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FA,name=PContent::Msg_AccumulateChildHistograms) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:53:04.388Z] 02:53:04     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FF,name=PContent::Msg_RecordDiscardedData) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:53:04.389Z] 02:53:04     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x550027,name=PNecko::Msg_RemoveRequestContext) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:53:06.382Z] 02:53:06     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FA,name=PContent::Msg_AccumulateChildHistograms) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:53:06.383Z] 02:53:06     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FF,name=PContent::Msg_RecordDiscardedData) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:53:30.677Z] 02:53:30     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FC,name=PContent::Msg_UpdateChildScalars) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:53:30.679Z] 02:53:30     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FF,name=PContent::Msg_RecordDiscardedData) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:53:45.204Z] 02:53:45     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FA,name=PContent::Msg_AccumulateChildHistograms) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:53:45.206Z] 02:53:45     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FF,name=PContent::Msg_RecordDiscardedData) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:53:56.146Z] 02:53:56     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D0108,name=PContent::Msg_AsyncMessage) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:53:56.146Z] 02:53:56     INFO - JavaScript error: resource://gre/modules/GCTelemetry.jsm, line 231: NS_ERROR_UNEXPECTED:
[task 2018-06-24T02:53:56.146Z] 02:53:56     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00B1,name=PContent::Msg_ScriptError) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:53:58.089Z] 02:53:58     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FA,name=PContent::Msg_AccumulateChildHistograms) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:53:58.091Z] 02:53:58     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FB,name=PContent::Msg_AccumulateChildKeyedHistograms) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:53:58.093Z] 02:53:58     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FF,name=PContent::Msg_RecordDiscardedData) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:57:48.727Z] 02:57:48     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D0108,name=PContent::Msg_AsyncMessage) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:57:48.727Z] 02:57:48     INFO - JavaScript error: resource://gre/modules/GCTelemetry.jsm, line 231: NS_ERROR_UNEXPECTED:
[task 2018-06-24T02:57:48.727Z] 02:57:48     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00B1,name=PContent::Msg_ScriptError) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:57:50.605Z] 02:57:50     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FA,name=PContent::Msg_AccumulateChildHistograms) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:57:50.607Z] 02:57:50     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FB,name=PContent::Msg_AccumulateChildKeyedHistograms) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:57:50.609Z] 02:57:50     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FF,name=PContent::Msg_RecordDiscardedData) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:58:48.779Z] 02:58:48     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D0108,name=PContent::Msg_AsyncMessage) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:58:48.779Z] 02:58:48     INFO - JavaScript error: resource://gre/modules/GCTelemetry.jsm, line 231: NS_ERROR_UNEXPECTED:
[task 2018-06-24T02:58:48.780Z] 02:58:48     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00B1,name=PContent::Msg_ScriptError) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:58:50.712Z] 02:58:50     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FA,name=PContent::Msg_AccumulateChildHistograms) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:58:50.713Z] 02:58:50     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FB,name=PContent::Msg_AccumulateChildKeyedHistograms) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T02:58:50.715Z] 02:58:50     INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FF,name=PContent::Msg_RecordDiscardedData) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-24T03:05:00.728Z] 03:05:00    ERROR - REFTEST ERROR | None | application timed out after 370 seconds with no output
[task 2018-06-24T03:05:00.730Z] 03:05:00    ERROR - REFTEST ERROR | Force-terminating active process(es).
There have been 57 failures in the last 7 days.

Failures per platform and build type:
- linux64 / opt & debug: 27
- linux64-qr / opt & debug: 18
- linux32 / opt & debug: 10
- osx-10-10/ debug: 1
- windows10-64-ccov: 1

Recent log file:
https://treeherder.mozilla.org/logviewer.html#?job_id=192886846&repo=mozilla-inbound&lineNumber=1443

Reftest analyzer:
https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://queue.taskcluster.net/v1/task/UYRq3f2IS0228FMFVfoqFg/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1&only_show_unexpected=1
Flags: needinfo?(ahal)
Whiteboard: [stockwell needswork]
This fails roughly 1 in 3000 pushes. Given it's basically impossible to reproduce and I don't have anything to add just by reading the logs, might as well clear my long standing needinfo.
Flags: needinfo?(ahal)
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 3 years ago2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 2 years ago2 years ago
Resolution: --- → INCOMPLETE
Status: REOPENED → RESOLVED
Closed: 2 years ago1 year ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 1 year ago1 year ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 1 year ago1 month ago
Resolution: --- → INCOMPLETE

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=349015840&repo=mozilla-central&lineNumber=4929

[task 2021-08-20T11:11:14.002Z] 11:11:14     INFO - Calling ['/builds/worker/workspace/build/venv/bin/python', '-u', '/builds/worker/workspace/build/tests/reftest/runreftest.py', '--enable-webrender', '--setpref=media.peerconnection.mtransport_process=false', '--setpref=network.process.enabled=false', '--setpref=layers.d3d11.enable-blacklist=false', '--appname=/builds/worker/workspace/build/application/firefox/firefox', '--utility-path=tests/bin', '--extra-profile-file=tests/bin/plugins', '--symbols-path=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/dPNFnBUKQuOPYOCFaUploQ/artifacts/public/build/target.crashreporter-symbols.zip', '--log-raw=/builds/worker/workspace/build/blobber_upload_dir/crashtest-test8_raw.log', '--log-errorsummary=/builds/worker/workspace/build/blobber_upload_dir/crashtest-test8_errorsummary.log', '--cleanup-crashes', '--marionette-startup-timeout=180', '--sandbox-read-whitelist=/builds/worker/workspace/build', '--log-raw=-', '--suite=crashtest', '--topsrcdir=tests/reftest/tests', '/builds/worker/workspace/build/tests/reftest/tests/layout/base/crashtests/1723200.html'] with output_timeout 1000
[task 2021-08-20T11:11:14.260Z] 11:11:14     INFO - REFTEST INFO | Removed pending crash reports at '/builds/worker/.mozilla/firefox/Crash Reports'
[task 2021-08-20T11:11:14.281Z] 11:11:14     INFO - REFTEST INFO | Running with e10s: True
[task 2021-08-20T11:11:14.282Z] 11:11:14     INFO - REFTEST INFO | Running with fission: False
[task 2021-08-20T11:11:14.282Z] 11:11:14     INFO - REFTEST INFO | Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -profile /tmp/tmpbz240i3h.mozrunner
[task 2021-08-20T11:11:14.338Z] 11:11:14     INFO - [CodeCoverage] Setting handlers for process 3786.
[task 2021-08-20T11:11:15.191Z] 11:11:15     INFO - 1629457875190	Marionette	INFO	Marionette enabled
[task 2021-08-20T11:11:15.308Z] 11:11:15     INFO - 1629457875307	Marionette	TRACE	Received observer notification toplevel-window-ready
[task 2021-08-20T11:11:16.472Z] 11:11:16     INFO - [CodeCoverage] Setting handlers for process 3869.
[task 2021-08-20T11:11:16.651Z] 11:11:16     INFO - [CodeCoverage] Setting handlers for process 3872.
[task 2021-08-20T11:11:18.024Z] 11:11:18     INFO - [CodeCoverage] Setting handlers for process 3933.
[task 2021-08-20T11:11:19.367Z] 11:11:19     INFO - console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/tmpbz240i3h.mozrunner/search.json.mozlz4", (void 0)))
[task 2021-08-20T11:11:20.363Z] 11:11:20     INFO - 1629457880362	Marionette	TRACE	Received observer notification marionette-startup-requested
[task 2021-08-20T11:11:20.364Z] 11:11:20     INFO - 1629457880362	Marionette	TRACE	Waiting until startup recorder finished recording startup scripts...
[task 2021-08-20T11:11:20.379Z] 11:11:20     INFO - 1629457880377	Marionette	TRACE	All scripts recorded.
[task 2021-08-20T11:11:20.382Z] 11:11:20     INFO - 1629457880381	Marionette	INFO	Listening on port 2828
[task 2021-08-20T11:11:20.383Z] 11:11:20     INFO - 1629457880381	Marionette	DEBUG	Marionette is listening
[task 2021-08-20T11:11:20.429Z] 11:11:20     INFO - 1629457880428	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:36648
[task 2021-08-20T11:11:20.460Z] 11:11:20     INFO - 1629457880459	Marionette	DEBUG	Closed connection 0
[task 2021-08-20T11:11:20.460Z] 11:11:20     INFO - 1629457880459	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:36650
[task 2021-08-20T11:11:20.492Z] 11:11:20     INFO - 1629457880491	Marionette	DEBUG	1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2021-08-20T11:11:20.515Z] 11:11:20     INFO - 1629457880514	Marionette	DEBUG	1 <- [1,1,null,{"sessionId":"97521cbe-c357-4409-830c-0f2122025513","capabilities":{"browserName":"firefox","browserVersion":"93.0a ... h.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"proxy":{}}}]
[task 2021-08-20T11:11:20.524Z] 11:11:20     INFO - 1629457880523	Marionette	DEBUG	1 -> [0,2,"Addon:Install",{"path":"/builds/worker/workspace/build/tests/reftest/specialpowers","temporary":true}]
[task 2021-08-20T11:11:20.560Z] 11:11:20     INFO - 1629457880558	Marionette	DEBUG	1 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
[task 2021-08-20T11:11:20.572Z] 11:11:20     INFO - 1629457880570	Marionette	DEBUG	1 -> [0,3,"Addon:Install",{"path":"/builds/worker/workspace/build/tests/reftest/reftest","temporary":true}]
[task 2021-08-20T11:11:20.624Z] 11:11:20     INFO - 1629457880623	Marionette	TRACE	Received observer notification domwindowopened
[task 2021-08-20T11:11:20.628Z] 11:11:20     INFO - 1629457880627	Marionette	DEBUG	1 <- [1,3,null,{"value":"reftest@mozilla.org"}]
[task 2021-08-20T11:11:20.639Z] 11:11:20     INFO - 1629457880638	Marionette	DEBUG	1 -> [0,4,"WebDriver:DeleteSession",{}]
[task 2021-08-20T11:11:20.647Z] 11:11:20     INFO - 1629457880646	Marionette	DEBUG	1 <- [1,4,null,{"value":null}]
[task 2021-08-20T11:11:20.650Z] 11:11:20     INFO - 1629457880649	Marionette	DEBUG	Closed connection 1
[task 2021-08-20T11:11:22.011Z] 11:11:22     INFO - [CodeCoverage] Setting handlers for process 3987.
[task 2021-08-20T11:17:32.030Z] 11:17:32    ERROR - REFTEST ERROR | None | application timed out after 370 seconds with no output
[task 2021-08-20T11:17:32.031Z] 11:17:32    ERROR - REFTEST ERROR | Force-terminating active process(es).
[task 2021-08-20T11:17:32.031Z] 11:17:32     INFO - REFTEST TEST-INFO | started process screentopng
[task 2021-08-20T11:17:32.233Z] 11:17:32     INFO - REFTEST TEST-INFO | screentopng: exit 0
[task 2021-08-20T11:17:32.360Z] 11:17:32    ERROR - TEST-UNEXPECTED-FAIL | None | application terminated with exit code 6
[task 2021-08-20T11:17:32.361Z] 11:17:32     INFO - REFTEST INFO | Downloading symbols from: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/dPNFnBUKQuOPYOCFaUploQ/artifacts/public/build/target.crashreporter-symbols.zip
[task 2021-08-20T11:17:37.184Z] 11:17:37     INFO - REFTEST INFO | Copy/paste: /builds/worker/fetches/minidump_stackwalk/minidump_stackwalk /tmp/tmpbz240i3h.mozrunner/minidumps/6c241e5e-3f09-9c20-d37c-e734ba90f647.dmp /tmp/tmpsscmqlvo
[task 2021-08-20T11:17:42.269Z] 11:17:42     INFO - REFTEST INFO | Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/6c241e5e-3f09-9c20-d37c-e734ba90f647.dmp
[task 2021-08-20T11:17:42.270Z] 11:17:42     INFO - REFTEST INFO | Saved app info as /builds/worker/workspace/build/blobber_upload_dir/6c241e5e-3f09-9c20-d37c-e734ba90f647.extra
[task 2021-08-20T11:17:42.486Z] 11:17:42     INFO - REFTEST PROCESS-CRASH | pid: None | application crashed [@ ld-linux-x86-64.so.2 + 0x19a58]
[task 2021-08-20T11:17:42.486Z] 11:17:42     INFO - Crash dump filename: /tmp/tmpbz240i3h.mozrunner/minidumps/6c241e5e-3f09-9c20-d37c-e734ba90f647.dmp
[task 2021-08-20T11:17:42.487Z] 11:17:42     INFO - Operating system: Linux
[task 2021-08-20T11:17:42.487Z] 11:17:42     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-08-20T11:17:42.487Z] 11:17:42     INFO - CPU: amd64
[task 2021-08-20T11:17:42.487Z] 11:17:42     INFO -      family 6 model 85 stepping 4
[task 2021-08-20T11:17:42.487Z] 11:17:42     INFO -      4 CPUs
[task 2021-08-20T11:17:42.487Z] 11:17:42     INFO - 
[task 2021-08-20T11:17:42.487Z] 11:17:42     INFO - GPU: UNKNOWN
[task 2021-08-20T11:17:42.487Z] 11:17:42     INFO - 
[task 2021-08-20T11:17:42.488Z] 11:17:42     INFO - Crash reason:  SIGABRT
[task 2021-08-20T11:17:42.488Z] 11:17:42     INFO - Crash address: 0x3e800000ebf
[task 2021-08-20T11:17:42.488Z] 11:17:42     INFO - Process uptime: not available
[task 2021-08-20T11:17:42.488Z] 11:17:42     INFO - 
[task 2021-08-20T11:17:42.488Z] 11:17:42     INFO - Thread 0 tid 3786 (crashed) 0  libc.so.6 + 0x114cf9
[task 2021-08-20T11:17:42.488Z] 11:17:42     INFO -     rax = 0xfffffffffffffffc   rdx = 0x00000000ffffffff
[task 2021-08-20T11:17:42.489Z] 11:17:42     INFO -     rcx = 0x00007f7895b59cf9   rbx = 0x00007f784398dc20
[task 2021-08-20T11:17:42.489Z] 11:17:42     INFO -     rsi = 0x0000000000000004   rdi = 0x00007f784398dc20
[task 2021-08-20T11:17:42.489Z] 11:17:42     INFO -     rbp = 0x0000000000000004   rsp = 0x00007fff83206290
[task 2021-08-20T11:17:42.489Z] 11:17:42     INFO -      r8 = 0x0000000000000000    r9 = 0x00007f7896d9b0e0
[task 2021-08-20T11:17:42.489Z] 11:17:42     INFO -     r10 = 0x00007f784395bfa0   r11 = 0x0000000000000293
[task 2021-08-20T11:17:42.490Z] 11:17:42     INFO -     r12 = 0x00000000ffffffff   r13 = 0x00000000ffffffff
[task 2021-08-20T11:17:42.490Z] 11:17:42     INFO -     r14 = 0x00000000ffffffff   r15 = 0x0000000000000004
[task 2021-08-20T11:17:42.490Z] 11:17:42     INFO -     rip = 0x00007f7895b59cf9
[task 2021-08-20T11:17:42.490Z] 11:17:42     INFO -     Found by: given as instruction pointer in context
[task 2021-08-20T11:17:42.495Z] 11:17:42     INFO -  1  ld-linux-x86-64.so.2 + 0x19a58
[task 2021-08-20T11:17:42.495Z] 11:17:42     INFO -     rsp = 0x00007fff832062a0   rip = 0x00007f7896bb1a58
[task 2021-08-20T11:17:42.496Z] 11:17:42     INFO -     Found by: stack scanning
[task 2021-08-20T11:17:42.496Z] 11:17:42     INFO -  2  libxul.so!PollWrapper(_GPollFD*, unsigned int, int) [nsAppShell.cpp:1e6d20eb3a0101716a0e8aa5614b74b1563e6089 : 58 + 0xf]
[task 2021-08-20T11:17:42.496Z] 11:17:42     INFO -     rsp = 0x00007fff832062c0   rip = 0x00007f78804b5294
[task 2021-08-20T11:17:42.496Z] 11:17:42     INFO -     Found by: stack scanning
[task 2021-08-20T11:17:42.497Z] 11:17:42     INFO -  3  libxul.so + 0xe963190
[task 2021-08-20T11:17:42.497Z] 11:17:42     INFO -     rsp = 0x00007fff832062e8   rip = 0x00007f78804b5190
[task 2021-08-20T11:17:42.497Z] 11:17:42     INFO -     Found by: stack scanning
[task 2021-08-20T11:17:42.498Z] 11:17:42     INFO -  4  libglib-2.0.so.0!g_main_context_iterate.isra.26 [gmain.c : 3897 + 0x24]
[task 2021-08-20T11:17:42.498Z] 11:17:42     INFO -     rsp = 0x00007fff83206300   rip = 0x00007f78916f46e9
[task 2021-08-20T11:17:42.498Z] 11:17:42     INFO -     Found by: stack scanning
[task 2021-08-20T11:17:42.498Z] 11:17:42     INFO -  5  libglib-2.0.so.0!g_main_context_iteration [gmain.c : 3963 + 0x14]
[task 2021-08-20T11:17:42.498Z] 11:17:42     INFO -     rbx = 0x00007f7871324870   rbp = 0x0000000000000001
[task 2021-08-20T11:17:42.499Z] 11:17:42     INFO -     rsp = 0x00007fff83206360   r12 = 0x00007f7895892c80
[task 2021-08-20T11:17:42.499Z] 11:17:42     INFO -     r13 = 0x0000000000000001   r14 = 0x0000000000000000
[task 2021-08-20T11:17:42.499Z] 11:17:42     INFO -     r15 = 0x0000000000000001   rip = 0x00007f78916f47fc
[task 2021-08-20T11:17:42.499Z] 11:17:42     INFO -     Found by: call frame info
[task 2021-08-20T11:17:42.499Z] 11:17:42     INFO -  6  libxul.so!nsAppShell::ProcessNextNativeEvent(bool) + 0x18
[task 2021-08-20T11:17:42.499Z] 11:17:42     INFO -     rbx = 0x00007f78713e8740   rbp = 0x00007fff83206390
[task 2021-08-20T11:17:42.500Z] 11:17:42     INFO -     rsp = 0x00007fff83206380   r12 = 0x00007f7895892c80
[task 2021-08-20T11:17:42.500Z] 11:17:42     INFO -     r13 = 0x0000000000000001   r14 = 0x0000000000000000
[task 2021-08-20T11:17:42.500Z] 11:17:42     INFO -     r15 = 0x0000000000000001   rip = 0x00007f78804b5518
[task 2021-08-20T11:17:42.500Z] 11:17:42     INFO -     Found by: call frame info
[task 2021-08-20T11:17:42.500Z] 11:17:42     INFO -  7  libxul.so!nsBaseAppShell::OnProcessNextEvent(nsIThreadInternal*, bool) [nsBaseAppShell.cpp:1e6d20eb3a0101716a0e8aa5614b74b1563e6089 : 259 + 0x3d]
[task 2021-08-20T11:17:42.500Z] 11:17:42     INFO -     rbx = 0x00007f78713e8740   rbp = 0x00007fff832063f0
[task 2021-08-20T11:17:42.500Z] 11:17:42     INFO -     rsp = 0x00007fff832063a0   r12 = 0x00007f7895892c80
[task 2021-08-20T11:17:42.500Z] 11:17:42     INFO -     r13 = 0x0000000000000001   r14 = 0x0000000000000000
[task 2021-08-20T11:17:42.500Z] 11:17:42     INFO -     r15 = 0x0000000000000001   rip = 0x00007f78803c7180
[task 2021-08-20T11:17:42.500Z] 11:17:42     INFO -     Found by: call frame info
[task 2021-08-20T11:17:42.500Z] 11:17:42     INFO -  8  libxul.so!{virtual override thunk({offset(-8)}, nsBaseAppShell::OnProcessNextEvent(nsIThreadInternal*, bool))} + 0xd
[task 2021-08-20T11:17:42.501Z] 11:17:42     INFO -     rbx = 0x0000000000000001   rbp = 0x00007fff83206400
[task 2021-08-20T11:17:42.501Z] 11:17:42     INFO -     rsp = 0x00007fff83206400   r12 = 0x00007f7895892c80
[task 2021-08-20T11:17:42.501Z] 11:17:42     INFO -     r13 = 0x00007f78713f9fc0   r14 = 0x00007f7871302001
[task 2021-08-20T11:17:42.501Z] 11:17:42     INFO -     r15 = 0x00007f78713e8748   rip = 0x00007f78803c73bd
[task 2021-08-20T11:17:42.501Z] 11:17:42     INFO -     Found by: call frame info
[task 2021-08-20T11:17:42.501Z] 11:17:42     INFO -  9  libxul.so!nsThread::ProcessNextEvent(bool, bool*) [nsThread.cpp:1e6d20eb3a0101716a0e8aa5614b74b1563e6089 : 1064 + 0x28]
[task 2021-08-20T11:17:42.502Z] 11:17:42     INFO -     rbx = 0x0000000000000001   rbp = 0x00007fff83206530
[task 2021-08-20T11:17:42.502Z] 11:17:42     INFO -     rsp = 0x00007fff83206410   r12 = 0x00007f7895892c80
[task 2021-08-20T11:17:42.503Z] 11:17:42     INFO -     r13 = 0x00007f78713f9fc0   r14 = 0x00007f7871302001
[task 2021-08-20T11:17:42.503Z] 11:17:42     INFO -     r15 = 0x00007f78713e8748   rip = 0x00007f787a633942
[task 2021-08-20T11:17:42.503Z] 11:17:42     INFO -     Found by: call frame info
[task 2021-08-20T11:17:42.503Z] 11:17:42     INFO - 10  libxul.so!NS_ProcessNextEvent(nsIThread*, bool) + 0x3f
[task 2021-08-20T11:17:42.504Z] 11:17:42     INFO -     rbx = 0x0000000000000001   rbp = 0x00007fff83206560
[task 2021-08-20T11:17:42.504Z] 11:17:42     INFO -     rsp = 0x00007fff83206540   r12 = 0x00007f78713f9fe0
[task 2021-08-20T11:17:42.504Z] 11:17:42     INFO -     r13 = 0x00007f78713f9fc0   r14 = 0x00007f78713020c0
[task 2021-08-20T11:17:42.504Z] 11:17:42     INFO -     r15 = 0x00007f7895892c80   rip = 0x00007f787a63b80f
[task 2021-08-20T11:17:42.504Z] 11:17:42     INFO -     Found by: call frame info
[task 2021-08-20T11:17:42.505Z] 11:17:42     INFO - 11  libxul.so!mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate*) [MessagePump.cpp:1e6d20eb3a0101716a0e8aa5614b74b1563e6089 : 107 + 0x15]
[task 2021-08-20T11:17:42.505Z] 11:17:42     INFO -     rbx = 0x0000000000000000   rbp = 0x00007fff832065a0
[task 2021-08-20T11:17:42.505Z] 11:17:42     INFO -     rsp = 0x00007fff83206570   r12 = 0x00007f78713f9fe0
[task 2021-08-20T11:17:42.506Z] 11:17:42     INFO -     r13 = 0x00007f78713f9fc0   r14 = 0x00007f78713020c0
[task 2021-08-20T11:17:42.506Z] 11:17:42     INFO -     r15 = 0x00007f7895892c80   rip = 0x00007f787b30d835
[task 2021-08-20T11:17:42.506Z] 11:17:42     INFO -     Found by: call frame info
[task 2021-08-20T11:17:42.506Z] 11:17:42     INFO - 12  libxul.so!MessageLoop::Run() [message_loop.cc:1e6d20eb3a0101716a0e8aa5614b74b1563e6089 : 306 + 0xc]
[task 2021-08-20T11:17:42.506Z] 11:17:42     INFO -     rbx = 0x00007f78713e8740   rbp = 0x00007fff832065d0
[task 2021-08-20T11:17:42.507Z] 11:17:42     INFO -     rsp = 0x00007fff832065b0   r12 = 0x00007fff83206878
[task 2021-08-20T11:17:42.507Z] 11:17:42     INFO -     r13 = 0x00007fff83206870   r14 = 0x00007f7895892c80
[task 2021-08-20T11:17:42.507Z] 11:17:42     INFO -     r15 = 0x00007fff83206929   rip = 0x00007f787b2162de
[task 2021-08-20T11:17:42.507Z] 11:17:42     INFO -     Found by: call frame info
[task 2021-08-20T11:17:42.507Z] 11:17:42     INFO - 13  libxul.so!nsBaseAppShell::Run() + 0x31
[task 2021-08-20T11:17:42.507Z] 11:17:42     INFO -     rbx = 0x00007f78713e8740   rbp = 0x00007fff832065f0
[task 2021-08-20T11:17:42.507Z] 11:17:42     INFO -     rsp = 0x00007fff832065e0   r12 = 0x00007fff83206878
[task 2021-08-20T11:17:42.507Z] 11:17:42     INFO -     r13 = 0x00007fff83206870   r14 = 0x00007f7895892c80
[task 2021-08-20T11:17:42.507Z] 11:17:42     INFO -     r15 = 0x00007fff83206929   rip = 0x00007f78803c6d11
[task 2021-08-20T11:17:42.507Z] 11:17:42     INFO -     Found by: call frame info
[task 2021-08-20T11:17:42.507Z] 11:17:42     INFO - 14  libxul.so!nsAppStartup::Run() [nsAppStartup.cpp:1e6d20eb3a0101716a0e8aa5614b74b1563e6089 : 284 + 0xa]
[task 2021-08-20T11:17:42.507Z] 11:17:42     INFO -     rbx = 0x00007f78713bc970   rbp = 0x00007fff83206620
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO -     rsp = 0x00007fff83206600   r12 = 0x00007fff83206878
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO -     r13 = 0x00007fff83206870   r14 = 0x0000000000000000
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO -     r15 = 0x00007fff83206929   rip = 0x00007f78829455cd
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO -     Found by: call frame info
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO - 15  libxul.so!XREMain::XRE_mainRun() [nsAppRunner.cpp:1e6d20eb3a0101716a0e8aa5614b74b1563e6089 : 5287 + 0x35]
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO -     rbx = 0x0000000080004005   rbp = 0x00007fff83206790
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO -     rsp = 0x00007fff83206630   r12 = 0x00007fff83206878
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO -     r13 = 0x00007fff83206870   r14 = 0x0000000000000000
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO -     r15 = 0x00007fff83206929   rip = 0x00007f7882b391d7
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO -     Found by: call frame info
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO - 16  libxul.so!XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) [nsAppRunner.cpp:1e6d20eb3a0101716a0e8aa5614b74b1563e6089 : 5479 + 0x8]
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO -     rbx = 0x00007fff832067c0   rbp = 0x00007fff83206830
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO -     rsp = 0x00007fff832067a0   r12 = 0x00007fff83206878
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO -     r13 = 0x00007fff83206870   r14 = 0x00007fff83206840
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO -     r15 = 0x0000560a98247bed   rip = 0x00007f7882b3b967
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO -     Found by: call frame info
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO - 17  libxul.so!XRE_main(int, char**, mozilla::BootstrapConfig const&) + 0x5e
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO -     rbx = 0x0000000000000004   rbp = 0x00007fff83206960
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO -     rsp = 0x00007fff83206840   r12 = 0x00007fff83206840
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO -     r13 = 0x00007fff83207ab0   r14 = 0x00007fff832069a0
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO -     r15 = 0x00007fff83207ab8   rip = 0x00007f7882b3c39e
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO -     Found by: call frame info
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO - 18  firefox-bin!main [nsBrowserApp.cpp:1e6d20eb3a0101716a0e8aa5614b74b1563e6089 : 378 + 0x10a]
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO -     rbx = 0x00007fff83207ab8   rbp = 0x00007fff832079d0
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO -     rsp = 0x00007fff83206970   r12 = 0x0000000000000004
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO -     r13 = 0x00007fff83207ab0   r14 = 0x00007fff83207ae0
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO -     r15 = 0x0000560a98247bed   rip = 0x0000560a98276236
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO -     Found by: call frame info
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO - 19  libc.so.6 + 0x21b97
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO -     rbx = 0x0000000000000000   rbp = 0x0000560a9836cd40
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO -     rsp = 0x00007fff832079e0   r12 = 0x0000560a98275960
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO -     r13 = 0x00007fff83207ab0   r14 = 0x0000000000000000
[task 2021-08-20T11:17:42.508Z] 11:17:42     INFO -     r15 = 0x0000000000000000   rip = 0x00007f7895a66b97
[task 2021-08-20T11:17:42.509Z] 11:17:42     INFO -     Found by: call frame info
[task 2021-08-20T11:17:42.509Z] 11:17:42     INFO - 20  firefox-bin + 0x5fba0
[task 2021-08-20T11:17:42.509Z] 11:17:42     INFO -     rsp = 0x00007fff83207a00   rip = 0x0000560a98275ba0
[task 2021-08-20T11:17:42.509Z] 11:17:42     INFO -     Found by: stack scanning
[task 2021-08-20T11:17:42.509Z] 11:17:42     INFO - 21  firefox-bin + 0x5f960
[task 2021-08-20T11:17:42.509Z] 11:17:42     INFO -     rsp = 0x00007fff83207a18   rip = 0x0000560a98275960
[task 2021-08-20T11:17:42.509Z] 11:17:42     INFO -     Found by: stack scanning
[task 2021-08-20T11:17:42.509Z] 11:17:42     INFO - 22  ld-linux-x86-64.so.2 + 0x10783
[task 2021-08-20T11:17:42.509Z] 11:17:42     INFO -     rsp = 0x00007fff83207a60   rip = 0x00007f7896ba8783
[task 2021-08-20T11:17:42.509Z] 11:17:42     INFO -     Found by: stack scanning
[task 2021-08-20T11:17:42.509Z] 11:17:42     INFO - 23  libdl.so.2 + 0x202d80
[task 2021-08-20T11:17:42.509Z] 11:17:42     INFO -     rsp = 0x00007fff83207a68   rip = 0x00007f7896977d80
[task 2021-08-20T11:17:42.509Z] 11:17:42     INFO -     Found by: stack scanning
[task 2021-08-20T11:17:42.509Z] 11:17:42     INFO - 24  libpthread.so.0 + 0x219bb0
[task 2021-08-20T11:17:42.509Z] 11:17:42     INFO -     rsp = 0x00007fff83207a70   rip = 0x00007f7896b92bb0
[task 2021-08-20T11:17:42.510Z] 11:17:42     INFO -     Found by: stack scanning
[task 2021-08-20T11:17:42.510Z] 11:17:42     INFO - 25  firefox-bin + 0x5f960
[task 2021-08-20T11:17:42.510Z] 11:17:42     INFO -     rsp = 0x00007fff83207a90   rip = 0x0000560a98275960
[task 2021-08-20T11:17:42.510Z] 11:17:42     INFO -     Found by: stack scanning
[task 2021-08-20T11:17:42.510Z] 11:17:42     INFO - 26  firefox-bin!_start + 0x29
[task 2021-08-20T11:17:42.510Z] 11:17:42     INFO -     rsp = 0x00007fff83207aa0   rip = 0x0000560a98275989
[task 2021-08-20T11:17:42.510Z] 11:17:42     INFO -     Found by: stack scanning
[task 2021-08-20T11:17:42.510Z] 11:17:42     INFO - 
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
You need to log in before you can comment on or make changes to this bug.