Filed by: nfay [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=415814271&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/VVvxssAAS5OqXUcI-3D6yQ/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/VVvxssAAS5OqXUcI-3D6yQ/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1
[task 2023-05-15T13:24:16.024Z] 13:24:16 INFO - TEST-START | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js
[task 2023-05-15T13:25:03.314Z] 13:25:03 INFO - TEST-INFO | started process screencapture
[task 2023-05-15T13:25:03.446Z] 13:25:03 INFO - TEST-INFO | screencapture: exit 0
[task 2023-05-15T13:25:03.446Z] 13:25:03 INFO - <snipped 401 output lines - if you need more context, please use SimpleTest.requestCompleteLog() in your test>
[task 2023-05-15T13:25:03.447Z] 13:25:03 INFO - Buffered messages logged at 13:24:56
[task 2023-05-15T13:25:03.447Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | true == true -
[task 2023-05-15T13:25:03.448Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | The view should remain open clicking the command - true == true -
[task 2023-05-15T13:25:03.448Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | 3 == 3 -
[task 2023-05-15T13:25:03.449Z] 13:25:03 INFO - Awaiting for the urlbar panel to open
[task 2023-05-15T13:25:03.449Z] 13:25:03 INFO - Urlbar panel opened
[task 2023-05-15T13:25:03.449Z] 13:25:03 INFO - Buffered messages logged at 13:24:57
[task 2023-05-15T13:25:03.450Z] 13:25:03 INFO - MockMerinoServer received request with query string: "q=12&sid=ad9189ac-243c-4b62-a101-b39ce0056903&seq=33"
[task 2023-05-15T13:25:03.451Z] 13:25:03 INFO - MockMerinoServer replying with response: {"status":200,"contentType":"application/json","body":{"request_id":"request_id","suggestions":[{"provider":"amo","icon":"https://example.com/first.svg","url":"https://example.com/first-addon","title":"First Addon","description":"This is a first addon","custom_details":{"amo":{"rating":"5","number_of_ratings":"1234567"}},"is_top_pick":true},{"provider":"amo","icon":"https://example.com/second.png","url":"https://example.com/second-addon","title":"Second Addon","description":"This is a second addon","custom_details":{"amo":{"rating":"4.5","number_of_ratings":"123"}},"is_sponsored":true},{"provider":"amo","icon":"https://example.com/third.svg","url":"https://example.com/third-addon","title":"Third Addon","description":"This is a third addon","custom_details":{"amo":{"rating":"0","number_of_ratings":"0"}}}]}}
[task 2023-05-15T13:25:03.452Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | Addons suggestion should be absent (checking index 0) - "undefined" != "addons" -
[task 2023-05-15T13:25:03.452Z] 13:25:03 INFO - MockMerinoServer received request with query string: "q=123&sid=ad9189ac-243c-4b62-a101-b39ce0056903&seq=34"
[task 2023-05-15T13:25:03.453Z] 13:25:03 INFO - MockMerinoServer replying with response: {"status":200,"contentType":"application/json","body":{"request_id":"request_id","suggestions":[{"provider":"amo","icon":"https://example.com/first.svg","url":"https://example.com/first-addon","title":"First Addon","description":"This is a first addon","custom_details":{"amo":{"rating":"5","number_of_ratings":"1234567"}},"is_top_pick":true},{"provider":"amo","icon":"https://example.com/second.png","url":"https://example.com/second-addon","title":"Second Addon","description":"This is a second addon","custom_details":{"amo":{"rating":"4.5","number_of_ratings":"123"}},"is_sponsored":true},{"provider":"amo","icon":"https://example.com/third.svg","url":"https://example.com/third-addon","title":"Third Addon","description":"This is a third addon","custom_details":{"amo":{"rating":"0","number_of_ratings":"0"}}}]}}
[task 2023-05-15T13:25:03.454Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | Addons suggestion should be present at expected index after 123 search - "addons" == "addons" -
[task 2023-05-15T13:25:03.454Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | view should be open - true == true -
[task 2023-05-15T13:25:03.455Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | found the menu button at result index 1 - {"elementIndex":2} == true -
[task 2023-05-15T13:25:03.455Z] 13:25:03 INFO - selecting the result at index 1
[task 2023-05-15T13:25:03.456Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | selected the menu button at result index 1 - {"elementIndex":2} == {"elementIndex":2} -
[task 2023-05-15T13:25:03.456Z] 13:25:03 INFO - waiting for KEY_Enter to open the menu popup
[task 2023-05-15T13:25:03.457Z] 13:25:03 INFO - Buffered messages logged at 13:24:58
[task 2023-05-15T13:25:03.457Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | Checking popup state - "open" == "open" -
[task 2023-05-15T13:25:03.458Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | true == true -
[task 2023-05-15T13:25:03.458Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | Row should not have feedback acknowledgment after clicking command - true == true -
[task 2023-05-15T13:25:03.459Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | "Menu item not found for command: show_less_frequently" == "Menu item not found for command: show_less_frequently" -
[task 2023-05-15T13:25:03.459Z] 13:25:03 INFO - Leaving test bound resultMenu_showLessFrequentlyWithNimbusMinimumLength
[task 2023-05-15T13:25:03.459Z] 13:25:03 INFO - Entering test bound resultMenu_notInterested
[task 2023-05-15T13:25:03.460Z] 13:25:03 INFO - initNimbusFeature awaiting ExperimentManager.onStartup
[task 2023-05-15T13:25:03.460Z] 13:25:03 INFO - initNimbusFeature awaiting ExperimentAPI.ready
[task 2023-05-15T13:25:03.460Z] 13:25:03 INFO - initNimbusFeature awaiting ExperimentFakes.enrollWithRollout
[task 2023-05-15T13:25:03.461Z] 13:25:03 INFO - initNimbusFeature done
[task 2023-05-15T13:25:03.461Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | 0 == 0 -
[task 2023-05-15T13:25:03.462Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | 0 == 0 -
[task 2023-05-15T13:25:03.462Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | 0 == 0 -
[task 2023-05-15T13:25:03.462Z] 13:25:03 INFO - Awaiting for the urlbar panel to open
[task 2023-05-15T13:25:03.463Z] 13:25:03 INFO - Urlbar panel opened
[task 2023-05-15T13:25:03.463Z] 13:25:03 INFO - MockMerinoServer received request with query string: "q=123&sid=ad9189ac-243c-4b62-a101-b39ce0056903&seq=35"
[task 2023-05-15T13:25:03.465Z] 13:25:03 INFO - MockMerinoServer replying with response: {"status":200,"contentType":"application/json","body":{"request_id":"request_id","suggestions":[{"provider":"amo","icon":"https://example.com/first.svg","url":"https://example.com/first-addon","title":"First Addon","description":"This is a first addon","custom_details":{"amo":{"rating":"5","number_of_ratings":"1234567"}},"is_top_pick":true},{"provider":"amo","icon":"https://example.com/second.png","url":"https://example.com/second-addon","title":"Second Addon","description":"This is a second addon","custom_details":{"amo":{"rating":"4.5","number_of_ratings":"123"}},"is_sponsored":true},{"provider":"amo","icon":"https://example.com/third.svg","url":"https://example.com/third-addon","title":"Third Addon","description":"This is a third addon","custom_details":{"amo":{"rating":"0","number_of_ratings":"0"}}}]}}
[task 2023-05-15T13:25:03.465Z] 13:25:03 INFO - Buffered messages logged at 13:24:59
[task 2023-05-15T13:25:03.465Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | Addons suggestion should be present - "addons" == "addons" -
[task 2023-05-15T13:25:03.466Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | view should be open - true == true -
[task 2023-05-15T13:25:03.466Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | found the menu button at result index 1 - {"elementIndex":2} == true -
[task 2023-05-15T13:25:03.467Z] 13:25:03 INFO - selecting the result at index 1
[task 2023-05-15T13:25:03.467Z] 13:25:03 INFO - waiting for the menu popup to open via mouse
[task 2023-05-15T13:25:03.467Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | Checking popup state - "open" == "open" -
[task 2023-05-15T13:25:03.468Z] 13:25:03 INFO - Clicking menu item with selector: [data-l10n-id=firefox-suggest-command-dont-show-this]
[task 2023-05-15T13:25:03.468Z] 13:25:03 INFO - Waiting for submenu popupshown event
[task 2023-05-15T13:25:03.469Z] 13:25:03 INFO - Got the submenu popupshown event
[task 2023-05-15T13:25:03.469Z] 13:25:03 INFO - Clicking menu item with command: not_interested
[task 2023-05-15T13:25:03.469Z] 13:25:03 INFO - Waiting for command event
[task 2023-05-15T13:25:03.470Z] 13:25:03 INFO - Buffered messages logged at 13:25:00
[task 2023-05-15T13:25:03.470Z] 13:25:03 INFO - Got the command event
[task 2023-05-15T13:25:03.470Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | addons.featureGate pref should be set to false after dismissal - true == true -
[task 2023-05-15T13:25:03.471Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | The view should remain open after dismissal - true == true -
[task 2023-05-15T13:25:03.471Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | The result count should not haved changed after dismissal - 2 == 2 -
[task 2023-05-15T13:25:03.472Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | Row should be a tip after dismissal - 7 == 7 -
[task 2023-05-15T13:25:03.473Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | Tip type should be dismissalAcknowledgment - "dismissalAcknowledgment" == "dismissalAcknowledgment" -
[task 2023-05-15T13:25:03.473Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | Row should not have feedback acknowledgment after dismissal - true == true -
[task 2023-05-15T13:25:03.473Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | Row should have a 'Got it' button - {"elementIndex":1} == true -
[task 2023-05-15T13:25:03.474Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | The view should remain open clicking the 'Got it' button - true == true -
[task 2023-05-15T13:25:03.474Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | The result count should be one less after clicking 'Got it' button - 1 == 1 -
[task 2023-05-15T13:25:03.475Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | Tip result and addon result should not be present - true == true -
[task 2023-05-15T13:25:03.475Z] 13:25:03 INFO - Leaving test bound resultMenu_notInterested
[task 2023-05-15T13:25:03.476Z] 13:25:03 INFO - Entering test bound notRelevant
[task 2023-05-15T13:25:03.476Z] 13:25:03 INFO - initNimbusFeature awaiting ExperimentManager.onStartup
[task 2023-05-15T13:25:03.476Z] 13:25:03 INFO - initNimbusFeature awaiting ExperimentAPI.ready
[task 2023-05-15T13:25:03.477Z] 13:25:03 INFO - initNimbusFeature awaiting ExperimentFakes.enrollWithRollout
[task 2023-05-15T13:25:03.477Z] 13:25:03 INFO - initNimbusFeature done
[task 2023-05-15T13:25:03.478Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | 0 == 0 -
[task 2023-05-15T13:25:03.478Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | 0 == 0 -
[task 2023-05-15T13:25:03.478Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | 0 == 0 -
[task 2023-05-15T13:25:03.479Z] 13:25:03 INFO - Awaiting for the urlbar panel to open
[task 2023-05-15T13:25:03.479Z] 13:25:03 INFO - Urlbar panel opened
[task 2023-05-15T13:25:03.480Z] 13:25:03 INFO - MockMerinoServer received request with query string: "q=123&sid=4a01d6b2-c9be-4ba9-957b-cac3157e39db&seq=0"
[task 2023-05-15T13:25:03.481Z] 13:25:03 INFO - MockMerinoServer replying with response: {"status":200,"contentType":"application/json","body":{"request_id":"request_id","suggestions":[{"provider":"amo","icon":"https://example.com/first.svg","url":"https://example.com/first-addon","title":"First Addon","description":"This is a first addon","custom_details":{"amo":{"rating":"5","number_of_ratings":"1234567"}},"is_top_pick":true},{"provider":"amo","icon":"https://example.com/second.png","url":"https://example.com/second-addon","title":"Second Addon","description":"This is a second addon","custom_details":{"amo":{"rating":"4.5","number_of_ratings":"123"}},"is_sponsored":true},{"provider":"amo","icon":"https://example.com/third.svg","url":"https://example.com/third-addon","title":"Third Addon","description":"This is a third addon","custom_details":{"amo":{"rating":"0","number_of_ratings":"0"}}}]}}
[task 2023-05-15T13:25:03.481Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | Addons suggestion should be present - "addons" == "addons" -
[task 2023-05-15T13:25:03.482Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | view should be open - true == true -
[task 2023-05-15T13:25:03.482Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | found the menu button at result index 1 - {"elementIndex":2} == true -
[task 2023-05-15T13:25:03.482Z] 13:25:03 INFO - selecting the result at index 1
[task 2023-05-15T13:25:03.483Z] 13:25:03 INFO - Buffered messages logged at 13:25:01
[task 2023-05-15T13:25:03.483Z] 13:25:03 INFO - waiting for the menu popup to open via mouse
[task 2023-05-15T13:25:03.484Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | Checking popup state - "open" == "open" -
[task 2023-05-15T13:25:03.484Z] 13:25:03 INFO - Clicking menu item with selector: [data-l10n-id=firefox-suggest-command-dont-show-this]
[task 2023-05-15T13:25:03.484Z] 13:25:03 INFO - Waiting for submenu popupshown event
[task 2023-05-15T13:25:03.485Z] 13:25:03 INFO - Got the submenu popupshown event
[task 2023-05-15T13:25:03.485Z] 13:25:03 INFO - Clicking menu item with command: not_relevant
[task 2023-05-15T13:25:03.486Z] 13:25:03 INFO - Waiting for command event
[task 2023-05-15T13:25:03.486Z] 13:25:03 INFO - Got the command event
[task 2023-05-15T13:25:03.487Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | addons.featureGate pref should be set to false after dismissal - true == true -
[task 2023-05-15T13:25:03.487Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | The view should remain open after dismissal - true == true -
[task 2023-05-15T13:25:03.488Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | The result count should not haved changed after dismissal - 2 == 2 -
[task 2023-05-15T13:25:03.488Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | Row should be a tip after dismissal - 7 == 7 -
[task 2023-05-15T13:25:03.489Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | Tip type should be dismissalAcknowledgment - "dismissalAcknowledgment" == "dismissalAcknowledgment" -
[task 2023-05-15T13:25:03.489Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | Row should not have feedback acknowledgment after dismissal - true == true -
[task 2023-05-15T13:25:03.490Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | Row should have a 'Got it' button - {"elementIndex":1} == true -
[task 2023-05-15T13:25:03.490Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | The view should remain open clicking the 'Got it' button - true == true -
[task 2023-05-15T13:25:03.490Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | The result count should be one less after clicking 'Got it' button - 1 == 1 -
[task 2023-05-15T13:25:03.491Z] 13:25:03 INFO - TEST-PASS | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | Tip result and addon result should not be present - true == true -
[task 2023-05-15T13:25:03.491Z] 13:25:03 INFO - Leaving test bound notRelevant
[task 2023-05-15T13:25:03.491Z] 13:25:03 INFO - Buffered messages logged at 13:25:03
[task 2023-05-15T13:25:03.492Z] 13:25:03 INFO - MerinoTestUtils cleanup function
[task 2023-05-15T13:25:03.492Z] 13:25:03 INFO - MerinoTestUtils uninit: Depth is now 1
[task 2023-05-15T13:25:03.492Z] 13:25:03 INFO - MerinoTestUtils uninit: Bailing because depth > 0
[task 2023-05-15T13:25:03.492Z] 13:25:03 INFO - MockMerinoServer stopping
[task 2023-05-15T13:25:03.492Z] 13:25:03 INFO - MockMerinoServer is now stopped
[task 2023-05-15T13:25:03.493Z] 13:25:03 INFO - MerinoTestUtils cleanup function
[task 2023-05-15T13:25:03.493Z] 13:25:03 INFO - MerinoTestUtils uninit: Depth is now 0
[task 2023-05-15T13:25:03.493Z] 13:25:03 INFO - MerinoTestUtils uninit: Now uninitializing
[task 2023-05-15T13:25:03.494Z] 13:25:03 INFO - MockMerinoServer uninit
[task 2023-05-15T13:25:03.494Z] 13:25:03 INFO - Buffered messages finished
[task 2023-05-15T13:25:03.494Z] 13:25:03 INFO - TEST-UNEXPECTED-FAIL | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | This test exceeded the timeout threshold. It should be rewritten or split up. If that's not possible, use requestLongerTimeout(N), but only as a last resort. -
[task 2023-05-15T13:25:03.495Z] 13:25:03 INFO - GECKO(2194) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
[task 2023-05-15T13:25:03.495Z] 13:25:03 INFO - GECKO(2194) | MEMORY STAT | vsize 7848MB | residentFast 299MB | heapAllocated 127MB
[task 2023-05-15T13:25:03.496Z] 13:25:03 INFO - TEST-OK | browser/components/urlbar/tests/quicksuggest/browser/browser_quicksuggest_addons.js | took 47290ms
[task 2023-05-15T13:25:03.496Z] 13:25:03 INFO - checking window state
[task 2023-05-15T13:25:03.496Z] 13:25:03 INFO - GECKO(2194) | Completed ShutdownLeaks collections in process 2194
[task 2023-05-15T13:25:03.497Z] 13:25:03 INFO - TEST-START | Shutdown
[task 2023-05-15T13:25:03.497Z] 13:25:03 INFO - Browser Chrome Test Summary
[task 2023-05-15T13:25:03.497Z] 13:25:03 INFO - Passed: 301
[task 2023-05-15T13:25:03.497Z] 13:25:03 INFO - Failed: 1
[task 2023-05-15T13:25:03.498Z] 13:25:03 INFO - Todo: 0
[task 2023-05-15T13:25:03.498Z] 13:25:03 INFO - Mode: e10s
[task 2023-05-15T13:25:03.498Z] 13:25:03 INFO - *** End BrowserChrome Test Results ***
Description
•