1885264 - Intermittent browser/components/urlbar/tests/quicksuggest/browser/browser_weather.js | single tracking bug

Status: RESOLVED INCOMPLETE

(Firefox :: Address Bar, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: smolnar [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=450702036&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/d4ErP9-2QVOwlWyx-DUkwQ/runs/0/artifacts/public/logs/live_backing.log

---

Uncaught exception in test bound manage_rustEnabledTEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_weather.js | Assert the page navigated to the weather webpage after selecting the weather result. - "https://example.com/weather" == "https://example.com/weather" - 
[task 2024-03-14T05:22:50.818Z] 05:22:50     INFO - add_tasks_with_rust: Done calling original task function: test_weather_result_selection
[task 2024-03-14T05:22:50.819Z] 05:22:50     INFO - add_tasks_with_rust: Clearing rustEnabled
[task 2024-03-14T05:22:50.819Z] 05:22:50     INFO - add_tasks_with_rust: Done clearing rustEnabled
[task 2024-03-14T05:22:50.820Z] 05:22:50     INFO - add_tasks_with_rust: Forcing sync
[task 2024-03-14T05:22:50.821Z] 05:22:50     INFO - QuickSuggestTestUtils.forceSync Started
[task 2024-03-14T05:22:50.821Z] 05:22:50     INFO - QuickSuggestTestUtils.forceSync Syncing Rust backend
[task 2024-03-14T05:22:50.822Z] 05:22:50     INFO - Buffered messages finished
[task 2024-03-14T05:22:50.824Z] 05:22:50     INFO - TEST-UNEXPECTED-FAIL | browser/components/urlbar/tests/quicksuggest/browser/browser_weather.js | Uncaught exception in test bound test_weather_result_selection_rustDisabled - at resource://gre/modules/RustSuggest.sys.mjs:992 - Other: Error
[task 2024-03-14T05:22:50.824Z] 05:22:50     INFO - Stack trace:
[task 2024-03-14T05:22:50.824Z] 05:22:50     INFO - SuggestApiError@resource://gre/modules/RustSuggest.sys.mjs:992:8
[task 2024-03-14T05:22:50.824Z] 05:22:50     INFO - Other@resource://gre/modules/RustSuggest.sys.mjs:1041:9
[task 2024-03-14T05:22:50.824Z] 05:22:50     INFO - read@resource://gre/modules/RustSuggest.sys.mjs:1065:24
[task 2024-03-14T05:22:50.824Z] 05:22:50     INFO - lift@resource://gre/modules/RustSuggest.sys.mjs:247:21
[task 2024-03-14T05:22:50.824Z] 05:22:50     INFO - liftError@resource://gre/modules/RustSuggest.sys.mjs:512:69
[task 2024-03-14T05:22:50.824Z] 05:22:50     INFO - handleRustResult@resource://gre/modules/RustSuggest.sys.mjs:204:19
[task 2024-03-14T05:22:50.824Z] 05:22:50     INFO - fetchGlobalConfig/<@resource://gre/modules/RustSuggest.sys.mjs:520:68
[task 2024-03-14T05:22:50.824Z] 05:22:50     INFO - promise callback*fetchGlobalConfig@resource://gre/modules/RustSuggest.sys.mjs:520:35
[task 2024-03-14T05:22:50.824Z] 05:22:50     INFO - #ingestHelper@resource:///modules/urlbar/private/SuggestBackendRust.sys.mjs:320:38
[task 2024-03-14T05:22:50.824Z] 05:22:50     INFO - async*#ingest@resource:///modules/urlbar/private/SuggestBackendRust.sys.mjs:291:52
[task 2024-03-14T05:22:50.824Z] 05:22:50     INFO - async*_test_ingest@resource:///modules/urlbar/private/SuggestBackendRust.sys.mjs:358:23
[task 2024-03-14T05:22:50.824Z] 05:22:50     INFO - forceSync@resource://testing-common/QuickSuggestTestUtils.sys.mjs:340:43
[task 2024-03-14T05:22:50.824Z] 05:22:50     INFO - newTaskFn@chrome://mochitests/content/browser/browser/components/urlbar/tests/quicksuggest/browser/head.js:688:37
[task 2024-03-14T05:22:50.824Z] 05:22:50     INFO - async*handleTask@chrome://mochikit/content/browser-test.js:1139:26
[task 2024-03-14T05:22:50.824Z] 05:22:50     INFO - _runTaskBasedTest@chrome://mochikit/content/browser-test.js:1211:18
[task 2024-03-14T05:22:50.824Z] 05:22:50     INFO - async*Tester_execTest@chrome://mochikit/content/browser-test.js:1353:14
[task 2024-03-14T05:22:50.824Z] 05:22:50     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1128:14
[task 2024-03-14T05:22:50.824Z] 05:22:50     INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13
[task 2024-03-14T05:22:50.825Z] 05:22:50     INFO - Leaving test bound test_weather_result_selection_rustDisabled
[task 2024-03-14T05:22:50.825Z] 05:22:50     INFO - Entering test bound test_weather_result_selection_rustEnabled
[task 2024-03-14T05:22:50.826Z] 05:22:50     INFO - add_tasks_with_rust: Setting rustEnabled: true
[task 2024-03-14T05:22:50.826Z] 05:22:50     INFO - add_tasks_with_rust: Done setting rustEnabled: true
[task 2024-03-14T05:22:50.827Z] 05:22:50     INFO - add_tasks_with_rust: Forcing sync
[task 2024-03-14T05:22:50.827Z] 05:22:50     INFO - QuickSuggestTestUtils.forceSync Started
[task 2024-03-14T05:22:50.828Z] 05:22:50     INFO - QuickSuggestTestUtils.forceSync Syncing Rust backend
[task 2024-03-14T05:22:50.828Z] 05:22:50     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-03-14T05:22:50.830Z] 05:22:50     INFO - TEST-UNEXPECTED-FAIL | browser/components/urlbar/tests/quicksuggest/browser/browser_weather.js | Uncaught exception in test bound test_weather_result_selection_rustEnabled - at resource://gre/modules/RustSuggest.sys.mjs:992 - Other: Error
[task 2024-03-14T05:22:50.830Z] 05:22:50     INFO - Stack trace:
[task 2024-03-14T05:22:50.830Z] 05:22:50     INFO - SuggestApiError@resource://gre/modules/RustSuggest.sys.mjs:992:8
[task 2024-03-14T05:22:50.830Z] 05:22:50     INFO - Other@resource://gre/modules/RustSuggest.sys.mjs:1041:9
[task 2024-03-14T05:22:50.831Z] 05:22:50     INFO - read@resource://gre/modules/RustSuggest.sys.mjs:1065:24
[task 2024-03-14T05:22:50.831Z] 05:22:50     INFO - lift@resource://gre/modules/RustSuggest.sys.mjs:247:21
[task 2024-03-14T05:22:50.831Z] 05:22:50     INFO - liftError@resource://gre/modules/RustSuggest.sys.mjs:512:69
[task 2024-03-14T05:22:50.831Z] 05:22:50     INFO - handleRustResult@resource://gre/modules/RustSuggest.sys.mjs:204:19
[task 2024-03-14T05:22:50.831Z] 05:22:50     INFO - fetchGlobalConfig/<@resource://gre/modules/RustSuggest.sys.mjs:520:68
[task 2024-03-14T05:22:50.831Z] 05:22:50     INFO - promise callback*fetchGlobalConfig@resource://gre/modules/RustSuggest.sys.mjs:520:35
[task 2024-03-14T05:22:50.831Z] 05:22:50     INFO - #ingestHelper@resource:///modules/urlbar/private/SuggestBackendRust.sys.mjs:320:38
[task 2024-03-14T05:22:50.831Z] 05:22:50     INFO - async*#ingest@resource:///modules/urlbar/private/SuggestBackendRust.sys.mjs:291:52
[task 2024-03-14T05:22:50.831Z] 05:22:50     INFO - async*_test_ingest@resource:///modules/urlbar/private/SuggestBackendRust.sys.mjs:358:23
[task 2024-03-14T05:22:50.831Z] 05:22:50     INFO - forceSync@resource://testing-common/QuickSuggestTestUtils.sys.mjs:340:43
[task 2024-03-14T05:22:50.831Z] 05:22:50     INFO - newTaskFn@chrome://mochitests/content/browser/browser/components/urlbar/tests/quicksuggest/browser/head.js:688:37
INFO - async*handleTask@chrome://mochikit/content/browser-test.js:1139:26
[task 2024-03-14T05:22:50.831Z] 05:22:50     INFO - _runTaskBasedTest@chrome://mochikit/content/browser-test.js:1211:18
[task 2024-03-14T05:22:50.831Z] 05:22:50     INFO - async*Tester_execTest@chrome://mochikit/content/browser-test.js:1353:14
[task 2024-03-14T05:22:50.831Z] 05:22:50     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1128:14
[task 2024-03-14T05:22:50.831Z] 05:22:50     INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13
[task 2024-03-14T05:22:50.831Z] 05:22:50     INFO - Leaving test bound test_weather_result_selection_rustEnabled
[task 2024-03-14T05:22:50.832Z] 05:22:50     INFO - Entering test bound showLessFrequentlyCapReached_manySearches_rustDisabled
[task 2024-03-14T05:22:50.833Z] 05:22:50     INFO - add_tasks_with_rust: Setting rustEnabled: false
[task 2024-03-14T05:22:50.833Z] 05:22:50     INFO - add_tasks_with_rust: Done setting rustEnabled: false
[task 2024-03-14T05:22:50.834Z] 05:22:50     INFO - add_tasks_with_rust: Forcing sync
[task 2024-03-14T05:22:50.834Z] 05:22:50     INFO - QuickSuggestTestUtils.forceSync Started
[task 2024-03-14T05:22:50.835Z] 05:22:50     INFO - QuickSuggestTestUtils.forceSync Syncing JS backend
[task 2024-03-14T05:22:50.835Z] 05:22:50     INFO - GECKO(15611) | console.info: RemoteSettingsServer: "< HTTP 1.1 GET /v1/buckets/monitor/collections/changes/changeset?collection=quicksuggest&bucket=main&_expected=0"
[task 2024-03-14T05:22:50.836Z] 05:22:50     INFO - GECKO(15611) | console.info: RemoteSettingsServer: "> 200 /v1/buckets/monitor/collections/changes/changeset"
[task 2024-03-14T05:22:50.836Z] 05:22:50     INFO - GECKO(15611) | console.info: RemoteSettingsServer: "< HTTP 1.1 GET /v1/buckets/monitor/collections/changes/changeset?collection=quicksuggest&bucket=main&_expected=0"
[task 2024-03-14T05:22:50.837Z] 05:22:50     INFO - GECKO(15611) | console.info: RemoteSettingsServer: "> 200 /v1/buckets/monitor/collections/changes/changeset"
[task 2024-03-14T05:22:50.837Z] 05:22:50     INFO - QuickSuggestTestUtils.forceSync Done syncing JS backend
[task 2024-03-14T05:22:50.838Z] 05:22:50     INFO - QuickSuggestTestUtils.forceSync Done
[task 2024-03-14T05:22:50.838Z] 05:22:50     INFO - add_tasks_with_rust: Done forcing sync
[task 2024-03-14T05:22:50.839Z] 05:22:50     INFO - add_tasks_with_rust: Calling setup function
[task 2024-03-14T05:22:50.839Z] 05:22:50     INFO - MockMerinoServer received request with query string: "q=&sid=52141607-bbb6-4849-b176-f1d7f47ed6fb&seq=0&providers=accuweather"
[task 2024-03-14T05:22:50.840Z] 05:22:50     INFO - MockMerinoServer replying with response: {"status":200,"contentType":"application/json","body":{"request_id":"request_id","suggestions":[{"title":"Weather for San Francisco","url":"https://example.com/weather","provider":"accuweather","is_sponsored":false,"score":0.2,"icon":null,"city_name":"San Francisco","current_conditions":{"url":"https://example.com/weather-current-conditions","summary":"Mostly cloudy","icon_id":6,"temperature":{"c":15.5,"f":60}},"forecast":{"url":"https://example.com/weather-forecast","summary":"Pleasant Saturday","high":{"c":21.1,"f":70},"low":{"c":13.9,"f":57}}}]}}
[task 2024-03-14T05:22:50.841Z] 05:22:50     INFO - add_tasks_with_rust: Done calling setup function
[task 2024-03-14T05:22:50.842Z] 05:22:50     INFO - add_tasks_with_rust: Calling original task function: showLessFrequentlyCapReached_manySearches
[task 2024-03-14T05:22:50.842Z] 05:22:50     INFO - QuickSuggestTestUtils.setRemoteSettingsRecords Started
[task 2024-03-14T05:22:50.843Z] 05:22:50     INFO - QuickSuggestTestUtils.setRemoteSettingsRecords Forcing sync
<...>

Comment 1

[Intermittent Failures Robot]

5 failures in 3849 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 2
• autoland: 1
• mozilla-central: 2

Platform and build breakdown:

• windows11-64-2009-asan-qr: 1
  • opt: 1
• linux1804-64-asan-qr: 3
  • opt: 3
• linux1804-64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1885264&startday=2024-03-11&endday=2024-03-17&tree=all

Comment 2

[Drew Willcoxon :adw]

This is logged in some failing runs I looked at, shortly before the failures. It's not logged when I run the test locally and it finishes successfully.

[ERROR error_support::handling] suggest-unexpected: Error executing SQL: interrupted

Comment 3

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

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.