Closed
Bug 1835195
Opened 3 years ago
Closed 2 years ago
Intermittent image/test/reftest/ico/ico-bmp-4bpp/reftest.list | application terminated with exit code 127
Categories
(Core :: Layout: Form Controls, defect, P5)
Core
Layout: Form Controls
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=417112275&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/LcniwRo_QyiZp6Kc1tXAqg/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/LcniwRo_QyiZp6Kc1tXAqg/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1
[task 2023-05-26T01:32:25.157Z] 01:32:25 INFO - REFTEST TEST-PASS | image/test/reftest/ico/ico-bmp-4bpp/ico-not-square-transparent-4bpp.ico == image/test/reftest/ico/ico-bmp-4bpp/ico-not-square-transparent-4bpp.png | image comparison, max difference: 0, number of differing pixels: 0
[task 2023-05-26T01:32:25.159Z] 01:32:25 INFO - REFTEST TEST-END | image/test/reftest/ico/ico-bmp-4bpp/ico-not-square-transparent-4bpp.ico == image/test/reftest/ico/ico-bmp-4bpp/ico-not-square-transparent-4bpp.png
[task 2023-05-26T01:32:25.247Z] 01:32:25 INFO - REFTEST INFO | Slowest test took 658ms (file:///builds/worker/workspace/build/tests/reftest/tests/image/test/reftest/ico/ico-bmp-4bpp/ico-size-256x256-4bpp.ico)
[task 2023-05-26T01:32:25.248Z] 01:32:25 INFO - REFTEST INFO | Total canvas count = 2
[task 2023-05-26T01:32:29.143Z] 01:32:29 INFO - 1685064749142 Marionette TRACE Received observer notification quit-application
[task 2023-05-26T01:32:29.148Z] 01:32:29 INFO - 1685064749143 Marionette INFO Stopped listening on port 2828
[task 2023-05-26T01:32:29.153Z] 01:32:29 INFO - 1685064749150 Marionette DEBUG Marionette stopped listening
[task 2023-05-26T01:32:33.220Z] 01:32:33 INFO - REFTEST INFO | Process mode: e10s
[task 2023-05-26T01:32:33.221Z] 01:32:33 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2023-05-26T01:32:33.224Z] 01:32:33 INFO - REFTEST INFO | Running tests in file:///builds/worker/workspace/build/tests/reftest/tests/layout/reftests/css-placeholder/input/reftest.list
[task 2023-05-26T01:32:33.234Z] 01:32:33 INFO - REFTEST INFO | Running with e10s: True
[task 2023-05-26T01:32:33.234Z] 01:32:33 INFO - REFTEST INFO | Running with fission: True
[task 2023-05-26T01:32:33.235Z] 01:32:33 INFO - REFTEST INFO | INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2023-05-26T01:32:33.236Z] 01:32:33 INFO - REFTEST INFO | Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -profile /tmp/tmpg0lk1sef.mozrunner
[task 2023-05-26T01:32:36.489Z] 01:32:36 INFO - ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2023-05-26T01:32:36.507Z] 01:32:36 INFO - Inconsistency detected by ld.so: ../elf/dl-tls.c: 481: _dl_allocate_tls_init: Assertion `listp->slotinfo[cnt].gen <= GL(dl_tls_generation)' failed!
[task 2023-05-26T01:35:33.309Z] 01:35:33 ERROR - TEST-UNEXPECTED-FAIL | file:///builds/worker/workspace/build/tests/reftest/tests/image/test/reftest/ico/ico-bmp-4bpp/reftest.list | application terminated with exit code 127
[task 2023-05-26T01:35:33.313Z] 01:35:33 INFO - Traceback (most recent call last):
[task 2023-05-26T01:35:33.314Z] 01:35:33 INFO - File "/builds/worker/workspace/build/tests/reftest/runreftest.py", line 1198, in <module>
[task 2023-05-26T01:35:33.314Z] 01:35:33 INFO - sys.exit(run_test_harness(parser, options))
[task 2023-05-26T01:35:33.315Z] 01:35:33 INFO - File "/builds/worker/workspace/build/tests/reftest/runreftest.py", line 1190, in run_test_harness
[task 2023-05-26T01:35:33.315Z] 01:35:33 INFO - result = reftest.runTests(options.tests, options)
[task 2023-05-26T01:35:33.316Z] 01:35:33 INFO - File "/builds/worker/workspace/build/tests/reftest/runreftest.py", line 711, in runTests
[task 2023-05-26T01:35:33.317Z] 01:35:33 INFO - return self.runSerialTests(manifests, options, cmdargs)
[task 2023-05-26T01:35:33.317Z] 01:35:33 INFO - File "/builds/worker/workspace/build/tests/reftest/runreftest.py", line 1132, in runSerialTests
[task 2023-05-26T01:35:33.318Z] 01:35:33 INFO - status = run(tests=tests)
[task 2023-05-26T01:35:33.323Z] 01:35:33 INFO - File "/builds/worker/workspace/build/tests/reftest/runreftest.py", line 1098, in run
[task 2023-05-26T01:35:33.323Z] 01:35:33 INFO - **kwargs
[task 2023-05-26T01:35:33.324Z] 01:35:33 INFO - File "/builds/worker/workspace/build/tests/reftest/runreftest.py", line 1027, in runApp
[task 2023-05-26T01:35:33.324Z] 01:35:33 INFO - raise reraise(exc, value, tb)
[task 2023-05-26T01:35:33.324Z] 01:35:33 INFO - File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/six.py", line 696, in reraise
[task 2023-05-26T01:35:33.324Z] 01:35:33 INFO - raise value
[task 2023-05-26T01:35:33.329Z] 01:35:33 INFO - File "/builds/worker/workspace/build/tests/reftest/runreftest.py", line 962, in runApp
[task 2023-05-26T01:35:33.329Z] 01:35:33 INFO - marionette.start_session()
[task 2023-05-26T01:35:33.329Z] 01:35:33 INFO - File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/decorators.py", line 34, in _
[task 2023-05-26T01:35:33.330Z] 01:35:33 INFO - m._handle_socket_failure()
[task 2023-05-26T01:35:33.330Z] 01:35:33 INFO - File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/marionette.py", line 747, in _handle_socket_failure
[task 2023-05-26T01:35:33.331Z] 01:35:33 INFO - reraise(exc_cls, exc, tb)
[task 2023-05-26T01:35:33.331Z] 01:35:33 INFO - File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/six.py", line 696, in reraise
[task 2023-05-26T01:35:33.332Z] 01:35:33 INFO - raise value
[task 2023-05-26T01:35:33.332Z] 01:35:33 INFO - File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/decorators.py", line 24, in _
[task 2023-05-26T01:35:33.332Z] 01:35:33 INFO - return func(*args, **kwargs)
[task 2023-05-26T01:35:33.333Z] 01:35:33 INFO - File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/marionette.py", line 1292, in start_session
[task 2023-05-26T01:35:33.333Z] 01:35:33 INFO - self.raise_for_port(timeout=timeout)
[task 2023-05-26T01:35:33.334Z] 01:35:33 INFO - File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/marionette.py", line 673, in raise_for_port
[task 2023-05-26T01:35:33.334Z] 01:35:33 INFO - self.host, self.port
[task 2023-05-26T01:35:33.334Z] 01:35:33 INFO - socket.timeout: Timed out waiting for connection on 127.0.0.1:2828!
[task 2023-05-26T01:35:33.349Z] 01:35:33 INFO - Exception ignored in: <function BaseProfile.__del__ at 0x7f85a5fb0d40>
[task 2023-05-26T01:35:33.350Z] 01:35:33 INFO - Traceback (most recent call last):
[task 2023-05-26T01:35:33.351Z] 01:35:33 INFO - File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/mozprofile/profile.py", line 84, in __del__
[task 2023-05-26T01:35:33.352Z] 01:35:33 INFO - File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/mozprofile/profile.py", line 294, in cleanup
[task 2023-05-26T01:35:33.353Z] 01:35:33 INFO - File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/mozprofile/profile.py", line 92, in cleanup
[task 2023-05-26T01:35:33.354Z] 01:35:33 INFO - File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/mozfile/mozfile.py", line 247, in remove
[task 2023-05-26T01:35:33.355Z] 01:35:33 INFO - ImportError: sys.meta_path is None, Python is likely shutting down
[task 2023-05-26T01:35:33.376Z] 01:35:33 INFO - Return code: 1
[task 2023-05-26T01:35:33.377Z] 01:35:33 ERROR - No suite end message was emitted by this harness.
[task 2023-05-26T01:35:33.378Z] 01:35:33 INFO - TinderboxPrint: reftest-reftest<br/>646/0/0
[task 2023-05-26T01:35:33.378Z] 01:35:33 WARNING - setting return code to 2
[task 2023-05-26T01:35:33.378Z] 01:35:33 INFO - The reftest suite: reftest ran with return status: FAILURE
[task 2023-05-26T01:35:33.379Z] 01:35:33 INFO - Running post-action listener: _package_coverage_data
[task 2023-05-26T01:35:33.379Z] 01:35:33 INFO - Running post-action listener: _resource_record_post_action
[task 2023-05-26T01:35:33.380Z] 01:35:33 INFO - Running post-action listener: process_java_coverage_data
[task 2023-05-26T01:35:33.380Z] 01:35:33 INFO - [mozharness: 2023-05-26 01:35:33.380471Z] Finished run-tests step (success)
[task 2023-05-26T01:35:33.380Z] 01:35:33 INFO - [mozharness: 2023-05-26 01:35:33.380628Z] Running uninstall step.
[task 2023-05-26T01:35:33.381Z] 01:35:33 INFO - Running pre-action listener: _resource_record_pre_action
[task 2023-05-26T01:35:33.381Z] 01:35:33 INFO - Running main action method: uninstall
[task 2023-05-26T01:35:33.382Z] 01:35:33 INFO - Skipping uninstall for non-MSIX test
[task 2023-05-26T01:35:33.382Z] 01:35:33 INFO - Running post-action listener: _resource_record_post_action
[task 2023-05-26T01:35:33.382Z] 01:35:33 INFO - [mozharness: 2023-05-26 01:35:33.382321Z] Finished uninstall step (success)
[task 2023-05-26T01:35:33.383Z] 01:35:33 INFO - Running post-run listener: _resource_record_post_run
[task 2023-05-26T01:35:33.450Z] 01:35:33 INFO - Validating Perfherder data against /builds/worker/workspace/mozharness/external_tools/performance-artifact-schema.json
[task 2023-05-26T01:35:33.454Z] 01:35:33 INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": "reftest.reftest.3.overall", "extraOptions": ["e10s", "taskcluster-projects/887720501152/machineTypes/n2-standard-2"], "subtests": [{"name": "cpu_percent", "value": 56.88734087694487}, {"name": "io_write_bytes", "value": 2715361280}, {"name": "io.read_bytes", "value": 37367808}, {"name": "io_write_time", "value": 1000392}, {"name": "io_read_time", "value": 564}]}, {"name": "reftest.reftest.3.start-pulseaudio", "subtests": [{"name": "time", "value": 0.030524492263793945}, {"name": "cpu_percent", "value": 0}]}, {"name": "reftest.reftest.3.install", "subtests": [{"name": "time", "value": 44.76118063926697}, {"name": "cpu_percent", "value": 50.75581395348837}]}, {"name": "reftest.reftest.3.stage-files", "subtests": [{"name": "time", "value": 0.0011677742004394531}, {"name": "cpu_percent", "value": 0}]}, {"name": "reftest.reftest.3.run-tests", "subtests": [{"name": "time", "value": 663.5245683193207}, {"name": "cpu_percent", "value": 57.300755287009075}]}, {"name": "reftest.reftest.3.uninstall", "subtests": [{"name": "time", "value": 0.0015332698822021484}, {"name": "cpu_percent", "value": 0}]}]}
[task 2023-05-26T01:35:33.454Z] 01:35:33 INFO - Total resource usage - Wall time: 708s; CPU: Can't collect data; Read bytes: 37367808; Write bytes: 2715361280; Read time: 564; Write time: 1000392
[task 2023-05-26T01:35:33.454Z] 01:35:33 INFO - TinderboxPrint: I/O read bytes / time<br/>37,367,808 / 564
[task 2023-05-26T01:35:33.454Z] 01:35:33 INFO - TinderboxPrint: I/O write bytes / time<br/>2,715,361,280 / 1,000,392
[task 2023-05-26T01:35:33.454Z] 01:35:33 INFO - TinderboxPrint: CPU idle<br/>607.4 (43.1%)
[task 2023-05-26T01:35:33.455Z] 01:35:33 INFO - TinderboxPrint: CPU system<br/>65.9 (4.7%)
[task 2023-05-26T01:35:33.455Z] 01:35:33 INFO - TinderboxPrint: CPU user<br/>734.4 (52.1%)
[task 2023-05-26T01:35:33.455Z] 01:35:33 INFO - TinderboxPrint: Swap in / out<br/>0 / 0
[task 2023-05-26T01:35:33.455Z] 01:35:33 INFO - start-pulseaudio - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2023-05-26T01:35:33.456Z] 01:35:33 INFO - install - Wall time: 45s; CPU: 51%; Read bytes: 196608; Write bytes: 1685729280; Read time: 0; Write time: 899496
[task 2023-05-26T01:35:33.457Z] 01:35:33 INFO - stage-files - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2023-05-26T01:35:33.460Z] 01:35:33 INFO - run-tests - Wall time: 664s; CPU: 57%; Read bytes: 36892672; Write bytes: 1029632000; Read time: 548; Write time: 100896
[task 2023-05-26T01:35:33.461Z] 01:35:33 INFO - uninstall - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2023-05-26T01:35:33.527Z] 01:35:33 WARNING - returning nonzero exit status 2
[task 2023-05-26T01:35:33.571Z] cleanup
[task 2023-05-26T01:35:33.571Z] + cleanup
[task 2023-05-26T01:35:33.571Z] + local rv=2
[task 2023-05-26T01:35:33.571Z] + [[ -s /builds/worker/.xsession-errors ]]
[task 2023-05-26T01:35:33.572Z] + cp /builds/worker/.xsession-errors /builds/worker/artifacts/public/xsession-errors.log
[task 2023-05-26T01:35:33.575Z] + '[' ']'
[task 2023-05-26T01:35:33.575Z] + true
[task 2023-05-26T01:35:33.575Z] + cleanup_xvfb
[task 2023-05-26T01:35:33.576Z] ++ pidof Xvfb
[task 2023-05-26T01:35:33.581Z] + local xvfb_pid=49
[task 2023-05-26T01:35:33.582Z] + local vnc=false
[task 2023-05-26T01:35:33.582Z] + local interactive=false
[task 2023-05-26T01:35:33.582Z] + '[' -n 49 ']'
[task 2023-05-26T01:35:33.584Z] + [[ false == false ]]
[task 2023-05-26T01:35:33.584Z] + [[ false == false ]]
[task 2023-05-26T01:35:33.585Z] + kill 49
[task 2023-05-26T01:35:33.586Z] + screen -XS xvfb quit
[task 2023-05-26T01:35:33.595Z] + exit 2
[taskcluster 2023-05-26 01:35:34.373Z] === Task Finished ===
[taskcluster 2023-05-26 01:35:36.272Z] Unsuccessful task run with exit code: 2 completed in 937.7 seconds
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•2 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•