Closed
Bug 1723167
Opened 4 years ago
Closed 4 years ago
Intermittent SUMMARY: ThreadSanitizer: SEGV /builds/worker/checkouts/gecko/gfx/wr/webrender/src/profiler.rs:1235:13 in webrender::profiler::register_thread::h3042ee117f6dd354
Categories
(Core :: Graphics: WebRender, defect, P5)
Core
Graphics: WebRender
Tracking
()
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
References
Details
(Keywords: intermittent-failure)
Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=346896581&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/DNE0Dth4QeOPspxIQA5W3w/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/DNE0Dth4QeOPspxIQA5W3w/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1
[task 2021-07-30T15:06:47.532Z] 15:06:47 INFO - REFTEST TEST-START | toolkit/themes/osx/reftests/nostretch.xhtml == toolkit/themes/osx/reftests/nostretch-ref.xhtml
[task 2021-07-30T15:06:47.532Z] 15:06:47 INFO - REFTEST TEST-SKIP | toolkit/themes/osx/reftests/nostretch.xhtml == toolkit/themes/osx/reftests/nostretch-ref.xhtml |
[task 2021-07-30T15:06:47.533Z] 15:06:47 INFO - REFTEST TEST-END | toolkit/themes/osx/reftests/nostretch.xhtml == toolkit/themes/osx/reftests/nostretch-ref.xhtml
[task 2021-07-30T15:06:47.533Z] 15:06:47 INFO - REFTEST INFO | Slowest test took 0ms (undefined)
[task 2021-07-30T15:06:47.534Z] 15:06:47 INFO - REFTEST INFO | Total canvas count = 0
[task 2021-07-30T15:06:48.131Z] 15:06:48 INFO - 1627657608129 Marionette TRACE Received observer notification quit-application
[task 2021-07-30T15:06:48.136Z] 15:06:48 INFO - 1627657608134 Marionette INFO Stopped listening on port 2828
[task 2021-07-30T15:06:48.137Z] 15:06:48 INFO - 1627657608135 Marionette DEBUG Marionette stopped listening
[task 2021-07-30T15:06:48.574Z] 15:06:48 INFO - JavaScript error: resource:///modules/Interactions.jsm, line 230: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIUserIdleService.removeIdleObserver]
[task 2021-07-30T15:06:49.627Z] 15:06:49 INFO - ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-07-30T15:06:50.508Z] 15:06:50 INFO - ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-07-30T15:06:59.443Z] 15:06:59 INFO - REFTEST INFO | Process mode: e10s
[task 2021-07-30T15:06:59.444Z] 15:06:59 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2021-07-30T15:06:59.444Z] 15:06:59 INFO - REFTEST INFO | Running tests in file:///builds/worker/workspace/build/tests/reftest/tests/layout/reftests/position-dynamic-changes/mixed/reftest_border_parent.list
[task 2021-07-30T15:06:59.482Z] 15:06:59 INFO - REFTEST INFO | INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2021-07-30T15:06:59.483Z] 15:06:59 INFO - REFTEST INFO | Running with e10s: True
[task 2021-07-30T15:06:59.483Z] 15:06:59 INFO - REFTEST INFO | Running with fission: False
[task 2021-07-30T15:06:59.483Z] 15:06:59 INFO - REFTEST INFO | Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -profile /tmp/tmpme4ornuu.mozrunner
[task 2021-07-30T15:07:04.547Z] 15:07:04 INFO - 1627657624545 Marionette INFO Marionette enabled
[task 2021-07-30T15:07:05.351Z] 15:07:05 INFO - 1627657625349 Marionette TRACE Received observer notification toplevel-window-ready
[task 2021-07-30T15:07:09.211Z] 15:07:09 INFO - ThreadSanitizer:DEADLYSIGNAL
[task 2021-07-30T15:07:09.213Z] 15:07:09 INFO - ==3858==ERROR: ThreadSanitizer: SEGV on unknown address 0x000000000018 (pc 0x7f6c5bd0abcf bp 0x7b0c000d0050 sp 0x7f6ba69e4dd0 T3929)
[task 2021-07-30T15:07:09.213Z] 15:07:09 INFO - ==3858==The signal is caused by a READ memory access.
[task 2021-07-30T15:07:09.214Z] 15:07:09 INFO - ==3858==Hint: address points to the zero page.
[task 2021-07-30T15:07:09.838Z] 15:07:09 INFO - #0 webrender::profiler::register_thread::h3042ee117f6dd354 /builds/worker/checkouts/gecko/gfx/wr/webrender/src/profiler.rs:1235:13 (libxul.so+0x8757bcf)
[task 2021-07-30T15:07:09.840Z] 15:07:09 INFO - #1 webrender::compositor::sw_compositor::SwCompositeThread::new::_$u7b$$u7b$closure$u7d$$u7d$::hcd815a1f77a165cf /builds/worker/checkouts/gecko/gfx/wr/webrender/src/compositor/sw_compositor.rs:479:17 (libxul.so+0x8757bcf)
[task 2021-07-30T15:07:09.842Z] 15:07:09 INFO - #2 std::sys_common::backtrace::__rust_begin_short_backtrace::hc6af76689b72f4cb /builds/worker/fetches/rustc/lib/rustlib/src/rust/library/std/src/sys_common/backtrace.rs:125:18 (libxul.so+0x8757bcf)
[task 2021-07-30T15:07:09.842Z] 15:07:09 INFO - ThreadSanitizer can not provide additional info.
[task 2021-07-30T15:07:09.842Z] 15:07:09 INFO - SUMMARY: ThreadSanitizer: SEGV /builds/worker/checkouts/gecko/gfx/wr/webrender/src/profiler.rs:1235:13 in webrender::profiler::register_thread::h3042ee117f6dd354
[task 2021-07-30T15:07:09.842Z] 15:07:09 INFO - ==3858==ABORTING
[task 2021-07-30T15:09:59.508Z] 15:09:59 ERROR - TEST-UNEXPECTED-FAIL | Last test finished | application terminated with exit code -6
[task 2021-07-30T15:09:59.519Z] 15:09:59 INFO - Traceback (most recent call last):
[task 2021-07-30T15:09:59.520Z] 15:09:59 INFO - File "/builds/worker/workspace/build/tests/reftest/runreftest.py", line 1172, in <module>
[task 2021-07-30T15:09:59.520Z] 15:09:59 INFO - sys.exit(run_test_harness(parser, options))
[task 2021-07-30T15:09:59.521Z] 15:09:59 INFO - File "/builds/worker/workspace/build/tests/reftest/runreftest.py", line 1164, in run_test_harness
[task 2021-07-30T15:09:59.521Z] 15:09:59 INFO - result = reftest.runTests(options.tests, options)
[task 2021-07-30T15:09:59.522Z] 15:09:59 INFO - File "/builds/worker/workspace/build/tests/reftest/runreftest.py", line 711, in runTests
[task 2021-07-30T15:09:59.523Z] 15:09:59 INFO - return self.runSerialTests(manifests, options, cmdargs)
[task 2021-07-30T15:09:59.523Z] 15:09:59 INFO - File "/builds/worker/workspace/build/tests/reftest/runreftest.py", line 1109, in runSerialTests
[task 2021-07-30T15:09:59.523Z] 15:09:59 INFO - status = run(tests=tests)
[task 2021-07-30T15:09:59.524Z] 15:09:59 INFO - File "/builds/worker/workspace/build/tests/reftest/runreftest.py", line 1079, in run
[task 2021-07-30T15:09:59.524Z] 15:09:59 INFO - **kwargs
[task 2021-07-30T15:09:59.524Z] 15:09:59 INFO - File "/builds/worker/workspace/build/tests/reftest/runreftest.py", line 1008, in runApp
[task 2021-07-30T15:09:59.527Z] 15:09:59 INFO - raise reraise(exc, value, tb)
[task 2021-07-30T15:09:59.527Z] 15:09:59 INFO - File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/six.py", line 696, in reraise
[task 2021-07-30T15:09:59.527Z] 15:09:59 INFO - raise value
[task 2021-07-30T15:09:59.527Z] 15:09:59 INFO - File "/builds/worker/workspace/build/tests/reftest/runreftest.py", line 965, in runApp
[task 2021-07-30T15:09:59.527Z] 15:09:59 INFO - marionette.start_session()
[task 2021-07-30T15:09:59.528Z] 15:09:59 INFO - File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 37, in _
[task 2021-07-30T15:09:59.529Z] 15:09:59 INFO - m._handle_socket_failure()
[task 2021-07-30T15:09:59.529Z] 15:09:59 INFO - File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 684, in _handle_socket_failure
[task 2021-07-30T15:09:59.529Z] 15:09:59 INFO - reraise(exc_cls, exc, tb)
[task 2021-07-30T15:09:59.529Z] 15:09:59 INFO - File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/six.py", line 696, in reraise
[task 2021-07-30T15:09:59.530Z] 15:09:59 INFO - raise value
[task 2021-07-30T15:09:59.531Z] 15:09:59 INFO - File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 27, in _
[task 2021-07-30T15:09:59.531Z] 15:09:59 INFO - return func(*args, **kwargs)
[task 2021-07-30T15:09:59.531Z] 15:09:59 INFO - File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 1181, in start_session
[task 2021-07-30T15:09:59.532Z] 15:09:59 INFO - self.raise_for_port(timeout=timeout)
[task 2021-07-30T15:09:59.532Z] 15:09:59 INFO - File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 600, in raise_for_port
[task 2021-07-30T15:09:59.532Z] 15:09:59 INFO - self.host, self.port
[task 2021-07-30T15:09:59.532Z] 15:09:59 INFO - socket.timeout: Timed out waiting for connection on 127.0.0.1:2828!
[task 2021-07-30T15:09:59.544Z] 15:09:59 INFO - Exception ignored in: <bound method BaseProfile.__del__ of <mozprofile.profile.Profile object at 0x7ffb12c01e10>>
[task 2021-07-30T15:09:59.544Z] 15:09:59 INFO - Traceback (most recent call last):
[task 2021-07-30T15:09:59.544Z] 15:09:59 INFO - File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/mozprofile/profile.py", line 86, in __del__
[task 2021-07-30T15:09:59.545Z] 15:09:59 INFO - File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/mozprofile/profile.py", line 298, in cleanup
[task 2021-07-30T15:09:59.545Z] 15:09:59 INFO - File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/mozprofile/profile.py", line 94, in cleanup
[task 2021-07-30T15:09:59.545Z] 15:09:59 INFO - File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/mozfile/mozfile.py", line 205, in remove
[task 2021-07-30T15:09:59.545Z] 15:09:59 INFO - ImportError: sys.meta_path is None, Python is likely shutting down
[task 2021-07-30T15:09:59.564Z] 15:09:59 ERROR - Return code: 1
[task 2021-07-30T15:09:59.565Z] 15:09:59 ERROR - No suite end message was emitted by this harness.
[task 2021-07-30T15:09:59.566Z] 15:09:59 INFO - TinderboxPrint: reftest-reftest<br/>689/0/1
[task 2021-07-30T15:09:59.568Z] 15:09:59 ERROR - # TBPL FAILURE #
[task 2021-07-30T15:09:59.568Z] 15:09:59 WARNING - setting return code to 2
[task 2021-07-30T15:09:59.568Z] 15:09:59 ERROR - The reftest suite: reftest ran with return status: FAILURE
[task 2021-07-30T15:09:59.568Z] 15:09:59 INFO - Running post-action listener: _package_coverage_data
[task 2021-07-30T15:09:59.568Z] 15:09:59 INFO - Running post-action listener: _resource_record_post_action
[task 2021-07-30T15:09:59.568Z] 15:09:59 INFO - Running post-action listener: process_java_coverage_data
[task 2021-07-30T15:09:59.568Z] 15:09:59 INFO - [mozharness: 2021-07-30 15:09:59.567114Z] Finished run-tests step (success)
[task 2021-07-30T15:09:59.568Z] 15:09:59 INFO - Running post-run listener: _resource_record_post_run
[task 2021-07-30T15:09:59.650Z] 15:09:59 INFO - Validating Perfherder data against /builds/worker/workspace/mozharness/external_tools/performance-artifact-schema.json
[task 2021-07-30T15:09:59.654Z] 15:09:59 INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": "reftest.reftest.8.overall", "extraOptions": ["e10s", "taskcluster-m5.large"], "subtests": [{"name": "cpu_percent", "value": 59.97683615819209}, {"name": "io_write_bytes", "value": 2393300992}, {"name": "io.read_bytes", "value": 37302272}, {"name": "io_write_time", "value": 239056}, {"name": "io_read_time", "value": 2864}]}, {"name": "reftest.reftest.8.start-pulseaudio", "subtests": [{"name": "time", "value": 0.028624534606933594}]}, {"name": "reftest.reftest.8.install", "subtests": [{"name": "time", "value": 34.44670128822327}, {"name": "cpu_percent", "value": 50.52727272727273}]}, {"name": "reftest.reftest.8.stage-files", "subtests": [{"name": "time", "value": 0.00024127960205078125}]}, {"name": "reftest.reftest.8.run-tests", "subtests": [{"name": "time", "value": 852.5894105434418}, {"name": "cpu_percent", "value": 60.39335294117647}]}]}
[task 2021-07-30T15:09:59.655Z] 15:09:59 INFO - Total resource usage - Wall time: 886s; CPU: 60%; Read bytes: 37302272; Write bytes: 2393300992; Read time: 2864; Write time: 239056
[task 2021-07-30T15:09:59.656Z] 15:09:59 INFO - TinderboxPrint: CPU usage<br/>60.4%
[task 2021-07-30T15:09:59.657Z] 15:09:59 INFO - TinderboxPrint: I/O read bytes / time<br/>37,302,272 / 2,864
[task 2021-07-30T15:09:59.657Z] 15:09:59 INFO - TinderboxPrint: I/O write bytes / time<br/>2,393,300,992 / 239,056
[task 2021-07-30T15:09:59.658Z] 15:09:59 INFO - TinderboxPrint: CPU idle<br/>698.6 (39.5%)
[task 2021-07-30T15:09:59.659Z] 15:09:59 INFO - TinderboxPrint: CPU system<br/>79.6 (4.5%)
[task 2021-07-30T15:09:59.660Z] 15:09:59 INFO - TinderboxPrint: CPU user<br/>979.2 (55.4%)
[task 2021-07-30T15:09:59.661Z] 15:09:59 INFO - TinderboxPrint: Swap in / out<br/>0 / 0
[task 2021-07-30T15:09:59.661Z] 15:09:59 INFO - start-pulseaudio - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2021-07-30T15:09:59.662Z] 15:09:59 INFO - install - Wall time: 34s; CPU: 51%; Read bytes: 204800; Write bytes: 1145651200; Read time: 0; Write time: 84680
[task 2021-07-30T15:09:59.663Z] 15:09:59 INFO - stage-files - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2021-07-30T15:09:59.665Z] 15:09:59 INFO - run-tests - Wall time: 853s; CPU: 60%; Read bytes: 36757504; Write bytes: 977379328; Read time: 1584; Write time: 114008
[task 2021-07-30T15:09:59.767Z] 15:09:59 WARNING - returning nonzero exit status 2
[task 2021-07-30T15:09:59.803Z] cleanup
[task 2021-07-30T15:09:59.803Z] + cleanup
[task 2021-07-30T15:09:59.803Z] + local rv=2
[task 2021-07-30T15:09:59.804Z] + [[ -s /builds/worker/.xsession-errors ]]
[task 2021-07-30T15:09:59.804Z] + cp /builds/worker/.xsession-errors /builds/worker/artifacts/public/xsession-errors.log
[task 2021-07-30T15:09:59.808Z] + '[' ']'
[task 2021-07-30T15:09:59.808Z] + true
[task 2021-07-30T15:09:59.808Z] + cleanup_xvfb
[task 2021-07-30T15:09:59.809Z] ++ pidof Xvfb
[task 2021-07-30T15:09:59.815Z] + local xvfb_pid=48
[task 2021-07-30T15:09:59.815Z] + local vnc=false
[task 2021-07-30T15:09:59.815Z] + local interactive=false
[task 2021-07-30T15:09:59.815Z] + '[' -n 48 ']'
[task 2021-07-30T15:09:59.815Z] + [[ false == false ]]
[task 2021-07-30T15:09:59.815Z] + [[ false == false ]]
[task 2021-07-30T15:09:59.815Z] + kill 48
[task 2021-07-30T15:09:59.825Z] + screen -XS xvfb quit
[task 2021-07-30T15:09:59.864Z] No screen session found.
[task 2021-07-30T15:09:59.865Z] + true
[task 2021-07-30T15:09:59.865Z] + exit 2
[taskcluster 2021-07-30 15:10:00.304Z] === Task Finished ===
[taskcluster 2021-07-30 15:10:01.239Z] Unsuccessful task run with exit code: 2 completed in 1194.472 seconds
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•4 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
|
||
Comment 3•4 years ago
|
||
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Comment 5•4 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: REOPENED → RESOLVED
Closed: 4 years ago → 4 years ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•