1777118 - Intermittent [TV] browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_telemetry.js | Test timed out - | Found a tab after previous test timed out: about:blank

Status: RESOLVED DUPLICATE of bug 1777117

(Firefox :: Address Bar, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2022-06-28T20:28:28.385Z] 20:28:28     INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_telemetry.js | Payload property: position - 2 == 2 - 
[task 2022-06-28T20:28:28.385Z] 20:28:28     INFO - Buffered messages logged at 20:21:24
[task 2022-06-28T20:28:28.386Z] 20:28:28     INFO - Leaving test bound impression_bestMatch
[task 2022-06-28T20:28:28.386Z] 20:28:28     INFO - Entering test bound noImpression_abandonment
[task 2022-06-28T20:28:28.387Z] 20:28:28     INFO - Buffered messages logged at 20:22:27
[task 2022-06-28T20:28:28.387Z] 20:28:28     INFO - Longer timeout required, waiting longer...  Remaining timeouts: 4
[task 2022-06-28T20:28:28.387Z] 20:28:28     INFO - Buffered messages logged at 20:23:57
[task 2022-06-28T20:28:28.388Z] 20:28:28     INFO - Longer timeout required, waiting longer...  Remaining timeouts: 3
[task 2022-06-28T20:28:28.388Z] 20:28:28     INFO - Buffered messages logged at 20:25:27
[task 2022-06-28T20:28:28.389Z] 20:28:28     INFO - Longer timeout required, waiting longer...  Remaining timeouts: 2
[task 2022-06-28T20:28:28.389Z] 20:28:28     INFO - Buffered messages logged at 20:26:58
[task 2022-06-28T20:28:28.389Z] 20:28:28     INFO - Longer timeout required, waiting longer...  Remaining timeouts: 1
[task 2022-06-28T20:28:28.390Z] 20:28:28     INFO - Buffered messages finished
[task 2022-06-28T20:28:28.390Z] 20:28:28     INFO - TEST-UNEXPECTED-FAIL | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_telemetry.js | Test timed out - 
[task 2022-06-28T20:28:28.390Z] 20:28:28     INFO - GECKO(2294) | [2022-06-28T20:28:28Z WARN  glean_core::error_recording] search.engine.private.submission_url: "" does not start with a valid URL scheme.
[task 2022-06-28T20:28:28.567Z] 20:28:28     INFO - GECKO(2294) | MEMORY STAT | vsize 8869MB | residentFast 391MB | heapAllocated 162MB
[task 2022-06-28T20:28:28.568Z] 20:28:28     INFO - TEST-OK | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_telemetry.js | took 450866ms
[task 2022-06-28T20:28:28.570Z] 20:28:28     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-06-28T20:28:28.570Z] 20:28:28     INFO - TEST-UNEXPECTED-FAIL | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_telemetry.js | Found a tab after previous test timed out: about:blank - 
[task 2022-06-28T20:28:28.572Z] 20:28:28     INFO - GECKO(2294) | [Child 2302, Main Thread] WARNING: '!CanHandleWith(aPresContext)', file /builds/worker/checkouts/gecko/dom/events/IMEStateManager.cpp:498
[task 2022-06-28T20:28:28.629Z] 20:28:28     INFO - GECKO(2294) | [Child 2313: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 113429c00 == 1 [pid = 2313] [id = 4]
[task 2022-06-28T20:28:28.630Z] 20:28:28     INFO - GECKO(2294) | [Child 2313: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (11329df10) [pid = 2313] [serial = 13] [outer = 0]
[task 2022-06-28T20:28:28.630Z] 20:28:28     INFO - GECKO(2294) | [Child 2312, Main Thread] WARNING: '!CanHandleWith(aPresContext)', file /builds/worker/checkouts/gecko/dom/events/IMEStateManager.cpp:498
[task 2022-06-28T20:28:28.631Z] 20:28:28     INFO - GECKO(2294) | [Child 2313: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (11347e000) [pid = 2313] [serial = 14] [outer = 11329df10]
[task 2022-06-28T20:28:28.647Z] 20:28:28     INFO - checking window state
[task 2022-06-28T20:28:28.763Z] 20:28:28     INFO - GECKO(2294) | [Parent 2294: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (134964400) [pid = 2294] [serial = 60] [outer = 10a73e6f0]
[task 2022-06-28T20:28:29.010Z] 20:28:29     INFO - GECKO(2294) | [Child 2312: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 113818c00 == 0 [pid = 2312] [id = 66] [url = about:blank]
[task 2022-06-28T20:28:29.020Z] 20:28:29     INFO - GECKO(2294) | [Child 2302: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 11f2b7c00 == 0 [pid = 2302] [id = 77] [url = about:blank]
[task 2022-06-28T20:28:29.068Z] 20:28:29     INFO - GECKO(2294) | [Child 2311: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 10d861000 == 0 [pid = 2311] [id = 0] [url = about:newtab]
[task 2022-06-28T20:28:29.069Z] 20:28:29     INFO - GECKO(2294) | [Child 2311, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/checkouts/gecko/dom/events/DOMEventTargetHelper.cpp:306
[task 2022-06-28T20:28:29.845Z] 20:28:29     INFO - GECKO(2294) | [Child 2312: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (11381fc00) [pid = 2312] [serial = 198] [outer = 0] [url = about:blank]
[task 2022-06-28T20:28:29.849Z] 20:28:29     INFO - GECKO(2294) | [Child 2312: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (10579df10) [pid = 2312] [serial = 197] [outer = 0] [url = about:blank]
[task 2022-06-28T20:28:29.849Z] 20:28:29     INFO - GECKO(2294) | [Child 2302: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (11f471800) [pid = 2302] [serial = 233] [outer = 0] [url = about:blank]
[task 2022-06-28T20:28:29.849Z] 20:28:29     INFO - GECKO(2294) | [Child 2302: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (10d59e650) [pid = 2302] [serial = 231] [outer = 0] [url = about:blank]
[task 2022-06-28T20:28:29.857Z] 20:28:29     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-06-28T20:28:29.858Z] 20:28:29     INFO - TEST-UNEXPECTED-FAIL | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_telemetry.js | Uncaught exception received from previously timed out test - at chrome://mochitests/content/browser/browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_telemetry.js:254 - ReferenceError: UrlbarTestUtils is not defined
[task 2022-06-28T20:28:29.858Z] 20:28:29     INFO - Stack trace:
[task 2022-06-28T20:28:29.858Z] 20:28:29     INFO - noImpression_abandonment/<@chrome://mochitests/content/browser/browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_telemetry.js:254:5
[task 2022-06-28T20:28:29.858Z] 20:28:29     INFO - withNewTab@resource://testing-common/BrowserTestUtils.jsm:152:24