Closed Bug 1892026 Opened 2 years ago Closed 2 years ago

Intermittent raptor-browsertime Critical: BrowserError: Tried to run command without establishing a connection | raptor-browsertime Error: Browsertime failed to run

Categories

(Testing :: Raptor, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: pstanciu [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=454847568&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/XHaSljShTFibOYqa8TmUBg/runs/0/artifacts/public/logs/live_backing.log


[task 2024-04-17T13:55:11.193Z] 13:54:14     INFO -  raptor-browsertime Info: CPU / Power usage: 4382
[task 2024-04-17T13:55:11.193Z] 13:54:15     INFO -  raptor-browsertime Info: Browsertime pageload ended.
[task 2024-04-17T13:55:11.193Z] 13:54:15     INFO -  raptor-browsertime Info: Ignoring shutdown failures on android...
[task 2024-04-17T13:55:11.193Z] 13:54:18     INFO -  raptor-browsertime Info: 2024-04-17T13:54:18.015Z adb:command Send '001Ahost:transport:R58R35204PW'
[task 2024-04-17T13:55:11.193Z] 13:54:18     INFO -  raptor-browsertime Info: 2024-04-17T13:54:18.015Z adb:command Send '0040shell:dumpsys battery | grep temperature | grep -Eo '[0-9]{1,3}''
[task 2024-04-17T13:55:11.193Z] 13:54:18     INFO -  raptor-browsertime Info: Use the visual metrics portable script
[task 2024-04-17T13:55:11.193Z] 13:54:18     INFO -  raptor-browsertime Info: Get visual metrics from the video
[task 2024-04-17T13:55:11.193Z] 13:54:30     INFO -  raptor-browsertime Info: Use the visual metrics portable script
[task 2024-04-17T13:55:11.193Z] 13:54:30     INFO -  raptor-browsertime Info: Get visual metrics from the video
[task 2024-04-17T13:55:11.193Z] 13:54:32     INFO -  raptor-browsertime Info: https://cnn.com/ampstories/us/why-hurricane-michael-is-a-monster-unlike-any-other TTFB: 387ms DOMContentLoaded: 580ms firstPaint: 1.02s FCP: 1.40s LCP: 1.81s Load: 1.30s
[task 2024-04-17T13:55:11.193Z] 13:54:32     INFO -  raptor-browsertime Info: VisualMetrics: FirstVisualChange: 1.11s SpeedIndex: 1.59s PerceptualSpeedIndex: 1.47s ContentfulSpeedIndex: 1.13s VisualComplete85: 1.58s LastVisualChange: 1.91s
[task 2024-04-17T13:55:11.193Z] 13:54:32     INFO -  raptor-browsertime Info: https://cnn.com/ampstories/us/why-hurricane-michael-is-a-monster-unlike-any-other?browsertime_run=2 TTFB: 72ms DOMContentLoaded: 143ms firstPaint: 182ms FCP: 490ms LCP: 663ms Load: 375ms
[task 2024-04-17T13:55:11.193Z] 13:54:32     INFO -  raptor-browsertime Info: VisualMetrics: FirstVisualChange: 296ms SpeedIndex: 599ms PerceptualSpeedIndex: 497ms ContentfulSpeedIndex: 303ms VisualComplete85: 596ms LastVisualChange: 747ms
[task 2024-04-17T13:55:11.193Z] 13:54:32     INFO -  raptor-browsertime Info: Skip setting default preferences for Firefox
[task 2024-04-17T13:55:11.193Z] 13:55:10    ERROR -  raptor-browsertime Error: Browsertime failed to run
[task 2024-04-17T13:55:11.193Z] 13:55:10     INFO -  raptor-browsertime Info:     at SeleniumRunner.start (file:///builds/task_171336164547816/fetches/browsertime/node_modules/browsertime/lib/core/seleniumRunner.js:142:13)
[task 2024-04-17T13:55:11.193Z] 13:55:10     INFO -  raptor-browsertime Info:     at process.processTicksAndRejections (node:internal/process/task_queues:95:5)
[task 2024-04-17T13:55:11.193Z] 13:55:10     INFO -  raptor-browsertime Info:     at async Iteration.run (file:///builds/task_171336164547816/fetches/browsertime/node_modules/browsertime/lib/core/engine/iteration.js:94:7)
[task 2024-04-17T13:55:11.193Z] 13:55:10     INFO -  raptor-browsertime Info:     at async Engine.runByScript (file:///builds/task_171336164547816/fetches/browsertime/node_modules/browsertime/lib/core/engine/index.js:308:20)
[task 2024-04-17T13:55:11.193Z] 13:55:10     INFO -  raptor-browsertime Info:     at async run (file:///builds/task_171336164547816/fetches/browsertime/node_modules/browsertime/bin/browsertime.js:104:22)
[task 2024-04-17T13:55:11.193Z] 13:55:10     INFO -  raptor-browsertime Info:     at async file:///builds/task_171336164547816/fetches/browsertime/node_modules/browsertime/bin/browsertime.js:223:1
[task 2024-04-17T13:55:11.193Z] 13:55:10 CRITICAL -  raptor-browsertime Critical: BrowserError: Tried to run command without establishing a connection
[task 2024-04-17T13:55:11.193Z] 13:55:10     INFO -  raptor-browsertime-android Info: removing reverse socket connections
[task 2024-04-17T13:55:11.193Z] 13:55:10     INFO -  raptor-mitmproxy Info: Mitmproxy stop!!
[task 2024-04-17T13:55:11.193Z] 13:55:10     INFO -  raptor-mitmproxy Info: Stopping mitmproxy playback, killing process 1298
[task 2024-04-17T13:55:11.193Z] 13:55:11     INFO -  raptor-mitmproxy Info: Successfully killed the mitmproxy playback process
[task 2024-04-17T13:55:11.193Z] 13:55:11     INFO -  raptor-perftest Info: Removing temporary directory: /tmp/tmp12urcr93
[task 2024-04-17T13:55:11.193Z] 13:55:11    ERROR -  Traceback (most recent call last):
[task 2024-04-17T13:55:11.193Z] 13:55:11     INFO -    File "/builds/task_171336164547816/workspace/build/tests/raptor/raptor/raptor.py", line 188, in <module>
[task 2024-04-17T13:55:20.323Z] 13:55:11     INFO -      main()
[task 2024-04-17T13:55:20.323Z] 13:55:11     INFO -    File "/builds/task_171336164547816/workspace/build/tests/raptor/raptor/raptor.py", line 137, in main
[task 2024-04-17T13:55:20.323Z] 13:55:11     INFO -      success = raptor.run_tests(raptor_test_list, raptor_test_names)
[task 2024-04-17T13:55:20.323Z] 13:55:11     INFO -    File "/builds/task_171336164547816/workspace/build/tests/raptor/raptor/browsertime/android.py", line 271, in run_tests
[task 2024-04-17T13:55:20.323Z] 13:55:11     INFO -      return super(BrowsertimeAndroid, self).run_tests(tests, test_names)
[task 2024-04-17T13:55:20.323Z] 13:55:11     INFO -    File "/builds/task_171336164547816/workspace/build/tests/raptor/raptor/perftest.py", line 499, in run_tests
[task 2024-04-17T13:55:20.323Z] 13:55:11     INFO -      self.run_test(test, timeout=int(test.get("page_timeout")))
[task 2024-04-17T13:55:20.323Z] 13:55:11     INFO -    File "/builds/task_171336164547816/workspace/build/tests/raptor/raptor/browsertime/base.py", line 1051, in run_test
[task 2024-04-17T13:55:20.323Z] 13:55:11     INFO -      raise Exception(self.browsertime_failure)
[task 2024-04-17T13:55:20.323Z] 13:55:11     INFO -  Exception: BrowserError: Tried to run command without establishing a connection
[task 2024-04-17T13:55:20.323Z] 13:55:11     INFO - Return code: 1
[task 2024-04-17T13:55:20.323Z] 13:55:11  WARNING - setting return code to 1
[task 2024-04-17T13:55:20.323Z] 13:55:11     INFO - Killing logcat pid 800.
[task 2024-04-17T13:55:20.323Z] 13:55:11     INFO - Copying Raptor results to upload dir:
[task 2024-04-17T13:55:20.323Z] 13:55:11     INFO - /builds/task_171336164547816/workspace/build/blobber_upload_dir/perfherder-data.json
[task 2024-04-17T13:55:20.323Z] 13:55:11     INFO - Copying raptor results from /builds/task_171336164547816/workspace/build/raptor.json to /builds/task_171336164547816/workspace/build/blobber_upload_dir/perfherder-data.json
[task 2024-04-17T13:55:20.323Z] 13:55:11 CRITICAL - Error copying results /builds/task_171336164547816/workspace/build/raptor.json to upload dir /builds/task_171336164547816/workspace/build/blobber_upload_dir/perfherder-data.json
[task 2024-04-17T13:55:20.323Z] 13:55:11     INFO - [Errno 2] No such file or directory: '/builds/task_171336164547816/workspace/build/raptor.json'
[task 2024-04-17T13:55:20.323Z] 13:55:11     INFO - Running post-action listener: _package_coverage_data
[task 2024-04-17T13:55:20.323Z] 13:55:11     INFO - Running post-action listener: _resource_record_post_action
[task 2024-04-17T13:55:20.323Z] 13:55:11     INFO - Running post-action listener: process_java_coverage_data
[task 2024-04-17T13:55:20.323Z] 13:55:11     INFO - Running post-action listener: stop_device
[task 2024-04-17T13:55:20.323Z] 13:55:14     INFO - /data/tombstones/tombstone_00 deleted
[task 2024-04-17T13:55:20.323Z] 13:55:14     INFO - Killing logcat pid 800.

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.