Closed Bug 1727609 Opened 3 years ago Closed 3 years ago

Intermittent raptor-browsertime Error: Browsertime failed to run | raptor-browsertime Critical: Failed waiting on page

Categories

(Testing :: Raptor, defect, P5)

Product:
Testing
Component:
Raptor
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED DUPLICATE of bug 1642045

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: archaeopteryx [at] coole-files.de
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=349545764&repo=fenix
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/cTHl-m9FQDm6A3xPaSYyBw/runs/0/artifacts/public/logs/live_backing.log

[task 2021-08-25T17:49:48.918Z] 17:48:43     INFO -  raptor-browsertime Info: https://www.youtube.com/watch?v=COU5T-Wafa4&browsertime_run=2 TTFB: 371ms DOMContentLoaded: 1.51s firstPaint: 865ms FCP: 860ms Load: 1.52s
[task 2021-08-25T17:49:48.918Z] 17:49:01     INFO -  raptor-browsertime Info: b"Wed, 25 Aug 2021 17:49:01 GMT adb:command Send '0019host:transport:ZY322HN78D'"
[task 2021-08-25T17:49:48.918Z] 17:49:01     INFO -  raptor-browsertime Info: b"Wed, 25 Aug 2021 17:49:01 GMT adb:command Send '0040shell:dumpsys battery | grep temperature | grep -Eo '[0-9]{1,3}''"
[task 2021-08-25T17:49:48.918Z] 17:49:02     INFO -  raptor-browsertime Info: Starting a browsertime pageload
[task 2021-08-25T17:49:48.918Z] 17:49:02     INFO -  raptor-browsertime Info: Waiting for 30000 ms (post_startup_delay)
[task 2021-08-25T17:49:48.918Z] 17:49:32     INFO -  raptor-browsertime Info: Navigating to about:blank
[task 2021-08-25T17:49:48.918Z] 17:49:32     INFO -  raptor-browsertime Info: Navigating to url about:blank iteration 13
[task 2021-08-25T17:49:48.918Z] 17:49:38     INFO -  raptor-browsertime Info: Navigating to primary url:https://www.youtube.com/watch?v=COU5T-Wafa4
[task 2021-08-25T17:49:48.918Z] 17:49:38     INFO -  raptor-browsertime Info: Cycle 0, waiting for 1000 ms
[task 2021-08-25T17:49:48.918Z] 17:49:39     INFO -  raptor-browsertime Info: Cycle 0, starting the measure
[task 2021-08-25T17:49:48.918Z] 17:49:39     INFO -  raptor-browsertime Info: Testing url https://www.youtube.com/watch?v=COU5T-Wafa4 iteration 13
[task 2021-08-25T17:49:48.918Z] 17:49:39     INFO -  raptor-browsertime Info: b"Wed, 25 Aug 2021 17:49:39 GMT adb:command Send '0019host:transport:ZY322HN78D'"
[task 2021-08-25T17:49:48.918Z] 17:49:39     INFO -  raptor-browsertime Info: b"Wed, 25 Aug 2021 17:49:39 GMT adb:command Send '003Dshell:screenrecord --bit-rate 8000000 /sdcard/browsertime.mp4'"
[task 2021-08-25T17:49:48.918Z] 17:49:47    ERROR -  raptor-browsertime Error: Browsertime failed to run
[task 2021-08-25T17:49:48.918Z] 17:49:47     INFO -  raptor-browsertime Info: b'    at Object.throwDecodedError (/builds/task_162991236072518/fetches/browsertime/node_modules/selenium-webdriver/lib/error.js:517:15)'
[task 2021-08-25T17:49:48.918Z] 17:49:47     INFO -  raptor-browsertime Info: b'    at parseHttpResponse (/builds/task_162991236072518/fetches/browsertime/node_modules/selenium-webdriver/lib/http.js:655:13)'
[task 2021-08-25T17:49:48.918Z] 17:49:47     INFO -  raptor-browsertime Info: b'    at Executor.execute (/builds/task_162991236072518/fetches/browsertime/node_modules/selenium-webdriver/lib/http.js:581:28)'
[task 2021-08-25T17:49:48.918Z] 17:49:47     INFO -  raptor-browsertime Info: b'    at process._tickCallback (internal/process/next_tick.js:68:7)'
[task 2021-08-25T17:49:48.918Z] 17:49:47     INFO -  raptor-browsertime Info: URL failed to load, trying 5 more time(s): Failed waiting on page https://www.youtube.com/watch?v=COU5T-Wafa4  to finished loading, timed out after 300000 ms
[task 2021-08-25T17:49:48.918Z] 17:49:47 CRITICAL -  raptor-browsertime Critical: Failed waiting on page https://www.youtube.com/watch?v=COU5T-Wafa4 to finished loading, timed out after 300000 ms WebDriverError: Failed to decode response from marionette
[task 2021-08-25T17:49:48.918Z] 17:49:47     INFO -  raptor-browsertime-android Info: removing reverse socket connections
[task 2021-08-25T17:49:48.918Z] 17:49:47     INFO -  adb command_output: adb -s ZY322HN78D wait-for-device reverse --remove-all, timeout: None, timedout: None, exitcode: 0, output:
[task 2021-08-25T17:49:48.918Z] 17:49:48     INFO -  adb shell_bool: adb -s ZY322HN78D wait-for-device shell su -c 'test -d /sdcard/Android/data/org.mozilla.fenix/files/test_root/org.mozilla.fenix-geckodriver-profile/minidumps', timeout: None, timedout: None, exitcode: 1, output:
[task 2021-08-25T17:49:48.918Z] 17:49:48     INFO -  raptor-mitmproxy Info: Mitmproxy stop!!
[task 2021-08-25T17:49:48.918Z] 17:49:48     INFO -  raptor-mitmproxy Info: Stopping mitmproxy playback, killing process 1297
[task 2021-08-25T17:49:48.918Z] 17:49:48     INFO -  raptor-mitmproxy Info: Successfully killed the mitmproxy playback process
[task 2021-08-25T17:49:48.918Z] 17:49:48    ERROR -  Traceback (most recent call last):
[task 2021-08-25T17:49:48.918Z] 17:49:48     INFO -    File "/builds/task_162991236072518/workspace/build/tests/raptor/raptor/raptor.py", line 213, in <module>
[task 2021-08-25T17:49:48.918Z] 17:49:48     INFO -      main()
[task 2021-08-25T17:49:48.918Z] 17:49:48     INFO -    File "/builds/task_162991236072518/workspace/build/tests/raptor/raptor/raptor.py", line 159, in main
[task 2021-08-25T17:49:48.918Z] 17:49:48     INFO -      success = raptor.run_tests(raptor_test_list, raptor_test_names)
[task 2021-08-25T17:49:48.918Z] 17:49:48     INFO -    File "/builds/task_162991236072518/workspace/build/tests/raptor/raptor/browsertime/android.py", line 235, in run_tests
[task 2021-08-25T17:49:48.918Z] 17:49:48     INFO -      return super(BrowsertimeAndroid, self).run_tests(tests, test_names)
[task 2021-08-25T17:49:48.918Z] 17:49:48     INFO -    File "/builds/task_162991236072518/workspace/build/tests/raptor/raptor/perftest.py", line 456, in run_tests
[task 2021-08-25T17:49:48.918Z] 17:49:48     INFO -      self.run_test(test, timeout=int(test.get("page_timeout")))
[task 2021-08-25T17:49:48.918Z] 17:49:48     INFO -    File "/builds/task_162991236072518/workspace/build/tests/raptor/raptor/browsertime/base.py", line 506, in run_test
[task 2021-08-25T17:49:48.918Z] 17:49:48     INFO -      raise Exception(self.browsertime_failure)
[task 2021-08-25T17:50:22.611Z] 17:49:48     INFO -  Exception: Failed waiting on page https://www.youtube.com/watch?v=COU5T-Wafa4 to finished loading, timed out after 300000 ms WebDriverError: Failed to decode response from marionette
[task 2021-08-25T17:50:22.611Z] 17:49:48    ERROR - Return code: 1
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → DUPLICATE
You need to log in before you can comment on or make changes to this bug.