1867942 - Permafailing [tier 2] raptor-browsertime Critical: Failed waiting on page <url> to finished loading, timed out after 120000 ms Error: Running page complete check

Status: RESOLVED FIXED

(Testing :: Raptor, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2023-12-02T07:16:45.858Z] 07:16:45     INFO -  raptor-browsertime Info: Testing url https://pinterest.com/ iteration 1
[task 2023-12-02T07:17:01.111Z] 07:17:01     INFO -  raptor-browsertime Info: Will check again if the browser has navigated to the page
[task 2023-12-02T07:19:01.148Z] 07:19:01    ERROR -  raptor-browsertime Error: Browsertime failed to run
[task 2023-12-02T07:19:01.148Z] 07:19:01     INFO -  raptor-browsertime Info: return (function(waitTime) {
[task 2023-12-02T07:19:01.149Z] 07:19:01     INFO -  raptor-browsertime Info:     try {
[task 2023-12-02T07:19:01.149Z] 07:19:01     INFO -  raptor-browsertime Info:             var end = window.performance.timing.loadEventEnd;
[task 2023-12-02T07:19:01.149Z] 07:19:01     INFO -  raptor-browsertime Info:             var start= window.performance.timing.navigationStart;
[task 2023-12-02T07:19:01.150Z] 07:19:01     INFO -  raptor-browsertime Info:             return (end > 0) && (performance.now() > end - start + waitTime);
[task 2023-12-02T07:19:01.150Z] 07:19:01     INFO -  raptor-browsertime Info:     }
[task 2023-12-02T07:19:01.150Z] 07:19:01     INFO -  raptor-browsertime Info:     catch(e) {
[task 2023-12-02T07:19:01.150Z] 07:19:01     INFO -  raptor-browsertime Info:         return true;
[task 2023-12-02T07:19:01.151Z] 07:19:01     INFO -  raptor-browsertime Info:     }
[task 2023-12-02T07:19:01.151Z] 07:19:01     INFO -  raptor-browsertime Info: })(arguments[arguments.length - 1]);
[task 2023-12-02T07:19:01.151Z] 07:19:01     INFO -  raptor-browsertime Info:  took too long
[task 2023-12-02T07:19:01.151Z] 07:19:01     INFO -  raptor-browsertime Info: return (function(waitTime) {
[task 2023-12-02T07:19:01.152Z] 07:19:01     INFO -  raptor-browsertime Info:     try {
[task 2023-12-02T07:19:01.152Z] 07:19:01     INFO -  raptor-browsertime Info:             var end = window.performance.timing.loadEventEnd;
[task 2023-12-02T07:19:01.152Z] 07:19:01     INFO -  raptor-browsertime Info:             var start= window.performance.timing.navigationStart;
[task 2023-12-02T07:19:01.152Z] 07:19:01     INFO -  raptor-browsertime Info:             return (end > 0) && (performance.now() > end - start + waitTime);
[task 2023-12-02T07:19:01.153Z] 07:19:01     INFO -  raptor-browsertime Info:     }
[task 2023-12-02T07:19:01.153Z] 07:19:01     INFO -  raptor-browsertime Info:     catch(e) {
[task 2023-12-02T07:19:01.153Z] 07:19:01     INFO -  raptor-browsertime Info:         return true;
[task 2023-12-02T07:19:01.153Z] 07:19:01     INFO -  raptor-browsertime Info:     }
[task 2023-12-02T07:19:01.153Z] 07:19:01     INFO -  raptor-browsertime Info: })(arguments[arguments.length - 1]);
[task 2023-12-02T07:19:01.154Z] 07:19:01     INFO -  raptor-browsertime Info:  took too long
[task 2023-12-02T07:19:01.154Z] 07:19:01     INFO -  raptor-browsertime Info:     at Timeout.<anonymous> (file:///opt/worker/tasks/task_170150075903221/fetches/browsertime/node_modules/browsertime/lib/core/seleniumRunner.js:45:16)
[task 2023-12-02T07:19:01.154Z] 07:19:01     INFO -  raptor-browsertime Info:     at listOnTimeout (node:internal/timers:559:17)
[task 2023-12-02T07:19:01.154Z] 07:19:01     INFO -  raptor-browsertime Info:     at processTimers (node:internal/timers:502:7)
[task 2023-12-02T07:19:01.155Z] 07:19:01     INFO -  raptor-browsertime Info: URL failed to load, trying 4 more time(s): Failed waiting on page https://pinterest.com/  to finished loading, timed out after 120000 ms
[task 2023-12-02T07:19:01.155Z] 07:19:01 CRITICAL -  raptor-browsertime Critical: Failed waiting on page https://pinterest.com/ to finished loading, timed out after 120000 ms Error: Running page complete check
[task 2023-12-02T07:19:01.155Z] 07:19:01     INFO -  raptor-mitmproxy Info: MitmproxyDesktop stop!!

Comment 1

[Natalia Csoregi [:nataliaCs]]

Hi Greg, do you have a bit of time to check this?
Thank you.

Comment 2

[BugBot [:suhaib / :marco/ :calixte]]

Set release status flags based on info from the regressing bug 1863130

Comment 3

[Greg Mierzwinski [:sparky]]

Thanks for the ping! That test shouldn't be running on mozilla-central, or autoland, but the add-all-browsertime backfill action was scheduling it since it wasn't taking into account the run-on-projects settings. This issue should be fixed by the patch in bug 1867511, but I'll check back to see if it's still being scheduled.

Comment 4

[Greg Mierzwinski [:sparky]]

This looks to be fixed now, the last time tp6-bench was triggered by the browsertime-backfill cron was here: https://treeherder.mozilla.org/jobs?repo=autoland&searchStr=browsertime%2Ctp6-bench&revision=97bd03fdb1bec86b388b3393aa328608da4b388d

After the patch, it no longer gets triggered. See this push for example: https://treeherder.mozilla.org/jobs?repo=autoland&searchStr=browsertime&revision=2a52041afc10652845d2b12c671546363922fbdd

I'll leave the ni? open to check back on Monday.

Comment 5

[Intermittent Failures Robot]

39 failures in 4108 pushes (0.009 failures/push) were associated with this bug in the last 7 days.

This is the #46 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 39

Platform and build breakdown:

• macosx1015-64-shippable-qr: 39
  • opt: 39

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1867942&startday=2023-11-27&endday=2023-12-03&tree=all

Comment 6

[Greg Mierzwinski [:sparky]]

This is fixed now from the changes in bug 1867511. The task is no longer scheduled on autoland by the add-all-browsertime cron.