Open Bug 2046574 Opened 20 hours ago Updated 1 hour ago

Perma browser/components/search/test/browser/browser_searchbar_keyboard_navigation.js | test_open_search - the opensearch.html page exposes 3 engines - Got +0, expected 3

Categories

(Firefox :: Search, defect, P5)

Product:
Firefox
Component:
Search
Type:
defect

Tracking

()

Status:
ASSIGNED
Tracking Flags:
Tracking Status
firefox-esr140 --- unaffected
firefox151 --- unaffected
firefox152 --- unaffected
firefox153 --- affected

People

(Reporter: intermittent-bug-filer, Assigned: florian, NeedInfo)

References

(Regression)

Details

(Keywords: intermittent-failure, intermittent-testcase, regression)

Attachments

(1 file)

Bug 2046574 - Wait for the search one-offs rebuild before checking OpenSearch engines in test_open_search, r=#search-reviewers.
48 bytes, text/x-phabricator-request
Details | Review

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

[task 2026-06-10T19:02:39.805+00:00] 19:02:39     INFO - TEST-PASS | browser/components/search/test/browser/browser_searchbar_keyboard_navigation.js | test_tab_and_arrows - there should be no selected suggestion
[task 2026-06-10T19:02:39.806+00:00] 19:02:39     INFO - Leaving test test_tab_and_arrows
[task 2026-06-10T19:02:39.807+00:00] 19:02:39     INFO - Entering test test_open_search
[task 2026-06-10T19:02:39.808+00:00] 19:02:39     INFO - Opening search panel
[task 2026-06-10T19:02:39.808+00:00] 19:02:39     INFO - Buffered messages finished
[task 2026-06-10T19:02:39.809+00:00] 19:02:39     INFO - TEST-UNEXPECTED-FAIL | browser/components/search/test/browser/browser_searchbar_keyboard_navigation.js | test_open_search - the opensearch.html page exposes 3 engines - Got +0, expected 3
[task 2026-06-10T19:02:39.809+00:00] 19:02:39     INFO - chrome://mochikit/content/browser-test.js:test_is:1920
[task 2026-06-10T19:02:39.809+00:00] 19:02:39     INFO - chrome://mochitests/content/browser/browser/components/search/test/browser/browser_searchbar_keyboard_navigation.js:test_open_search:587
[task 2026-06-10T19:02:39.810+00:00] 19:02:39     INFO - chrome://mochikit/content/browser-test.js:handleTask:1377
[task 2026-06-10T19:02:39.810+00:00] 19:02:39     INFO - chrome://mochikit/content/browser-test.js:_runTaskBasedTest:1467
[task 2026-06-10T19:02:39.810+00:00] 19:02:39     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1619
[task 2026-06-10T19:02:39.810+00:00] 19:02:39     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1357
[task 2026-06-10T19:02:39.810+00:00] 19:02:39     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1060
[task 2026-06-10T19:02:39.810+00:00] 19:02:39     INFO - TEST-PASS | browser/components/search/test/browser/browser_searchbar_keyboard_navigation.js | test_open_search - no suggestion should be selected
[task 2026-06-10T19:02:39.811+00:00] 19:02:39     INFO - TEST-PASS | browser/components/search/test/browser/browser_searchbar_keyboard_navigation.js | test_open_search - no button should be selected
[task 2026-06-10T19:02:39.811+00:00] 19:02:39     INFO - TEST-PASS | browser/components/search/test/browser/browser_searchbar_keyboard_navigation.js | test_open_search - the settings item should be selected
[task 2026-06-10T19:02:39.812+00:00] 19:02:39     INFO - Not taking screenshot here: see the one that was previously logged
[task 2026-06-10T19:02:39.812+00:00] 19:02:39     INFO - TEST-UNEXPECTED-FAIL | browser/components/search/test/browser/browser_searchbar_keyboard_navigation.js | test_open_search - the last one-off button should be selected - Got [object XULElement], expected [object XULElement]
[task 2026-06-10T19:02:39.812+00:00] 19:02:39     INFO - chrome://mochikit/content/browser-test.js:test_is:1920
[task 2026-06-10T19:02:39.812+00:00] 19:02:39     INFO - chrome://mochitests/content/browser/browser/components/search/test/browser/browser_searchbar_keyboard_navigation.js:test_open_search:618
[task 2026-06-10T19:02:39.812+00:00] 19:02:39     INFO - chrome://mochikit/content/browser-test.js:handleTask:1377
[task 2026-06-10T19:02:39.812+00:00] 19:02:39     INFO - chrome://mochikit/content/browser-test.js:_runTaskBasedTest:1467
[task 2026-06-10T19:02:39.812+00:00] 19:02:39     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1619
[task 2026-06-10T19:02:39.812+00:00] 19:02:39     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1357
[task 2026-06-10T19:02:39.812+00:00] 19:02:39     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1060
[task 2026-06-10T19:02:39.814+00:00] 19:02:39     INFO - now check that the down key navigates open search items as expected
[task 2026-06-10T19:02:39.815+00:00] 19:02:39     INFO - Not taking screenshot here: see the one that was previously logged
[task 2026-06-10T19:02:39.815+00:00] 19:02:39     INFO - TEST-UNEXPECTED-FAIL | browser/components/search/test/browser/browser_searchbar_keyboard_navigation.js | test_open_search - the settings item should be selected
[task 2026-06-10T19:02:39.815+00:00] 19:02:39     INFO - chrome://mochikit/content/browser-test.js:test_ok:1892
[task 2026-06-10T19:02:39.815+00:00] 19:02:39     INFO - chrome://mochitests/content/browser/browser/components/search/test/browser/browser_searchbar_keyboard_navigation.js:test_open_search:636
[task 2026-06-10T19:02:39.816+00:00] 19:02:39     INFO - chrome://mochikit/content/browser-test.js:handleTask:1377
[task 2026-06-10T19:02:39.816+00:00] 19:02:39     INFO - chrome://mochikit/content/browser-test.js:_runTaskBasedTest:1467
[task 2026-06-10T19:02:39.816+00:00] 19:02:39     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1619
[task 2026-06-10T19:02:39.816+00:00] 19:02:39     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1357
[task 2026-06-10T19:02:39.816+00:00] 19:02:39     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1060
[task 2026-06-10T19:02:39.817+00:00] 19:02:39     INFO - Leaving test test_open_search
[task 2026-06-10T19:02:39.818+00:00] 19:02:39     INFO - Entering test cleanup
[task 2026-06-10T19:02:39.819+00:00] 19:02:39     INFO - removing search history values: foo1,foo2,foo3
[task 2026-06-10T19:02:39.819+00:00] 19:02:39     INFO - Leaving test cleanup
[task 2026-06-10T19:02:39.981+00:00] 19:02:39     INFO - GECKO(4516) | MEMORY STAT | vsize 2112609MB | vsizeMaxContiguous 65778497MB | residentFast 337MB | heapAllocated 121MB
[task 2026-06-10T19:02:39.990+00:00] 19:02:39     INFO - checking for open sidebars
[task 2026-06-10T19:02:39.990+00:00] 19:02:39     INFO - checking window state
[task 2026-06-10T19:02:39.991+00:00] 19:02:39     INFO - Not taking screenshot here: see the one that was previously logged
[task 2026-06-10T19:02:39.991+00:00] 19:02:39     INFO - TEST-UNEXPECTED-FAIL | browser/components/search/test/browser/browser_searchbar_keyboard_navigation.js | finished in 1563ms
[task 2026-06-10T19:02:39.991+00:00] 19:02:39     INFO - TEST-INFO took 1575ms
[task 2026-06-10T19:02:40.008+00:00] 19:02:40     INFO - GECKO(4516) | Completed ShutdownLeaks collections in process 1496
[task 2026-06-10T19:02:40.015+00:00] 19:02:40     INFO - TEST-START | Shutdown
[task 2026-06-10T19:02:40.015+00:00] 19:02:40     INFO - Browser Chrome Test Summary
[task 2026-06-10T19:02:40.017+00:00] 19:02:40     INFO - Passed:  192
[task 2026-06-10T19:02:40.017+00:00] 19:02:40     INFO - Failed:  3
[task 2026-06-10T19:02:40.018+00:00] 19:02:40     INFO - Todo:    0
[task 2026-06-10T19:02:40.018+00:00] 19:02:40     INFO - Mode:    e10s
[task 2026-06-10T19:02:40.019+00:00] 19:02:40     INFO - *** End BrowserChrome Test Results ***
[task 2026-06-10T19:02:40.040+00:00] 19:02:40     INFO - GECKO(4516) | Exiting due to channel error.
[task 2026-06-10T19:02:40.049+00:00] 19:02:40     INFO - GECKO(4516) | Exiting due to channel error.
[task 2026-06-10T19:02:40.103+00:00] 19:02:40     INFO - TEST-INFO | Main app process: exit 0
[task 2026-06-10T19:02:40.104+00:00] 19:02:40     INFO - runtests.py | Application ran for: 0:00:05.574052
[task 2026-06-10T19:02:40.105+00:00] 19:02:40     INFO - zombiecheck | Reading PID log: C:\Users\task_178111498130517\AppData\Local\Temp\tmpwduhwz4hpidlog
[task 2026-06-10T19:02:40.106+00:00] 19:02:40     INFO - ==> process 1496 launched child process 8296 ("D:\task_178111498130517\build\application\firefox\firefox.exe" -contentproc -parentBuildID 20260608094347 -prefsHandle 2416:27878 -prefMapHandle 2432:310033 -ipcHandle 2452 -initialChannelId {15c0614a-29fe-44d9-81d9-d093ef4421e6} -parentPid 1496 -crashReporter "\\.\pipe\gecko-crash-server-pipe.1496" -crashHelperPid 0 -crashHelper 2476 -appDir "D:\task_178111498130517\build\application\firefox\browser" - 1 gpu)
[task 2026-06-10T19:02:40.107+00:00] 19:02:40     INFO - ==> process 1496 launched child process 1168 ("D:\task_178111498130517\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 2804:27643 -prefMapHandle 2808:310033 -jsInitHandle 2812:164512 -parentBuildID 20260608094347 -ipcHandle 2816 -initialChannelId {5899cdb0-068e-4ca9-9adc-ce2a1456575c} -parentPid 1496 -crashReporter "\\.\pipe\gecko-crash-server-pipe.1496" -crashHelperPid 0 -crashHelper 2828 -win32kLockedDown -appDir "D:\task_178111498130517\build\application\firefox\browser" - 2 tab)
[task 2026-06-10T19:02:40.108+00:00] 19:02:40     INFO - ==> process 1496 launched child process 2004 ("D:\task_178111498130517\build\application\firefox\firefox.exe" -contentproc -parentBuildID 20260608094347 -prefsHandle 3040:28934 -prefMapHandle 3044:310033 -ipcHandle 3052 -initialChannelId {0dcac14b-4216-45d7-88f5-372377871d48} -parentPid 1496 -crashReporter "\\.\pipe\gecko-crash-server-pipe.1496" -crashHelperPid 0 -crashHelper 3064 -appDir "D:\task_178111498130517\build\application\firefox\browser" - 3 rdd)
[task 2026-06-10T19:02:40.108+00:00] 19:02:40     INFO - ==> process 1496 launched child process 5384 ("D:\task_178111498130517\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 3448:29183 -prefMapHandle 3452:310033 -jsInitHandle 3456:164512 -parentBuildID 20260608094347 -ipcHandle 2332 -initialChannelId {09085394-91aa-4991-bac6-4ee4a9f0e065} -parentPid 1496 -crashReporter "\\.\pipe\gecko-crash-server-pipe.1496" -crashHelperPid 0 -crashHelper 3460 -win32kLockedDown -appDir "D:\task_178111498130517\build\application\firefox\browser" - 4 tab)
[task 2026-06-10T19:02:40.109+00:00] 19:02:40     INFO - ==> process 1496 launched child process 5664 ("D:\task_178111498130517\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 3936:28057 -prefMapHandle 3940:310033 -jsInitHandle 3944:164512 -parentBuildID 20260608094347 -ipcHandle 3952 -initialChannelId {b0e7f23b-4b76-4c62-9c6e-11d7de4c111d} -parentPid 1496 -crashReporter "\\.\pipe\gecko-crash-server-pipe.1496" -crashHelperPid 0 -crashHelper 3964 -win32kLockedDown -appDir "D:\task_178111498130517\build\application\firefox\browser" - 5 tab)
[task 2026-06-10T19:02:40.110+00:00] 19:02:40     INFO - ==> process 1496 launched child process 8148 ("D:\task_178111498130517\build\application\firefox\firefox.exe" -contentproc -parentBuildID 20260608094347 -sandboxingKind 0 -prefsHandle 4772:45717 -prefMapHandle 4768:310033 -ipcHandle 4808 -initialChannelId {c838c1dc-71a6-4550-8f1b-d5e6b413538f} -parentPid 1496 -crashReporter "\\.\pipe\gecko-crash-server-pipe.1496" -crashHelperPid 0 -crashHelper 4820 -win32kLockedDown -appDir "D:\task_178111498130517\build\application\firefox\browser" - 6 utility)
[task 2026-06-10T19:02:40.112+00:00] 19:02:40     INFO - ==> process 1496 launched child process 5440 ("D:\task_178111498130517\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 4784:43368 -prefMapHandle 4820:310033 -jsInitHandle 4808:164512 -parentBuildID 20260608094347 -ipcHandle 4772 -initialChannelId {c5b838dc-dba2-4b78-85c4-384f1d6bf837} -parentPid 1496 -crashReporter "\\.\pipe\gecko-crash-server-pipe.1496" -crashHelperPid 0 -crashHelper 4956 -win32kLockedDown -appDir "D:\task_178111498130517\build\application\firefox\browser" - 7 tab)
[task 2026-06-10T19:02:40.113+00:00] 19:02:40     INFO - ==> process 1496 launched child process 7184 ("D:\task_178111498130517\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 4944:43368 -prefMapHandle 4960:310033 -jsInitHandle 4952:164512 -parentBuildID 20260608094347 -ipcHandle 4964 -initialChannelId {7b7a978d-0b59-4a62-9232-22661c9b4dcf} -parentPid 1496 -crashReporter "\\.\pipe\gecko-crash-server-pipe.1496" -crashHelperPid 0 -crashHelper 5100 -win32kLockedDown -appDir "D:\task_178111498130517\build\application\firefox\browser" - 8 tab)
[task 2026-06-10T19:02:40.113+00:00] 19:02:40     INFO - ==> process 1496 launched child process 940 ("D:\task_178111498130517\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 5372:43368 -prefMapHandle 5376:310033 -jsInitHandle 5380:164512 -parentBuildID 20260608094347 -ipcHandle 5392 -initialChannelId {b606738d-03fa-45be-befa-fcf8dc7cd9a1} -parentPid 1496 -crashReporter "\\.\pipe\gecko-crash-server-pipe.1496" -crashHelperPid 0 -crashHelper 5408 -win32kLockedDown -appDir "D:\task_178111498130517\build\application\firefox\browser" - 9 tab)
[task 2026-06-10T19:02:40.114+00:00] 19:02:40     INFO - ==> process 1496 launched child process 6160 ("D:\task_178111498130517\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 5488:45898 -prefMapHandle 5516:310033 -jsInitHandle 5512:164512 -parentBuildID 20260608094347 -ipcHandle 5500 -initialChannelId {1088b72b-f475-4162-8bbe-5c64d844511b} -parentPid 1496 -crashReporter "\\.\pipe\gecko-crash-server-pipe.1496" -crashHelperPid 0 -crashHelper 5404 -win32kLockedDown -appDir "D:\task_178111498130517\build\application\firefox\browser" - 10 tab)
[task 2026-06-10T19:02:40.115+00:00] 19:02:40     INFO - ==> process 1496 launched child process 5536 ("D:\task_178111498130517\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 6108:43804 -prefMapHandle 6112:310033 -jsInitHandle 6116:164512 -parentBuildID 20260608094347 -ipcHandle 6012 -initialChannelId {5b6ab1bf-bb68-4d72-829b-cea7c276107e} -parentPid 1496 -crashReporter "\\.\pipe\gecko-crash-server-pipe.1496" -crashHelperPid 0 -crashHelper 6072 -win32kLockedDown -appDir "D:\task_178111498130517\build\application\firefox\browser" - 11 tab)
[task 2026-06-10T19:02:40.116+00:00] 19:02:40     INFO - zombiecheck | Checking for orphan process with PID: 5664
[task 2026-06-10T19:02:40.116+00:00] 19:02:40     INFO - zombiecheck | Checking for orphan process with PID: 5440
[task 2026-06-10T19:02:40.117+00:00] 19:02:40     INFO - zombiecheck | Checking for orphan process with PID: 5536
[task 2026-06-10T19:02:40.117+00:00] 19:02:40     INFO - zombiecheck | Checking for orphan process with PID: 8296
[task 2026-06-10T19:02:40.117+00:00] 19:02:40     INFO - zombiecheck | Checking for orphan process with PID: 5384
[task 2026-06-10T19:02:40.119+00:00] 19:02:40     INFO - zombiecheck | Checking for orphan process with PID: 940
[task 2026-06-10T19:02:40.119+00:00] 19:02:40     INFO - zombiecheck | Checking for orphan process with PID: 1168
[task 2026-06-10T19:02:40.119+00:00] 19:02:40     INFO - zombiecheck | Checking for orphan process with PID: 7184
[task 2026-06-10T19:02:40.120+00:00] 19:02:40     INFO - zombiecheck | Checking for orphan process with PID: 6160
[task 2026-06-10T19:02:40.121+00:00] 19:02:40     INFO - zombiecheck | Checking for orphan process with PID: 2004
[task 2026-06-10T19:02:40.121+00:00] 19:02:40     INFO - zombiecheck | Checking for orphan process with PID: 8148
[task 2026-06-10T19:02:40.122+00:00] 19:02:40     INFO - runtests.py | Running http tests: end. status: 0
[task 2026-06-10T19:02:40.123+00:00] 19:02:40     INFO - Stopping web server
[task 2026-06-10T19:02:40.123+00:00] 19:02:40     INFO - Server shut down.
[task 2026-06-10T19:02:40.123+00:00] 19:02:40     INFO - Web server killed.
[task 2026-06-10T19:02:40.124+00:00] 19:02:40     INFO - Stopping web socket server
[task 2026-06-10T19:02:40.124+00:00] 19:02:40     INFO - Stopping ssltunnel
[task 2026-06-10T19:02:40.125+00:00] 19:02:40  WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2026-06-10T19:02:40.166+00:00] 19:02:40     INFO - Buffered messages finished
[task 2026-06-10T19:02:40.173+00:00] 19:02:40     INFO - Running manifest: browser/components/urlbar/tests/browser-switchTab/browser.toml
[task 2026-06-10T19:02:40.423+00:00] 19:02:40     INFO - PID 8640 | D:\task_178111498130517\build\tests\bin\pk12util.exe: PKCS12 IMPORT SUCCESSFUL
[task 2026-06-10T19:02:40.423+00:00] 19:02:40     INFO - 
[task 2026-06-10T19:02:40.480+00:00] 19:02:40     INFO - MochitestServer : launching ['D:\\task_178111498130517\\build\\tests\\bin\\xpcshell.exe', '-g', 'D:\\task_178111498130517\\build\\application\\firefox', '-e', "const _PROFILE_PATH = 'C:\\\\Users\\\\task_178111498130517\\\\AppData\\\\Local\\\\Temp\\\\tmp1ayd7z43.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false; const _HTTPD_PATH = 'D:\\\\task_178111498130517\\\\build\\\\tests\\\\bin\\\\components'; ", '-f', 'D:\\task_178111498130517\\build\\tests\\mochitest\\server.js']
[task 2026-06-10T19:02:40.481+00:00] 19:02:40     INFO - runtests.py | Server pid: 1384
[task 2026-06-10T19:02:40.483+00:00] 19:02:40     INFO - runtests.py | Websocket server pid: 2108
[task 2026-06-10T19:02:40.487+00:00] 19:02:40     INFO - runtests.py | SSL tunnel pid: 7080
[task 2026-06-10T19:02:40.588+00:00] 19:02:40     INFO - use http3 server: False
[task 2026-06-10T19:02:40.589+00:00] 19:02:40     INFO - Use MOZ_NODE_PATH at D:/task_178111498130517/fetches\node/node.exe
[task 2026-06-10T19:02:40.589+00:00] 19:02:40     INFO - mozserve | Found moz-http2 server path: D:\task_178111498130517\build\tests\mochitest\xpcshell\moz-http2\moz-http2.js
[task 2026-06-10T19:02:40.590+00:00] 19:02:40     INFO - Found node at D:/task_178111498130517/fetches\node/node.exe
[task 2026-06-10T19:02:40.718+00:00] 19:02:40     INFO - mozserve | moz-http2 server msg: HTTP2 server listening on ports 51487,51488
[task 2026-06-10T19:02:40.718+00:00] 19:02:40     INFO - 
[task 2026-06-10T19:02:40.720+00:00] 19:02:40     INFO - moz-http2 server started on ports 51487, 51488
[task 2026-06-10T19:02:40.721+00:00] 19:02:40     INFO - node moz-http2 [stderr] (node:5932) [DEP0060] DeprecationWarning: The `util._extend` API is deprecated. Please use Object.assign() instead.
[task 2026-06-10T19:02:40.721+00:00] 19:02:40     INFO - 
[task 2026-06-10T19:02:40.721+00:00] 19:02:40     INFO - node moz-http2 [stderr] (Use `node --trace-deprecation ...` to show where the warning was created)
[task 2026-06-10T19:02:40.721+00:00] 19:02:40     INFO - 
[task 2026-06-10T19:02:40.723+00:00] 19:02:40     INFO - runtests.py | Running with scheme: http
[task 2026-06-10T19:02:40.724+00:00] 19:02:40     INFO - runtests.py | Running with e10s: True
[task 2026-06-10T19:02:40.724+00:00] 19:02:40     INFO - runtests.py | Running with fission: True
[task 2026-06-10T19:02:40.724+00:00] 19:02:40     INFO - runtests.py | Running with cross-origin iframes: False
[task 2026-06-10T19:02:40.725+00:00] 19:02:40     INFO - runtests.py | Running with socketprocess_e10s: False
[task 2026-06-10T19:02:40.725+00:00] 19:02:40     INFO - runtests.py | Running http tests: start.
[task 2026-06-10T19:02:40.725+00:00] 19:02:40     INFO - 
[task 2026-06-10T19:02:40.726+00:00] 19:02:40     INFO - Application command: D:\task_178111498130517\build\application\firefox\firefox.exe -marionette -remote-allow-system-access --wait-for-browser -foreground -profile C:\Users\task_178111498130517\AppData\Local\Temp\tmp1ayd7z43.mozrunner
[task 2026-06-10T19:02:40.728+00:00] 19:02:40     INFO - runtests.py | Application pid: 5776
[task 2026-06-10T19:02:40.728+00:00] 19:02:40     INFO - TEST-INFO | started process GECKO(5776)
[task 2026-06-10T19:02:41.121+00:00] 19:02:41     INFO - GECKO(5776) | [GFX1-]: DeviceManagerDx::VideoProcessorHDREnabled: Failed to create D3D11 device for checking video processor HDR support: 0x887a0004
[task 2026-06-10T19:02:41.159+00:00] 19:02:41     INFO - GECKO(5776) | ### XPCOM_MEM_BLOAT_LOG defined -- unable to log bloat/leaks to C:\Users\task_178111498130517\AppData\Local\Temp\tmp1ayd7z43.mozrunner\runtests_leaks_gpu_pid4152.log
[task 2026-06-10T19:02:41.170+00:00] 19:02:41     INFO - GECKO(5776) | 1781118161169	Marionette	INFO	Marionette enabled
[task 2026-06-10T19:02:41.172+00:00] 19:02:41     INFO - GECKO(5776) | 1781118161171	Marionette	TRACE	Received observer notification final-ui-startup
[task 2026-06-10T19:02:41.248+00:00] 19:02:41     INFO - GECKO(5776) | console.error: "Warning: unrecognized command line flag" "-foreground"
[task 2026-06-10T19:02:41.266+00:00] 19:02:41     INFO - GECKO(5776) | 1781118161265	Marionette	INFO	Listening on port 2828
[task 2026-06-10T19:02:41.268+00:00] 19:02:41     INFO - GECKO(5776) | 1781118161268	Marionette	DEBUG	Marionette is listening
[task 2026-06-10T19:02:41.283+00:00] 19:02:41     INFO - GECKO(5776) | 1781118161284	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:51489
[task 2026-06-10T19:02:41.296+00:00] 19:02:41     INFO - GECKO(5776) | [GFX1-]: D3D11Checks: Failed to ge1781118161295	Marionette	DEBUG	Closed connection 0
[task 2026-06-10T19:02:41.297+00:00] 19:02:41     INFO - GECKO(5776) | t D3D11VideoDevice: 0x80004002
[task 2026-06-10T19:02:41.377+00:00] 19:02:41     INFO - GECKO(5776) | 1781118161378	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:51492
[task 2026-06-10T19:02:41.419+00:00] 19:02:41     INFO - GECKO(5776) | ### XPCOM_MEM_BLOAT_LOG defined -- unable to log bloat/leaks to C:\Users\task_178111498130517\AppData\Local\Temp\tmp1ayd7z43.mozrunner\runtests_leaks_tab_pid6564.log
[task 2026-06-10T19:02:41.441+00:00] 19:02:41     INFO - GECKO(5776) | ### XPCOM_MEM_BLOAT_LOG defined -- unable to log bloat/leaks to C:\Users\task_178111498130517\AppData\Local\Temp\tmp1ayd7z43.mozrunner\runtests_leaks_rdd_pid8804.log
[task 2026-06-10T19:02:41.455+00:00] 19:02:41     INFO - GECKO(5776) | 1781118161454	Marionette	DEBUG	Closed connection 1
[task 2026-06-10T19:02:41.457+00:00] 19:02:41     INFO - GECKO(5776) | 1781118161456	Marionette	DEBUG	Accepted connection 2 from 127.0.0.1:51493
[task 2026-06-10T19:02:41.568+00:00] 19:02:41     INFO - GECKO(5776) | 1781118161569	Marionette	DEBUG	2 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2026-06-10T19:02:41.578+00:00] 19:02:41     INFO - GECKO(5776) | 1781118161577	Marionette	DEBUG	Waiting for initial application window
[task 2026-06-10T19:02:41.719+00:00] 19:02:41     INFO - GECKO(5776) | ### XPCOM_MEM_BLOAT_LOG defined -- unable to log bloat/leaks to C:\Users\task_178111498130517\AppData\Local\Temp\tmp1ayd7z43.mozrunner\runtests_leaks_tab_pid5036.log
[task 2026-06-10T19:02:41.917+00:00] 19:02:41     INFO - GECKO(5776) | [Parent 6756: Main Thread]: I/SlowChromeEvent Please do not use mouseenter/leave events in chrome. They are slower than mouseover/out! chrome://browser/content/tabbrowser/tabs.js:53:12
[task 2026-06-10T19:02:42.107+00:00] 19:02:42     INFO - GECKO(5776) | ### XPCOM_MEM_BLOAT_LOG defined -- unable to log bloat/leaks to C:\Users\task_178111498130517\AppData\Local\Temp\tmp1ayd7z43.mozrunner\runtests_leaks_tab_pid1152.log
[task 2026-06-10T19:02:43.485+00:00] 19:02:43     INFO - GECKO(5776) | console.error: services.settings:
[task 2026-06-10T19:02:43.500+00:00] 19:02:43     INFO - GECKO(5776) |   Message: EmptyDatabaseError: "main/nimbus-desktop-experiments" has not been synced yet
[task 2026-06-10T19:02:43.501+00:00] 19:02:43     INFO - GECKO(5776) |   Stack:
[task 2026-06-10T19:02:43.502+00:00] 19:02:43     INFO - GECKO(5776) |     EmptyDatabaseError@resource://services-settings/Database.sys.mjs:19:5
[task 2026-06-10T19:02:43.502+00:00] 19:02:43     INFO - GECKO(5776) | list@resource://services-settings/Database.sys.mjs:96:13
[task 2026-06-10T19:02:43.503+00:00] 19:02:43     INFO - GECKO(5776) | async*get@resource://services-settings/RemoteSettingsClient.sys.mjs:570:28
[task 2026-06-10T19:02:43.504+00:00] 19:02:43     INFO - GECKO(5776) | async*getRecipesFromCollection@resource://nimbus/lib/RemoteSettingsExperimentLoader.sys.mjs:669:30
[task 2026-06-10T19:02:43.504+00:00] 19:02:43     INFO - GECKO(5776) | getRecipesFromAllCollections@resource://nimbus/lib/RemoteSettingsExperimentLoader.sys.mjs:563:39
[task 2026-06-10T19:02:43.505+00:00] 19:02:43     INFO - GECKO(5776) | #updateImpl@resource://nimbus/lib/RemoteSettingsExperimentLoader.sys.mjs:450:33
[task 2026-06-10T19:02:43.505+00:00] 19:02:43     INFO - GECKO(5776) | async*updateRecipes/<@resource://nimbus/lib/RemoteSettingsExperimentLoader.sys.mjs:392:53
[task 2026-06-10T19:02:43.506+00:00] 19:02:43     INFO - GECKO(5776) | LockGrantedCallback*withUpdateLock@resource://nimbus/lib/RemoteSettingsExperimentLoader.sys.mjs:349:24
[task 2026-06-10T19:02:43.506+00:00] 19:02:43     INFO - GECKO(5776) | updateRecipes@resource://nimbus/lib/RemoteSettingsExperimentLoader.sys.mjs:392:16
[task 2026-06-10T19:02:43.507+00:00] 19:02:43     INFO - GECKO(5776) | enable@resource://nimbus/lib/RemoteSettingsExperimentLoader.sys.mjs:318:16
[task 2026-06-10T19:02:43.507+00:00] 19:02:43     INFO - GECKO(5776) | #init@resource://nimbus/ExperimentAPI.sys.mjs:330:28
[task 2026-06-10T19:02:43.508+00:00] 19:02:43     INFO - GECKO(5776) | async*init@resource://nimbus/ExperimentAPI.sys.mjs:269:49
[task 2026-06-10T19:02:43.508+00:00] 19:02:43     INFO - GECKO(5776) | finishInit@resource://normandy/Normandy.sys.mjs:109:30
[task 2026-06-10T19:02:43.509+00:00] 19:02:43     INFO - GECKO(5776) | async*init@resource://normandy/Normandy.sys.mjs:80:16
[task 2026-06-10T19:02:43.510+00:00] 19:02:43     INFO - GECKO(5776) | async*fn@resource://gre/modules/BrowserUtils.sys.mjs:83:33
[task 2026-06-10T19:02:43.510+00:00] 19:02:43     INFO - GECKO(5776) | callSingleListener@resource://gre/modules/BrowserUtils.sys.mjs:728:15
[task 2026-06-10T19:02:43.511+00:00] 19:02:43     INFO - GECKO(5776) | callModulesFromCategory@resource://gre/modules/BrowserUtils.sys.mjs:769:23
[task 2026-06-10T19:02:43.511+00:00] 19:02:43     INFO - GECKO(5776) | BG__beforeUIStartup@resource:///modules/BrowserGlue.sys.mjs:415:23
[task 2026-06-10T19:02:43.512+00:00] 19:02:43     INFO - GECKO(5776) | BG_observe@resource:///modules/BrowserGlue.sys.mjs:195:14
[task 2026-06-10T19:02:43.701+00:00] 19:02:43     INFO - GECKO(5776) | ### XPCOM_MEM_BLOAT_LOG defined -- unable to log bloat/leaks to C:\Users\task_178111498130517\AppData\Local\Temp\tmp1ayd7z43.mozrunner\runtests_leaks_utility_pid1336.log
[task 2026-06-10T19:02:43.714+00:00] 19:02:43     INFO - GECKO(5776) | ### XPCOM_MEM_BLOAT_LOG defined -- unable to log bloat/leaks to C:\Users\task_178111498130517\AppData\Local\Temp\tmp1ayd7z43.mozrunner\runtests_leaks_tab_pid1520.log
[task 2026-06-10T19:02:43.722+00:00] 19:02:43     INFO - GECKO(5776) | ### XPCOM_MEM_BLOAT_LOG defined -- unable to log bloat/leaks to C:\Users\task_178111498130517\AppData\Local\Temp\tmp1ayd7z43.mozrunner\runtests_leaks_tab_pid4192.log
[task 2026-06-10T19:02:43.748+00:00] 19:02:43     INFO - GECKO(5776) | ### XPCOM_MEM_BLOAT_LOG defined -- unable to log bloat/leaks to C:\Users\task_178111498130517\AppData\Local\Temp\tmp1ayd7z43.mozrunner\runtests_leaks_tab_pid2988.log
[task 2026-06-10T19:02:43.788+00:00] 19:02:43     INFO - GECKO(5776) | ### XPCOM_MEM_BLOAT_LOG defined -- unable to log bloat/leaks to C:\Users\task_178111498130517\AppData\Local\Temp\tmp1ayd7z43.mozrunner\runtests_leaks_tab_pid8776.log
[task 2026-06-10T19:02:43.819+00:00] 19:02:43     INFO - GECKO(5776) | 1781118163818	Marionette	TRACE	Received observer notification browser-idle-startup-tasks-finished
[task 2026-06-10T19:02:44.163+00:00] 19:02:44     INFO - GECKO(5776) | 1781118164163	RemoteAgent	TRACE	[12] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=5000 waitForExplicitStart=false
[task 2026-06-10T19:02:44.165+00:00] 19:02:44     INFO - GECKO(5776) | 1781118164163	RemoteAgent	TRACE	[12] ProgressListener Setting unload timer (5000ms)
[task 2026-06-10T19:02:44.166+00:00] 19:02:44     INFO - GECKO(5776) | 1781118164164	RemoteAgent	TRACE	[12] Wait for initial navigation: isUncommittedInitial=false, isLoadingDocument=false
[task 2026-06-10T19:02:44.166+00:00] 19:02:44     INFO - GECKO(5776) | 1781118164164	RemoteAgent	TRACE	[12] Document already finished loading: about:blank
[task 2026-06-10T19:02:44.167+00:00] 19:02:44     INFO - GECKO(5776) | 1781118164164	RemoteAgent	TRACE	[12] ProgressListener Stop: has error=false url=about:blank
[task 2026-06-10T19:02:44.180+00:00] 19:02:44     INFO - GECKO(5776) | 1781118164178	Marionette	DEBUG	2 <- [1,1,null,{"sessionId":"4001c813-553f-45b6-9999-8f1e27674158","capabilities":{"acceptInsecureCerts":false,"browserName":"firefox","browserVersion":"153.0a1","platformName":"windows","setWindowRect":true,"unhandledPromptBehavior":"dismiss and notify","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:153.0) Gecko/20100101 Firefox/153.0","moz:buildID":"20260608094347","moz:headless":false,"moz:platformVersion":"10.0","moz:processID":6756,"moz:profile":"C:\\Users\\task_178111498130517\\AppData\\Local\\Temp\\tmp1ayd7z43.mozrunner","moz:shutdownTimeout":60000,"pageLoadStrategy":"normal","timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"strictFileInteractability":true,"moz:accessibilityChecks":false,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2026-06-10T19:02:44.205+00:00] 19:02:44     INFO - GECKO(5776) | 1781118164206	Marionette	DEBUG	2 -> [0,2,"Addon:Install",{"temporary":false,"path":"C:\\Users\\task_178111498130517\\AppData\\Local\\Temp\\tmp5f6nu4y1.zip"}]
[task 2026-06-10T19:02:44.273+00:00] 19:02:44     INFO - GECKO(5776) | 1781118164272	Marionette	DEBUG	2 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
[task 2026-06-10T19:02:44.312+00:00] 19:02:44     INFO - GECKO(5776) | 1781118164311	Marionette	DEBUG	2 -> [0,3,"Addon:Install",{"temporary":false,"path":"C:\\Users\\task_178111498130517\\AppData\\Local\\Temp\\tmpxfdac8jy.zip"}]
[task 2026-06-10T19:02:44.334+00:00] 19:02:44     INFO - GECKO(5776) | 1781118164334	Marionette	DEBUG	2 <- [1,3,null,{"value":"mochikit@mozilla.org"}]
[task 2026-06-10T19:02:44.337+00:00] 19:02:44     INFO - GECKO(5776) | 1781118164336	Marionette	DEBUG	2 -> [0,4,"Marionette:GetContext",{}]
[task 2026-06-10T19:02:44.338+00:00] 19:02:44     INFO - GECKO(5776) | 1781118164336	Marionette	DEBUG	2 <- [1,4,null,{"value":"content"}]
[task 2026-06-10T19:02:44.339+00:00] 19:02:44     INFO - GECKO(5776) | 1781118164338	Marionette	DEBUG	2 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2026-06-10T19:02:44.339+00:00] 19:02:44     INFO - GECKO(5776) | 1781118164338	Marionette	DEBUG	2 <- [1,5,null,{"value":null}]
[task 2026-06-10T19:02:44.341+00:00] 19:02:44     INFO - GECKO(5776) | 1781118164340	Marionette	DEBUG	2 -> [0,6,"WebDriver:ExecuteScript",{"script":"/* This Source Code Form is subject to the terms of the Mozilla Public\n * License, v. 2.0. If a copy of the MPL was not distr ... \n// the flavor and url to load.\nlet ev = new CustomEvent(\"mochitest-load\", { detail: [flavor, url] });\nwin.dispatchEvent(ev);","args":[{"flavor":"browser-chrome","testUrl":"about:blank"}],"newSandbox":true,"sandbox":"default","line":2273,"filename":"D:\\task_178111498130517\\build\\tests\\mochitest\\runtests.py"}]
[task 2026-06-10T19:02:44.346+00:00] 19:02:44     INFO - GECKO(5776) | 1781118164345	RemoteAgent	TRACE	WebDriverProcessData actor created for PID 6756
[task 2026-06-10T19:02:44.346+00:00] 19:02:44     INFO - GECKO(5776) | 1781118164346	Marionette	TRACE	[2] MarionetteCommands actor created for window id 4
[task 2026-06-10T19:02:44.371+00:00] 19:02:44     INFO - GECKO(5776) | 1781118164371	Marionette	DEBUG	2 <- [1,6,null,{"value":null}]
[task 2026-06-10T19:02:44.380+00:00] 19:02:44     INFO - GECKO(5776) | 1781118164379	Marionette	DEBUG	2 -> [0,7,"Marionette:SetContext",{"value":"content"}]
[task 2026-06-10T19:02:44.381+00:00] 19:02:44     INFO - GECKO(5776) | 1781118164379	Marionette	DEBUG	2 <- [1,7,null,{"value":null}]
[task 2026-06-10T19:02:44.408+00:00] 19:02:44     INFO - GECKO(5776) | 1781118164407	Marionette	DEBUG	2 -> [0,8,"WebDriver:DeleteSession",{}]
[task 2026-06-10T19:02:44.408+00:00] 19:02:44     INFO - GECKO(5776) | 1781118164407	Marionette	TRACE	[2] MarionetteCommands actor destroyed for window id 4
[task 2026-06-10T19:02:44.411+00:00] 19:02:44     INFO - GECKO(5776) | 1781118164410	Marionette	DEBUG	2 <- [1,8,null,{"value":null}]
[task 2026-06-10T19:02:44.411+00:00] 19:02:44     INFO - runtests.py | Waiting for browser...
[task 2026-06-10T19:02:44.413+00:00] 19:02:44     INFO - GECKO(5776) | 1781118164412	Marionette	DEBUG	Closed connection 2
[task 2026-06-10T19:02:44.503+00:00] 19:02:44     INFO - *** Start BrowserChrome Test Results ***
[task 2026-06-10T19:02:44.506+00:00] 19:02:44     INFO - checking for open sidebars
[task 2026-06-10T19:02:44.506+00:00] 19:02:44     INFO - checking window state
[task 2026-06-10T19:02:44.675+00:00] 19:02:44     INFO - TEST-START | browser/components/urlbar/tests/browser-switchTab/browser_closesUrlbarPopup.js
[task 2026-06-10T19:02:45.472+00:00] 19:02:45     INFO - GECKO(5776) | MEMORY STAT | vsize 2112593MB | vsizeMaxContiguous 66741786MB | residentFast 326MB | heapAllocated 112MB
[task 2026-06-10T19:02:45.476+00:00] 19:02:45     INFO - TEST-PASS | browser/components/urlbar/tests/browser-switchTab/browser_closesUrlbarPopup.js | took 801ms
[task 2026-06-10T19:02:45.496+00:00] 19:02:45     INFO - GECKO(5776) | Completed ShutdownLeaks collections in process 6756
[task 2026-06-10T19:02:45.500+00:00] 19:02:45     INFO - TEST-START | Shutdown
[task 2026-06-10T19:02:45.501+00:00] 19:02:45     INFO - Browser Chrome Test Summary
[task 2026-06-10T19:02:45.501+00:00] 19:02:45     INFO - Passed:  1
[task 2026-06-10T19:02:45.502+00:00] 19:02:45     INFO - Failed:  0
[task 2026-06-10T19:02:45.503+00:00] 19:02:45     INFO - Todo:    0
[task 2026-06-10T19:02:45.503+00:00] 19:02:45     INFO - Mode:    e10s
[task 2026-06-10T19:02:45.504+00:00] 19:02:45     INFO - *** End BrowserChrome Test Results ***
[task 2026-06-10T19:02:45.525+00:00] 19:02:45     INFO - GECKO(5776) | Exiting due to channel error.
[task 2026-06-10T19:02:45.535+00:00] 19:02:45     INFO - GECKO(5776) | Exiting due to channel error.
[task 2026-06-10T19:02:45.572+00:00] 19:02:45     INFO - TEST-INFO | Main app process: exit 0
[task 2026-06-10T19:02:45.587+00:00] 19:02:45     INFO - runtests.py | Application ran for: 0:00:04.849023
[task 2026-06-10T19:02:45.587+00:00] 19:02:45     INFO - zombiecheck | Reading PID log: C:\Users\task_178111498130517\AppData\Local\Temp\tmpwxa5f7l9pidlog
[task 2026-06-10T19:02:45.589+00:00] 19:02:45     INFO - ==> process 6756 launched child process 4152 ("D:\task_178111498130517\build\application\firefox\firefox.exe" -contentproc -parentBuildID 20260608094347 -prefsHandle 2460:27878 -prefMapHandle 2464:310041 -ipcHandle 2496 -initialChannelId {d01338e8-faf9-4975-a23d-5d8b2c8c5de8} -parentPid 6756 -crashReporter "\\.\pipe\gecko-crash-server-pipe.6756" -crashHelperPid 0 -crashHelper 2504 -appDir "D:\task_178111498130517\build\application\firefox\browser" - 1 gpu)
[task 2026-06-10T19:02:45.589+00:00] 19:02:45     INFO - ==> process 6756 launched child process 6564 ("D:\task_178111498130517\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 2860:27643 -prefMapHandle 2864:310041 -jsInitHandle 2868:164512 -parentBuildID 20260608094347 -ipcHandle 2876 -initialChannelId {1361914f-cb8f-4b58-953e-86149dd35985} -parentPid 6756 -crashReporter "\\.\pipe\gecko-crash-server-pipe.6756" -crashHelperPid 0 -crashHelper 2884 -win32kLockedDown -appDir "D:\task_178111498130517\build\application\firefox\browser" - 2 tab)
[task 2026-06-10T19:02:45.590+00:00] 19:02:45     INFO - ==> process 6756 launched child process 8804 ("D:\task_178111498130517\build\application\firefox\firefox.exe" -contentproc -parentBuildID 20260608094347 -prefsHandle 3076:28934 -prefMapHandle 3080:310041 -ipcHandle 3088 -initialChannelId {fb040729-8d5e-49e2-98b6-1c12fa1c2ed2} -parentPid 6756 -crashReporter "\\.\pipe\gecko-crash-server-pipe.6756" -crashHelperPid 0 -crashHelper 3100 -appDir "D:\task_178111498130517\build\application\firefox\browser" - 3 rdd)
[task 2026-06-10T19:02:45.591+00:00] 19:02:45     INFO - ==> process 6756 launched child process 5036 ("D:\task_178111498130517\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 3400:29183 -prefMapHandle 3408:310041 -jsInitHandle 2824:164512 -parentBuildID 20260608094347 -ipcHandle 2332 -initialChannelId {19cef96b-a932-4ca5-966c-46f2954ad521} -parentPid 6756 -crashReporter "\\.\pipe\gecko-crash-server-pipe.6756" -crashHelperPid 0 -crashHelper 2380 -win32kLockedDown -appDir "D:\task_178111498130517\build\application\firefox\browser" - 4 tab)
[task 2026-06-10T19:02:45.592+00:00] 19:02:45     INFO - ==> process 6756 launched child process 1152 ("D:\task_178111498130517\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 4044:28039 -prefMapHandle 4048:310041 -jsInitHandle 4052:164512 -parentBuildID 20260608094347 -ipcHandle 4060 -initialChannelId {504897d6-3c25-4078-8e1e-ffccf0b43a77} -parentPid 6756 -crashReporter "\\.\pipe\gecko-crash-server-pipe.6756" -crashHelperPid 0 -crashHelper 4072 -win32kLockedDown -appDir "D:\task_178111498130517\build\application\firefox\browser" - 5 tab)
[task 2026-06-10T19:02:45.593+00:00] 19:02:45     INFO - ==> process 6756 launched child process 1336 ("D:\task_178111498130517\build\application\firefox\firefox.exe" -contentproc -parentBuildID 20260608094347 -sandboxingKind 0 -prefsHandle 4492:45670 -prefMapHandle 4780:310041 -ipcHandle 4788 -initialChannelId {f4a1c08d-5472-4360-9c69-bf4781f3220d} -parentPid 6756 -crashReporter "\\.\pipe\gecko-crash-server-pipe.6756" -crashHelperPid 0 -crashHelper 4792 -win32kLockedDown -appDir "D:\task_178111498130517\build\application\firefox\browser" - 6 utility)
[task 2026-06-10T19:02:45.594+00:00] 19:02:45     INFO - ==> process 6756 launched child process 1520 ("D:\task_178111498130517\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 4784:43321 -prefMapHandle 4808:310041 -jsInitHandle 4788:164512 -parentBuildID 20260608094347 -ipcHandle 4492 -initialChannelId {e89a32f6-a1e9-4299-a168-99b0ee4eaaf1} -parentPid 6756 -crashReporter "\\.\pipe\gecko-crash-server-pipe.6756" -crashHelperPid 0 -crashHelper 4936 -win32kLockedDown -appDir "D:\task_178111498130517\build\application\firefox\browser" - 7 tab)
[task 2026-06-10T19:02:45.595+00:00] 19:02:45     INFO - ==> process 6756 launched child process 4192 ("D:\task_178111498130517\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 4972:43321 -prefMapHandle 4976:310041 -jsInitHandle 4980:164512 -parentBuildID 20260608094347 -ipcHandle 4988 -initialChannelId {8cba0fa7-083f-4f41-a3d8-728f78695121} -parentPid 6756 -crashReporter "\\.\pipe\gecko-crash-server-pipe.6756" -crashHelperPid 0 -crashHelper 4952 -win32kLockedDown -appDir "D:\task_178111498130517\build\application\firefox\browser" - 8 tab)
[task 2026-06-10T19:02:45.596+00:00] 19:02:45     INFO - ==> process 6756 launched child process 2988 ("D:\task_178111498130517\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 5352:43321 -prefMapHandle 5356:310041 -jsInitHandle 5360:164512 -parentBuildID 20260608094347 -ipcHandle 5364 -initialChannelId {dcb41c47-4eb1-4d93-857c-cdfa546a8161} -parentPid 6756 -crashReporter "\\.\pipe\gecko-crash-server-pipe.6756" -crashHelperPid 0 -crashHelper 5380 -win32kLockedDown -appDir "D:\task_178111498130517\build\application\firefox\browser" - 9 tab)
[task 2026-06-10T19:02:45.596+00:00] 19:02:45     INFO - ==> process 6756 launched child process 8776 ("D:\task_178111498130517\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 5292:45851 -prefMapHandle 5288:310041 -jsInitHandle 5284:164512 -parentBuildID 20260608094347 -ipcHandle 5220 -initialChannelId {36e7c09f-6b89-48d0-a868-f746674bedb8} -parentPid 6756 -crashReporter "\\.\pipe\gecko-crash-server-pipe.6756" -crashHelperPid 0 -crashHelper 4492 -win32kLockedDown -appDir "D:\task_178111498130517\build\application\firefox\browser" - 10 tab)
[task 2026-06-10T19:02:45.597+00:00] 19:02:45     INFO - zombiecheck | Checking for orphan process with PID: 1152
[task 2026-06-10T19:02:45.598+00:00] 19:02:45     INFO - zombiecheck | Checking for orphan process with PID: 4192
[task 2026-06-10T19:02:45.598+00:00] 19:02:45     INFO - zombiecheck | Checking for orphan process with PID: 1336
[task 2026-06-10T19:02:45.599+00:00] 19:02:45     INFO - zombiecheck | Checking for orphan process with PID: 8804
[task 2026-06-10T19:02:45.599+00:00] 19:02:45     INFO - zombiecheck | Checking for orphan process with PID: 6564
[task 2026-06-10T19:02:45.600+00:00] 19:02:45     INFO - zombiecheck | Checking for orphan process with PID: 8776
[task 2026-06-10T19:02:45.600+00:00] 19:02:45     INFO - zombiecheck | Checking for orphan process with PID: 5036
[task 2026-06-10T19:02:45.601+00:00] 19:02:45     INFO - zombiecheck | Checking for orphan process with PID: 2988
[task 2026-06-10T19:02:45.602+00:00] 19:02:45     INFO - zombiecheck | Checking for orphan process with PID: 1520
[task 2026-06-10T19:02:45.602+00:00] 19:02:45     INFO - zombiecheck | Checking for orphan process with PID: 4152
[task 2026-06-10T19:02:45.603+00:00] 19:02:45     INFO - runtests.py | Running http tests: end. status: 0
[task 2026-06-10T19:02:45.603+00:00] 19:02:45     INFO - Stopping web server
[task 2026-06-10T19:02:45.604+00:00] 19:02:45     INFO - Server shut down.
[task 2026-06-10T19:02:45.604+00:00] 19:02:45     INFO - Web server killed.
[task 2026-06-10T19:02:45.604+00:00] 19:02:45     INFO - Stopping web socket server
[task 2026-06-10T19:02:45.605+00:00] 19:02:45     INFO - Stopping ssltunnel
[task 2026-06-10T19:02:45.606+00:00] 19:02:45  WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2026-06-10T19:02:45.905+00:00] 19:02:45     INFO - PID 7580 | D:\task_178111498130517\build\tests\bin\pk12util.exe: PKCS12 IMPORT SUCCESSFUL
[task 2026-06-10T19:02:45.905+00:00] 19:02:45     INFO - 
[task 2026-06-10T19:02:45.951+00:00] 19:02:45     INFO - MochitestServer : launching ['D:\\task_178111498130517\\build\\tests\\bin\\xpcshell.exe', '-g', 'D:\\task_178111498130517\\build\\application\\firefox', '-e', "const _PROFILE_PATH = 'C:\\\\Users\\\\task_178111498130517\\\\AppData\\\\Local\\\\Temp\\\\tmpe3ggaluq.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false; const _HTTPD_PATH = 'D:\\\\task_178111498130517\\\\build\\\\tests\\\\bin\\\\components'; ", '-f', 'D:\\task_178111498130517\\build\\tests\\mochitest\\server.js']
[task 2026-06-10T19:02:45.963+00:00] 19:02:45     INFO - runtests.py | Server pid: 3552
[task 2026-06-10T19:02:45.964+00:00] 19:02:45     INFO - runtests.py | Websocket server pid: 1852
[task 2026-06-10T19:02:45.969+00:00] 19:02:45     INFO - runtests.py | SSL tunnel pid: 5796
[task 2026-06-10T19:02:46.069+00:00] 19:02:46     INFO - use http3 server: False
[task 2026-06-10T19:02:46.071+00:00] 19:02:46     INFO - Use MOZ_NODE_PATH at D:/task_178111498130517/fetches\node/node.exe
[task 2026-06-10T19:02:46.071+00:00] 19:02:46     INFO - mozserve | Found moz-http2 server path: D:\task_178111498130517\build\tests\mochitest\xpcshell\moz-http2\moz-http2.js
[task 2026-06-10T19:02:46.072+00:00] 19:02:46     INFO - Found node at D:/task_178111498130517/fetches\node/node.exe
[task 2026-06-10T19:02:46.202+00:00] 19:02:46     INFO - mozserve | moz-http2 server msg: HTTP2 server listening on ports 51502,51503
[task 2026-06-10T19:02:46.203+00:00] 19:02:46     INFO - 
[task 2026-06-10T19:02:46.204+00:00] 19:02:46     INFO - moz-http2 server started on ports 51502, 51503
[task 2026-06-10T19:02:46.204+00:00] 19:02:46     INFO - node moz-http2 [stderr] (node:6456) [DEP0060] DeprecationWarning: The `util._extend` API is deprecated. Please use Object.assign() instead.
[task 2026-06-10T19:02:46.204+00:00] 19:02:46     INFO - 
[task 2026-06-10T19:02:46.205+00:00] 19:02:46     INFO - node moz-http2 [stderr] (Use `node --trace-deprecation ...` to show where the warning was created)
[task 2026-06-10T19:02:46.205+00:00] 19:02:46     INFO - 
[task 2026-06-10T19:02:46.208+00:00] 19:02:46     INFO - runtests.py | Running with scheme: http
[task 2026-06-10T19:02:46.208+00:00] 19:02:46     INFO - runtests.py | Running with e10s: True
[task 2026-06-10T19:02:46.208+00:00] 19:02:46     INFO - runtests.py | Running with fission: True
[task 2026-06-10T19:02:46.209+00:00] 19:02:46     INFO - runtests.py | Running with cross-origin iframes: False
[task 2026-06-10T19:02:46.209+00:00] 19:02:46     INFO - runtests.py | Running with socketprocess_e10s: False
[task 2026-06-10T19:02:46.210+00:00] 19:02:46     INFO - runtests.py | Running http tests: start.
[task 2026-06-10T19:02:46.210+00:00] 19:02:46     INFO - 
[task 2026-06-10T19:02:46.210+00:00] 19:02:46     INFO - Application command: D:\task_178111498130517\build\application\firefox\firefox.exe -marionette -remote-allow-system-access --wait-for-browser -foreground -profile C:\Users\task_178111498130517\AppData\Local\Temp\tmpe3ggaluq.mozrunner
[task 2026-06-10T19:02:46.213+00:00] 19:02:46     INFO - runtests.py | Application pid: 4408
[task 2026-06-10T19:02:46.213+00:00] 19:02:46     INFO - TEST-INFO | started process GECKO(4408)
[task 2026-06-10T19:02:46.630+00:00] 19:02:46     INFO - GECKO(4408) | [GFX1-]: DeviceManagerDx::VideoProcessorHDREnabled: Failed to create D3D11 device for checking video processor HDR support: 0x887a0004
[task 2026-06-10T19:02:46.670+00:00] 19:02:46     INFO - GECKO(4408) | ### XPCOM_MEM_BLOAT_LOG defined -- unable to log bloat/leaks to C:\Users\task_178111498130517\AppData\Local\Temp\tmpe3ggaluq.mozrunner\runtests_leaks_gpu_pid5868.log
[task 2026-06-10T19:02:46.679+00:00] 19:02:46     INFO - GECKO(4408) | 1781118166678	Marionette	INFO	Marionette enabled
[task 2026-06-10T19:02:46.680+00:00] 19:02:46     INFO - GECKO(4408) | 1781118166680	Marionette	TRACE	Received observer notification final-ui-startup
[task 2026-06-10T19:02:46.753+00:00] 19:02:46     INFO - GECKO(4408) | console.error: "Warning: unrecognized command line flag" "-foreground"
[task 2026-06-10T19:02:46.772+00:00] 19:02:46     INFO - GECKO(4408) | 1781118166772	Marionette	INFO	Listening on port 2828
[task 2026-06-10T19:02:46.775+00:00] 19:02:46     INFO - GECKO(4408) | 1781118166774	Marionette	DEBUG	Marionette is listening
[task 2026-06-10T19:02:46.804+00:00] 19:02:46     INFO - GECKO(4408) | [GFX1-]: D3D11Checks: Failed to get D3D11VideoDevice: 0x80004002
[task 2026-06-10T19:02:46.884+00:00] 19:02:46     INFO - GECKO(4408) | 1781118166885	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:51507
[task 2026-06-10T19:02:46.914+00:00] 19:02:46     INFO - GECKO(4408) | ### XPCOM_MEM_BLOAT_LOG defined -- unable to log bloat/leaks to C:\Users\task_178111498130517\AppData\Local\Temp\tmpe3ggaluq.mozrunner\runtests_leaks_tab_pid2072.log
[task 2026-06-10T19:02:46.941+00:00] 19:02:46     INFO - GECKO(4408) | ### XPCOM_MEM_BLOAT_LOG defined -- unable to log bloat/leaks to C:\Users\task_178111498130517\AppData\Local\Temp\tmpe3ggaluq.mozrunner\runtests_leaks_rdd_pid3360.log
[task 2026-06-10T19:02:46.961+00:00] 19:02:46     INFO - GECKO(4408) | 1781118166963	Marionette	DEBUG	Closed connection 0
[task 2026-06-10T19:02:46.965+00:00] 19:02:46     INFO - GECKO(4408) | 1781118166965	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:51511
[task 2026-06-10T19:02:47.071+00:00] 19:02:47     INFO - GECKO(4408) | 1781118167071	Marionette	DEBUG	1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2026-06-10T19:02:47.079+00:00] 19:02:47     INFO - GECKO(4408) | 1781118167078	Marionette	DEBUG	Waiting for initial application window
[task 2026-06-10T19:02:47.213+00:00] 19:02:47     INFO - GECKO(4408) | ### XPCOM_MEM_BLOAT_LOG defined -- unable to log bloat/leaks to C:\Users\task_178111498130517\AppData\Local\Temp\tmpe3ggaluq.mozrunner\runtests_leaks_tab_pid8408.log
[task 2026-06-10T19:02:47.457+00:00] 19:02:47     INFO - GECKO(4408) | [Parent 5704: Main Thread]: I/SlowChromeEvent Please do not use mouseenter/leave events in chrome. They are slower than mouseover/out! chrome://browser/content/tabbrowser/tabs.js:53:12
[task 2026-06-10T19:02:47.668+00:00] 19:02:47     INFO - GECKO(4408) | ### XPCOM_MEM_BLOAT_LOG defined -- unable to log bloat/leaks to C:\Users\task_178111498130517\AppData\Local\Temp\tmpe3ggaluq.mozrunner\runtests_leaks_tab_pid2268.log
[task 2026-06-10T19:02:49.055+00:00] 19:02:49     INFO - GECKO(4408) | console.error: services.settings:
[task 2026-06-10T19:02:49.056+00:00] 19:02:49     INFO - GECKO(4408) |   Message: EmptyDatabaseError: "main/nimbus-desktop-experiments" has not been synced yet
[task 2026-06-10T19:02:49.056+00:00] 19:02:49     INFO - GECKO(4408) |   Stack:
[task 2026-06-10T19:02:49.057+00:00] 19:02:49     INFO - GECKO(4408) |     EmptyDatabaseError@resource://services-settings/Database.sys.mjs:19:5
[task 2026-06-10T19:02:49.058+00:00] 19:02:49     INFO - GECKO(4408) | list@resource://services-settings/Database.sys.mjs:96:13
[task 2026-06-10T19:02:49.058+00:00] 19:02:49     INFO - GECKO(4408) | async*get@resource://services-settings/RemoteSettingsClient.sys.mjs:570:28
[task 2026-06-10T19:02:49.059+00:00] 19:02:49     INFO - GECKO(4408) | async*getRecipesFromCollection@resource://nimbus/lib/RemoteSettingsExperimentLoader.sys.mjs:669:30
[task 2026-06-10T19:02:49.059+00:00] 19:02:49     INFO - GECKO(4408) | getRecipesFromAllCollections@resource://nimbus/lib/RemoteSettingsExperimentLoader.sys.mjs:563:39
[task 2026-06-10T19:02:49.060+00:00] 19:02:49     INFO - GECKO(4408) | #updateImpl@resource://nimbus/lib/RemoteSettingsExperimentLoader.sys.mjs:450:33
[task 2026-06-10T19:02:49.061+00:00] 19:02:49     INFO - GECKO(4408) | async*updateRecipes/<@resource://nimbus/lib/RemoteSettingsExperimentLoader.sys.mjs:392:53
[task 2026-06-10T19:02:49.061+00:00] 19:02:49     INFO - GECKO(4408) | LockGrantedCallback*withUpdateLock@resource://nimbus/lib/RemoteSettingsExperimentLoader.sys.mjs:349:24
[task 2026-06-10T19:02:49.062+00:00] 19:02:49     INFO - GECKO(4408) | updateRecipes@resource://nimbus/lib/RemoteSettingsExperimentLoader.sys.mjs:392:16
[task 2026-06-10T19:02:49.062+00:00] 19:02:49     INFO - GECKO(4408) | enable@resource://nimbus/lib/RemoteSettingsExperimentLoader.sys.mjs:318:16
[task 2026-06-10T19:02:49.063+00:00] 19:02:49     INFO - GECKO(4408) | #init@resource://nimbus/ExperimentAPI.sys.mjs:330:28
[task 2026-06-10T19:02:49.063+00:00] 19:02:49     INFO - GECKO(4408) | async*init@resource://nimbus/ExperimentAPI.sys.mjs:269:49
[task 2026-06-10T19:02:49.064+00:00] 19:02:49     INFO - GECKO(4408) | finishInit@resource://normandy/Normandy.sys.mjs:109:30
[task 2026-06-10T19:02:49.065+00:00] 19:02:49     INFO - GECKO(4408) | async*init@resource://normandy/Normandy.sys.mjs:80:16
[task 2026-06-10T19:02:49.065+00:00] 19:02:49     INFO - GECKO(4408) | async*fn@resource://gre/modules/BrowserUtils.sys.mjs:83:33
[task 2026-06-10T19:02:49.066+00:00] 19:02:49     INFO - GECKO(4408) | callSingleListener@resource://gre/modules/BrowserUtils.sys.mjs:728:15
[task 2026-06-10T19:02:49.066+00:00] 19:02:49     INFO - GECKO(4408) | callModulesFromCategory@resource://gre/modules/BrowserUtils.sys.mjs:769:23
[task 2026-06-10T19:02:49.067+00:00] 19:02:49     INFO - GECKO(4408) | BG__beforeUIStartup@resource:///modules/BrowserGlue.sys.mjs:415:23
[task 2026-06-10T19:02:49.067+00:00] 19:02:49     INFO - GECKO(4408) | BG_observe@resource:///modules/BrowserGlue.sys.mjs:195:14
[task 2026-06-10T19:02:49.267+00:00] 19:02:49     INFO - GECKO(4408) | ### XPCOM_MEM_BLOAT_LOG defined -- unable to log bloat/leaks to C:\Users\task_178111498130517\AppData\Local\Temp\tmpe3ggaluq.mozrunner\runtests_leaks_utility_pid7040.log
[task 2026-06-10T19:02:49.273+00:00] 19:02:49     INFO - GECKO(4408) | ### XPCOM_MEM_BLOAT_LOG defined -- unable to log bloat/leaks to C:\Users\task_178111498130517\AppData\Local\Temp\tmpe3ggaluq.mozrunner\runtests_leaks_tab_pid4380.log
[task 2026-06-10T19:02:49.285+00:00] 19:02:49     INFO - GECKO(4408) | ### XPCOM_MEM_BLOAT_LOG defined -- unable to log bloat/leaks to C:\Users\task_178111498130517\AppData\Local\Temp\tmpe3ggaluq.mozrunner\runtests_leaks_tab_pid7500.log
[task 2026-06-10T19:02:49.319+00:00] 19:02:49     INFO - GECKO(4408) | ### XPCOM_MEM_BLOAT_LOG defined -- unable to log bloat/leaks to C:\Users\task_178111498130517\AppData\Local\Temp\tmpe3ggaluq.mozrunner\runtests_leaks_tab_pid9004.log
[task 2026-06-10T19:02:49.354+00:00] 19:02:49     INFO - GECKO(4408) | 1781118169353	Marionette	TRACE	Received observer notification browser-idle-startup-tasks-finished
[task 2026-06-10T19:02:49.361+00:00] 19:02:49     INFO - GECKO(4408) | ### XPCOM_MEM_BLOAT_LOG defined -- unable to log bloat/leaks to C:\Users\task_178111498130517\AppData\Local\Temp\tmpe3ggaluq.mozrunner\runtests_leaks_tab_pid8640.log
[task 2026-06-10T19:02:49.652+00:00] 19:02:49     INFO - GECKO(4408) | 1781118169663	RemoteAgent	TRACE	[12] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=5000 waitForExplicitStart=false
[task 2026-06-10T19:02:49.665+00:00] 19:02:49     INFO - GECKO(4408) | 1781118169663	RemoteAgent	TRACE	[12] ProgressListener Setting unload timer (5000ms)
[task 2026-06-10T19:02:49.666+00:00] 19:02:49     INFO - GECKO(4408) | 1781118169664	RemoteAgent	TRACE	[12] Wait for initial navigation: isUncommittedInitial=false, isLoadingDocument=false
[task 2026-06-10T19:02:49.667+00:00] 19:02:49     INFO - GECKO(4408) | 1781118169664	RemoteAgent	TRACE	[12] Document already finished loading: about:blank
[task 2026-06-10T19:02:49.667+00:00] 19:02:49     INFO - GECKO(4408) | 1781118169664	RemoteAgent	TRACE	[12] ProgressListener Stop: has error=false url=about:blank
[task 2026-06-10T19:02:49.678+00:00] 19:02:49     INFO - GECKO(4408) | 1781118169679	Marionette	DEBUG	1 <- [1,1,null,{"sessionId":"81e71317-429b-459a-a02d-2cb61e2545b9","capabilities":{"acceptInsecureCerts":false,"browserName":"firefox","browserVersion":"153.0a1","platformName":"windows","setWindowRect":true,"unhandledPromptBehavior":"dismiss and notify","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:153.0) Gecko/20100101 Firefox/153.0","moz:buildID":"20260608094347","moz:headless":false,"moz:platformVersion":"10.0","moz:processID":5704,"moz:profile":"C:\\Users\\task_178111498130517\\AppData\\Local\\Temp\\tmpe3ggaluq.mozrunner","moz:shutdownTimeout":60000,"pageLoadStrategy":"normal","timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"strictFileInteractability":true,"moz:accessibilityChecks":false,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2026-06-10T19:02:49.702+00:00] 19:02:49     INFO - GECKO(4408) | 1781118169701	Marionette	DEBUG	1 ->
Flags: needinfo?(nical.bugzilla)
Keywords: regression
Regressed by: 2023886

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

status-firefox151: --- → unaffected
status-firefox152: --- → unaffected
status-firefox153: --- → affected
status-firefox-esr140: --- → unaffected

The popup's popupshown event fires before SearchOneOffs._rebuild() finishes appending the .searchbar-engine-one-off-add-engine buttons asynchronously, so the test could query the DOM before the buttons existed. Await the one-offs "rebuild" event alongside popupshown, matching the pattern already used in browser_searchbar_addEngine.js.

Assignee: nobody → florian
Status: NEW → ASSIGNED

I thought the failure I saw on try was caused by my patches, so I started investigating before I noticed it was an existing perma-fail.

You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: