Closed Bug 1352679 Opened 7 years ago Closed 4 years ago

Intermittent browser/components/urlbar/tests/browser/browser_canonizeURL.js | Test timed out -

Categories

(Firefox :: Address Bar, defect, P3)

Product:
Firefox
Component:
Address Bar
Type:
defect

Tracking

()

Status:
RESOLVED WORKSFORME

People

(Reporter: aryx, Unassigned)

Details

(Keywords: intermittent-failure, Whiteboard: [stockwell unknown][fxsearch]) 

https://treeherder.mozilla.org/logviewer.html#?job_id=88049574&repo=mozilla-inbound

09:19:20     INFO -  81 INFO TEST-PASS | browser/base/content/test/urlbar/browser_canonizeURL.js | waitForDocLoadAndStopIt: The expected URL was loaded -
09:19:20     INFO -  82 INFO waitForDocLoadAndStopIt: Waiting for URL: http://example.net/
09:19:20     INFO -  83 INFO TEST-PASS | browser/base/content/test/urlbar/browser_canonizeURL.js | waitForDocLoadAndStopIt: The expected URL was loaded -
09:19:20     INFO -  84 INFO waitForDocLoadAndStopIt: Waiting for URL: http://example/
09:19:20     INFO -  Buffered messages finished
09:19:20    ERROR -  85 INFO TEST-UNEXPECTED-FAIL | browser/base/content/test/urlbar/browser_canonizeURL.js | Test timed out -
Priority: -- → P3
I see this test failing consistently for the last 2+ months, recently failing enough to match my query for high frequency failures- the pattern is windows 7 non/debug (heavily on pgo).

here is a recent log:
https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-inbound&job_id=133148311&lineNumber=2003

and the related screenshot:
https://public-artifacts.taskcluster.net/Eipuv_5ERgGNWRQIKmP-zQ/0/public/test_info/mozilla-test-fail-screenshot_k9wzqv.png

and related information from the log file:
17:04:09     INFO -  128 INFO TEST-START | browser/base/content/test/urlbar/browser_canonizeURL.js
17:04:09     INFO -  GECKO(3164) | waitForDocLoadAndStopIt: onStateChange f0001: http://www.example.net/
17:04:09     INFO -  GECKO(3164) | waitForDocLoadAndStopIt: Document start: http://www.example.net/
17:04:09     INFO -  GECKO(3164) | waitForDocLoadAndStopIt: onStateChange f0001: http://example.com/test/
17:04:09     INFO -  GECKO(3164) | waitForDocLoadAndStopIt: Document start: http://example.com/test/
17:04:09     INFO -  GECKO(3164) | waitForDocLoadAndStopIt: onStateChange f0001: http://www.ex-ample.net/
17:04:09     INFO -  GECKO(3164) | waitForDocLoadAndStopIt: Document start: http://www.ex-ample.net/
17:04:09     INFO -  GECKO(3164) | waitForDocLoadAndStopIt: onStateChange f0001: http://www.example.net/
17:04:09     INFO -  GECKO(3164) | waitForDocLoadAndStopIt: Document start: http://www.example.net/
17:04:09     INFO -  GECKO(3164) | waitForDocLoadAndStopIt: onStateChange f0001: http://www.example.net/foo
17:04:09     INFO -  GECKO(3164) | waitForDocLoadAndStopIt: Document start: http://www.example.net/foo
17:04:54     INFO -  TEST-INFO | started process screenshot
17:04:54     INFO -  TEST-INFO | screenshot: exit 0
17:04:54     INFO -  Buffered messages logged at 17:04:09
17:04:54     INFO -  129 INFO Entering test bound
17:04:54     INFO -  130 INFO waitForDocLoadAndStopIt: Waiting for URL: http://www.example.net/
17:04:54     INFO -  131 INFO TEST-PASS | browser/base/content/test/urlbar/browser_canonizeURL.js | waitForDocLoadAndStopIt: The expected URL was loaded -
17:04:54     INFO -  132 INFO waitForDocLoadAndStopIt: Waiting for URL: http://example.com/test/
17:04:54     INFO -  133 INFO TEST-PASS | browser/base/content/test/urlbar/browser_canonizeURL.js | waitForDocLoadAndStopIt: The expected URL was loaded -
17:04:54     INFO -  134 INFO waitForDocLoadAndStopIt: Waiting for URL: http://www.ex-ample.net/
17:04:54     INFO -  135 INFO TEST-PASS | browser/base/content/test/urlbar/browser_canonizeURL.js | waitForDocLoadAndStopIt: The expected URL was loaded -
17:04:54     INFO -  136 INFO waitForDocLoadAndStopIt: Waiting for URL: http://www.example.net/
17:04:54     INFO -  137 INFO TEST-PASS | browser/base/content/test/urlbar/browser_canonizeURL.js | waitForDocLoadAndStopIt: The expected URL was loaded -
17:04:54     INFO -  138 INFO waitForDocLoadAndStopIt: Waiting for URL: http://www.example.net/foo
17:04:54     INFO -  139 INFO TEST-PASS | browser/base/content/test/urlbar/browser_canonizeURL.js | waitForDocLoadAndStopIt: The expected URL was loaded -
17:04:54     INFO -  140 INFO waitForDocLoadAndStopIt: Waiting for URL: http://www.example.net/foo%20bar
17:04:54     INFO -  141 INFO Console message: [JavaScript Warning: "Error in parsing value for ‘width’.  Declaration dropped." {file: "chrome://browser/content/browser.xul" line: 0 column: 0 source: "-36px"}]
17:04:54     INFO -  Buffered messages finished
17:04:54    ERROR -  142 INFO TEST-UNEXPECTED-FAIL | browser/base/content/test/urlbar/browser_canonizeURL.js | Test timed out -
17:04:54     INFO -  GECKO(3164) | MEMORY STAT | vsize 765MB | vsizeMaxContiguous 437MB | residentFast 213MB | heapAllocated 73MB
17:04:54     INFO -  143 INFO TEST-OK | browser/base/content/test/urlbar/browser_canonizeURL.js | took 45021ms


::mak, I saw you marked this as p3 about a week ago, when it comes time to work on this would you be the right person to look at this?
Flags: needinfo?(mak77)
Whiteboard: [stockwell needswork]
(In reply to Joel Maher ( :jmaher) (UTC-5) from comment #18)
> ::mak, I saw you marked this as p3 about a week ago, when it comes time to
> work on this would you be the right person to look at this?

I marked it as P3 because from the stats it ddidn't look frequent enough to become a priority (0.03 failures/push). If you think these stats are wrong, what should we look at?
Flags: needinfo?(mak77) → needinfo?(jmaher)
I see 31 failures in the last 7 days:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1352679

I did check win7/pgo and this specific chunk runs all the time (the test moves between chunks sometimes, so it is not 100% certain over time that it runs on every push).  As this is just around our threshold, I don't see this as a priority to fix now, but I do see the failure rate staying high and this should be reproducible.  I am pushing to try to see if it is and what the failure rate is there.
Flags: needinfo?(jmaher)
I couldn't reproduce this on try server:
https://treeherder.mozilla.org/#/jobs?repo=try&revision=94e1fffb69e2cb28397f649f873abc5a6df5af43

possibly this is harder to reproduce than originally thought- lets see if this continues with a 30+/week failure rate.
Priority: P3 → P2
Whiteboard: [stockwell unknown] → [stockwell unknown][fxsearch]
Priority: P2 → P3
This seemed to get rarer, then appear in beta, then release & nothing else. So I guess this got resolved somehow -> WFM.
Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → WORKSFORME
Summary: Intermittent browser/base/content/test/urlbar/browser_canonizeURL.js | Test timed out - → Intermittent browser/components/urlbar/tests/browser/browser_canonizeURL.js | Test timed out -

doesn't happen from quite some time

Status: REOPENED → RESOLVED
Closed: 6 years ago → 4 years ago
Resolution: --- → WORKSFORME
You need to log in before you can comment on or make changes to this bug.