1835171 - Intermittent TV TinderboxPrint: Per-test run of .../text/browser_textleafpoint.js<br/>: FAILURE

Status: NEW

(Core :: Disability Access APIs, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=417061080&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/dgKBJDgIT1W7nKr_BfUqTw/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/dgKBJDgIT1W7nKr_BfUqTw/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

---

[task 2023-05-25T21:58:48.874Z] 21:58:48     INFO -      reraise(exc_cls, exc, tb)
[task 2023-05-25T21:58:48.875Z] 21:58:48     INFO -    File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/six.py", line 696, in reraise
[task 2023-05-25T21:58:48.875Z] 21:58:48     INFO -      raise value
[task 2023-05-25T21:58:48.876Z] 21:58:48     INFO -    File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/decorators.py", line 24, in _
[task 2023-05-25T21:58:48.877Z] 21:58:48     INFO -      return func(*args, **kwargs)
[task 2023-05-25T21:58:48.877Z] 21:58:48     INFO -    File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/marionette.py", line 1292, in start_session
[task 2023-05-25T21:58:48.878Z] 21:58:48     INFO -      self.raise_for_port(timeout=timeout)
[task 2023-05-25T21:58:48.879Z] 21:58:48     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-25T21:58:48.879Z] 21:58:48     INFO -      self.host, self.port
[task 2023-05-25T21:58:48.880Z] 21:58:48     INFO -  socket.timeout: Timed out waiting for connection on 127.0.0.1:2828!
[task 2023-05-25T21:58:48.881Z] 21:58:48    ERROR - Automation Error: Received unexpected exception while running application
[task 2023-05-25T21:58:48.881Z] 21:58:48    ERROR - 
[task 2023-05-25T21:58:48.882Z] 21:58:48     INFO - Stopping web server
[task 2023-05-25T21:58:48.928Z] 21:58:48     INFO - Server shut down.
[task 2023-05-25T21:58:48.954Z] 21:58:48     INFO - Web server killed.
[task 2023-05-25T21:58:48.954Z] 21:58:48     INFO - Stopping web socket server
[task 2023-05-25T21:58:48.974Z] 21:58:48     INFO - Stopping ssltunnel
[task 2023-05-25T21:58:48.997Z] 21:58:48     INFO - Stopping gst for v4l2loopback
[task 2023-05-25T21:58:48.999Z] 21:58:48     INFO - runtests.py | Running tests: end.
[task 2023-05-25T21:58:49.027Z] 21:58:49     INFO - Buffered messages finished
[task 2023-05-25T21:58:49.028Z] 21:58:49     INFO -  TEST-INFO | checking window state
[task 2023-05-25T21:58:49.029Z] 21:58:49     INFO -  Browser Chrome Test Summary
[task 2023-05-25T21:58:49.030Z] 21:58:49     INFO -  	Passed: 900
[task 2023-05-25T21:58:49.031Z] 21:58:49     INFO -  	Failed: 0
[task 2023-05-25T21:58:49.032Z] 21:58:49     INFO -  	Todo: 48
[task 2023-05-25T21:58:49.033Z] 21:58:49     INFO -  	Mode: e10s
[task 2023-05-25T21:58:49.035Z] 21:58:49     INFO -  *** End BrowserChrome Test Results ***
[task 2023-05-25T21:58:49.035Z] 21:58:49     INFO - Buffered messages finished
[task 2023-05-25T21:58:49.035Z] 21:58:49     INFO - SUITE-END | took 181s
[task 2023-05-25T21:58:49.035Z] 21:58:49     INFO - SUITE-START | Running 1 tests
[task 2023-05-25T21:58:49.035Z] 21:58:49     INFO - :::
[task 2023-05-25T21:58:49.035Z] 21:58:49     INFO - ::: Test verification summary for:
[task 2023-05-25T21:58:49.038Z] 21:58:49     INFO - :::
[task 2023-05-25T21:58:49.038Z] 21:58:49     INFO - ::: accessible/tests/browser/text/browser_textleafpoint.js
[task 2023-05-25T21:58:49.038Z] 21:58:49     INFO - :::
[task 2023-05-25T21:58:49.038Z] 21:58:49     INFO - ::: 1. Run each test 10 times in one browser. : Pass
[task 2023-05-25T21:58:49.038Z] 21:58:49     INFO - ::: 2. Run each test 5 times in a new browser each time. : FAIL
[task 2023-05-25T21:58:49.040Z] 21:58:49     INFO - ::: 3. Run each test 10 times in one browser, in chaos mode. : not run / incomplete
[task 2023-05-25T21:58:49.040Z] 21:58:49     INFO - ::: 4. Run each test 5 times in a new browser each time, in chaos mode. : not run / incomplete
[task 2023-05-25T21:58:49.040Z] 21:58:49     INFO - :::
[task 2023-05-25T21:58:49.040Z] 21:58:49     INFO - ::: Test verification FAILED!
[task 2023-05-25T21:58:49.040Z] 21:58:49     INFO - :::
[task 2023-05-25T21:58:49.040Z] 21:58:49     INFO - Buffered messages finished
[task 2023-05-25T21:58:49.040Z] 21:58:49     INFO - SUITE-END | took 0s
[task 2023-05-25T21:58:49.080Z] 21:58:49     INFO - Return code: 0
[task 2023-05-25T21:58:49.082Z] 21:58:49     INFO - TinderboxPrint: mochitest-mochitest-browser-a11y<br/>12/0/0
[task 2023-05-25T21:58:49.082Z] 21:58:49  WARNING - setting return code to 2
[task 2023-05-25T21:58:49.082Z] 21:58:49    ERROR - TinderboxPrint: Per-test run of .../text/browser_textleafpoint.js<br/>: FAILURE
[task 2023-05-25T21:58:49.082Z] 21:58:49     INFO - Per-test suites: {}
[task 2023-05-25T21:58:49.082Z] 21:58:49     INFO - Per-test suites: {}
[task 2023-05-25T21:58:49.083Z] 21:58:49     INFO - Running post-action listener: _package_coverage_data
[task 2023-05-25T21:58:49.083Z] 21:58:49     INFO - Running post-action listener: _resource_record_post_action
[task 2023-05-25T21:58:49.083Z] 21:58:49     INFO - Running post-action listener: process_java_coverage_data
[task 2023-05-25T21:58:49.083Z] 21:58:49     INFO - [mozharness: 2023-05-25 21:58:49.082931Z] Finished run-tests step (success)
[task 2023-05-25T21:58:49.083Z] 21:58:49     INFO - [mozharness: 2023-05-25 21:58:49.083071Z] Running uninstall step.
[task 2023-05-25T21:58:49.083Z] 21:58:49     INFO - Running pre-action listener: _resource_record_pre_action
[task 2023-05-25T21:58:49.084Z] 21:58:49     INFO - Running main action method: uninstall
[task 2023-05-25T21:58:49.084Z] 21:58:49     INFO - Skipping uninstall for non-MSIX test
[task 2023-05-25T21:58:49.084Z] 21:58:49     INFO - Running post-action listener: _resource_record_post_action
[task 2023-05-25T21:58:49.084Z] 21:58:49     INFO - [mozharness: 2023-05-25 21:58:49.083673Z] Finished uninstall step (success)
[task 2023-05-25T21:58:49.085Z] 21:58:49     INFO - Running post-run listener: _resource_record_post_run
[task 2023-05-25T21:58:49.268Z] 21:58:49     INFO - Validating Perfherder data against /builds/worker/workspace/mozharness/external_tools/performance-artifact-schema.json
[task 2023-05-25T21:58:49.272Z] 21:58:49     INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": "1.overall", "extraOptions": ["e10s", "taskcluster-projects/887720501152/machineTypes/n2-standard-2"], "subtests": [{"name": "cpu_percent", "value": 75.36257056830999}, {"name": "io_write_bytes", "value": 4357382144}, {"name": "io.read_bytes", "value": 63012864}, {"name": "io_write_time", "value": 1163460}, {"name": "io_read_time", "value": 1712}]}, {"name": "1.start-pulseaudio", "subtests": [{"name": "time", "value": 0.02692127227783203}, {"name": "cpu_percent", "value": 0}]}, {"name": "1.install", "subtests": [{"name": "time", "value": 41.20286726951599}, {"name": "cpu_percent", "value": 50.70625}]}, {"name": "1.stage-files", "subtests": [{"name": "time", "value": 0.0026748180389404297}, {"name": "cpu_percent", "value": 0}]}, {"name": "1.run-tests", "subtests": [{"name": "time", "value": 2620.2742161750793}, {"name": "cpu_percent", "value": 75.75271510516237}]}, {"name": "1.uninstall", "subtests": [{"name": "time", "value": 0.00045418739318847656}, {"name": "cpu_percent", "value": 0}]}]}
[task 2023-05-25T21:58:49.273Z] 21:58:49     INFO - Total resource usage - Wall time: 2661s; CPU: Can't collect data; Read bytes: 63012864; Write bytes: 4357382144; Read time: 1712; Write time: 1163460
[task 2023-05-25T21:58:49.273Z] 21:58:49     INFO - TinderboxPrint: I/O read bytes / time<br/>63,012,864 / 1,712
[task 2023-05-25T21:58:49.273Z] 21:58:49     INFO - TinderboxPrint: I/O write bytes / time<br/>4,357,382,144 / 1,163,460
[task 2023-05-25T21:58:49.273Z] 21:58:49     INFO - TinderboxPrint: CPU idle<br/>1,303.1 (24.6%)
[task 2023-05-25T21:58:49.274Z] 21:58:49     INFO - TinderboxPrint: CPU nice<br/>807.0 (15.2%)
[task 2023-05-25T21:58:49.274Z] 21:58:49     INFO - TinderboxPrint: CPU system<br/>310.1 (5.9%)
[task 2023-05-25T21:58:49.274Z] 21:58:49     INFO - TinderboxPrint: CPU user<br/>2,873.7 (54.2%)
[task 2023-05-25T21:58:49.274Z] 21:58:49     INFO - TinderboxPrint: Swap in / out<br/>0 / 0
[task 2023-05-25T21:58:49.276Z] 21:58:49     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-25T21:58:49.277Z] 21:58:49     INFO - install - Wall time: 41s; CPU: 51%; Read bytes: 212992; Write bytes: 1722458112; Read time: 8; Write time: 980672
[task 2023-05-25T21:58:49.278Z] 21:58:49     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-25T21:58:49.291Z] 21:58:49     INFO - run-tests - Wall time: 2620s; CPU: 76%; Read bytes: 60424192; Write bytes: 2629500928; Read time: 1640; Write time: 180044
[task 2023-05-25T21:58:49.293Z] 21:58:49     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-25T21:58:49.543Z] 21:58:49  WARNING - returning nonzero exit status 2
[task 2023-05-25T21:58:49.604Z] cleanup
[task 2023-05-25T21:58:49.604Z] + cleanup
[task 2023-05-25T21:58:49.604Z] + local rv=2
[task 2023-05-25T21:58:49.604Z] + [[ -s /builds/worker/.xsession-errors ]]
[task 2023-05-25T21:58:49.605Z] + cp /builds/worker/.xsession-errors /builds/worker/artifacts/public/xsession-errors.log
[task 2023-05-25T21:58:49.608Z] + '[' ']'
[task 2023-05-25T21:58:49.608Z] + true
[task 2023-05-25T21:58:49.608Z] + cleanup_xvfb
[task 2023-05-25T21:58:49.609Z] ++ pidof Xvfb
[task 2023-05-25T21:58:49.618Z] + local xvfb_pid=52
[task 2023-05-25T21:58:49.618Z] + local vnc=false
[task 2023-05-25T21:58:49.618Z] + local interactive=false
[task 2023-05-25T21:58:49.618Z] + '[' -n 52 ']'
[task 2023-05-25T21:58:49.618Z] + [[ false == false ]]
[task 2023-05-25T21:58:49.619Z] + [[ false == false ]]
[task 2023-05-25T21:58:49.619Z] + kill 52
[task 2023-05-25T21:58:49.619Z] + screen -XS xvfb quit
[task 2023-05-25T21:58:49.663Z] + exit 2
[taskcluster 2023-05-25 21:58:50.066Z] === Task Finished ===
[taskcluster 2023-05-25 21:58:53.401Z] Unsuccessful task run with exit code: 2 completed in 2922.856 seconds

Comment 1

[Intermittent Failures Robot]

1 failures in 3946 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-tsan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1835171&startday=2023-05-22&endday=2023-05-28&tree=all