Open Bug 1971091 Opened 10 months ago Updated 1 month ago

High-freq browser/components/urlbar/tests/quicksuggest/browser/browser_telemetry_suggestMetrics.js | single tracking bug

Categories

(Firefox :: Address Bar, defect, P5)

defect

Tracking

()

REOPENED
Tracking Status
firefox-esr128 --- unaffected
firefox-esr140 --- unaffected
firefox142 --- unaffected
firefox143 --- wontfix
firefox144 --- wontfix

People

(Reporter: intermittent-bug-filer, Assigned: adw)

References

(Blocks 1 open bug, Regression)

Details

(Keywords: intermittent-failure, intermittent-testcase, regression, Whiteboard: [stockwell disabled][sng])

Attachments

(1 file)

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


[task 2025-06-09T11:25:25.947+00:00] 11:25:25     INFO - TEST-START | browser/components/urlbar/tests/quicksuggest/browser/browser_telemetry_suggestMetrics.js
[task 2025-06-09T11:25:25.957+00:00] 11:25:25     INFO - GECKO(2552) | console.info: RemoteSettingsServer: "Starting"
[task 2025-06-09T11:25:25.957+00:00] 11:25:25     INFO - GECKO(2552) | console.info: RemoteSettingsServer: "Server is now started at http://localhost:51893/v1"
[task 2025-06-09T11:30:38.176+00:00] 11:30:38     INFO -  console.trace: Places - FrecencyRecalculator: "Initializing Frecency Recalculator"
[task 2025-06-09T11:30:38.177+00:00] 11:30:38     INFO -  resource://gre/modules/PlacesFrecencyRecalculator.sys.mjs 123 PlacesFrecencyRecalculator
[task 2025-06-09T11:30:38.177+00:00] 11:30:38     INFO -  D:\task_174946738732101\build\tests\mochitest\server.js 178 runServer
[task 2025-06-09T11:30:38.178+00:00] 11:30:38     INFO -  D:\task_174946738732101\build\tests\mochitest\server.js 71
[task 2025-06-09T11:30:38.182+00:00] 11:30:38     INFO -  console.trace: Places - FrecencyRecalculator: "Arm frecency recalculation"
[task 2025-06-09T11:30:38.182+00:00] 11:30:38     INFO -  resource://gre/modules/PlacesFrecencyRecalculator.sys.mjs 387 maybeStartFrecencyRecalculation
[task 2025-06-09T11:30:38.182+00:00] 11:30:38     INFO -  resource://gre/modules/PlacesFrecencyRecalculator.sys.mjs 164 PlacesFrecencyRecalculator
[task 2025-06-09T11:30:38.183+00:00] 11:30:38     INFO -  D:\task_174946738732101\build\tests\mochitest\server.js 178 runServer
[task 2025-06-09T11:30:38.183+00:00] 11:30:38     INFO -  D:\task_174946738732101\build\tests\mochitest\server.js 71
[task 2025-06-09T11:30:38.183+00:00] 11:30:38     INFO -  console.trace: Places - FrecencyRecalculator: "Got places-init-complete topic"
[task 2025-06-09T11:30:38.184+00:00] 11:30:38     INFO -  resource://gre/modules/PlacesFrecencyRecalculator.sys.mjs 497 observe
[task 2025-06-09T11:30:38.184+00:00] 11:30:38     INFO -  D:\task_174946738732101\build\tests\mochitest\server.js 178 runServer
[task 2025-06-09T11:30:38.185+00:00] 11:30:38     INFO -  D:\task_174946738732101\build\tests\mochitest\server.js 71
[task 2025-06-09T11:30:38.185+00:00] 11:30:38     INFO -  console.error: (new TypeError("connection not specified or invalid.", "resource://gre/modules/Sqlite.sys.mjs", 1590))
[task 2025-06-09T11:30:38.185+00:00] 11:30:38     INFO -  console.error: (new TypeError("can't access property \"executeBeforeShutdown\", db is undefined", "resource://gre/modules/PlacesUtils.sys.mjs", 1632))
[task 2025-06-09T11:31:35.942+00:00] 11:31:35     INFO - Buffered messages logged at 11:25:25
[task 2025-06-09T11:31:35.942+00:00] 11:31:35     INFO - Entering setup bound 
[task 2025-06-09T11:31:35.943+00:00] 11:31:35     INFO - QuickSuggestTestUtils.ensureQuickSuggestInit Started
[task 2025-06-09T11:31:35.944+00:00] 11:31:35     INFO - QuickSuggestTestUtils.ensureQuickSuggestInit Awaiting ExperimentAPI.init
[task 2025-06-09T11:31:35.944+00:00] 11:31:35     INFO - QuickSuggestTestUtils.ensureQuickSuggestInit Awaiting ExperimentAPI.ready
[task 2025-06-09T11:31:35.945+00:00] 11:31:35     INFO - QuickSuggestTestUtils.ensureQuickSuggestInit Preparing remote settings server
[task 2025-06-09T11:31:35.945+00:00] 11:31:35     INFO - QuickSuggestTestUtils.ensureQuickSuggestInit Starting remote settings server
[task 2025-06-09T11:31:35.946+00:00] 11:31:35     INFO - QuickSuggestTestUtils.ensureQuickSuggestInit Remote settings server started
[task 2025-06-09T11:31:35.947+00:00] 11:31:35     INFO - QuickSuggestTestUtils.ensureQuickSuggestInit Calling QuickSuggest.init() and setting prefs
[task 2025-06-09T11:31:35.947+00:00] 11:31:35     INFO - Buffered messages finished
[task 2025-06-09T11:31:35.947+00:00] 11:31:35     INFO - TEST-UNEXPECTED-TIMEOUT | browser/components/urlbar/tests/quicksuggest/browser/browser_telemetry_suggestMetrics.js | application timed out after 370 seconds with no output
[task 2025-06-09T11:31:35.947+00:00] 11:31:35     INFO - TEST-INFO took 369054ms
[task 2025-06-09T11:31:35.948+00:00] 11:31:35     INFO - Buffered messages finished
[task 2025-06-09T11:31:35.948+00:00] 11:31:35  WARNING - Force-terminating active process(es).
[task 2025-06-09T11:31:35.949+00:00] 11:31:35     INFO - profiler Not sending a signal to start the profiler - not on MacOS or Linux. See Bug 1823370.
[task 2025-06-09T11:31:35.949+00:00] 11:31:35     INFO - Determining child pids from psutil...
[task 2025-06-09T11:31:35.949+00:00] 11:31:35     INFO - [9228]
[task 2025-06-09T11:31:35.950+00:00] 11:31:35     INFO - ==> process 9228 launched child process 6632 ("D:\task_174946738732101\build\application\firefox\firefox.exe" -contentproc -parentBuildID 20250609101912 -prefsHandle 2380:28574 -prefMapHandle 2384:290867 -ipcHandle 2412 -initialChannelId {d983e62b-337c-4c2d-87bc-9b496f78ec50} -parentPid 9228 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9228" -appDir "D:\task_174946738732101\build\application\firefox\browser" - 1 gpu)
[task 2025-06-09T11:31:35.952+00:00] 11:31:35     INFO - ==> process 9228 launched child process 9132 ("D:\task_174946738732101\build\application\firefox\firefox.exe" -contentproc -parentBuildID 20250609101912 -prefsHandle 2636:28574 -prefMapHandle 2640:290867 -ipcHandle 2648 -initialChannelId {24f830a7-234a-4e93-99fa-c010e3a5414d} -parentPid 9228 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9228" -win32kLockedDown -appDir "D:\task_174946738732101\build\application\firefox\browser" - 2 socket)
[task 2025-06-09T11:31:35.953+00:00] 11:31:35     INFO - ==> process 9228 launched child process 3160 ("D:\task_174946738732101\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 2928:28222 -prefMapHandle 2932:290867 -jsInitHandle 2936:248576 -parentBuildID 20250609101912 -ipcHandle 2944 -initialChannelId {f20c8de1-38ca-4216-881e-09253e2102cf} -parentPid 9228 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9228" -win32kLockedDown -appDir "D:\task_174946738732101\build\application\firefox\browser" - 3 tab)
[task 2025-06-09T11:31:35.953+00:00] 11:31:35     INFO - ==> process 9228 launched child process 9812 ("D:\task_174946738732101\build\application\firefox\firefox.exe" -contentproc -parentBuildID 20250609101912 -prefsHandle 3192:29678 -prefMapHandle 3196:290867 -ipcHandle 3204 -initialChannelId {94197bd8-323c-432a-9c91-4acfdfb8989a} -parentPid 9228 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9228" -appDir "D:\task_174946738732101\build\application\firefox\browser" - 4 rdd)
[task 2025-06-09T11:31:35.954+00:00] 11:31:35     INFO - ==> process 9228 launched child process 7092 ("D:\task_174946738732101\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 3516:29814 -prefMapHandle 3520:290867 -jsInitHandle 3524:248576 -parentBuildID 20250609101912 -ipcHandle 3532 -initialChannelId {0fa69664-792e-4c4e-bd4b-3bc4331a39ec} -parentPid 9228 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9228" -win32kLockedDown -appDir "D:\task_174946738732101\build\application\firefox\browser" - 5 tab)
[task 2025-06-09T11:31:35.955+00:00] 11:31:35     INFO - ==> process 9228 launched child process 9004 ("D:\task_174946738732101\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 3648:28516 -prefMapHandle 3548:290867 -jsInitHandle 3560:248576 -parentBuildID 20250609101912 -ipcHandle 3680 -initialChannelId {79d21fb8-8cea-4874-bbb4-da66933e2c8a} -parentPid 9228 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9228" -win32kLockedDown -appDir "D:\task_174946738732101\build\application\firefox\browser" - 6 tab)
[task 2025-06-09T11:31:35.956+00:00] 11:31:35     INFO - ==> process 9228 launched child process 1748 ("D:\task_174946738732101\build\application\firefox\firefox.exe" -contentproc -parentBuildID 20250609101912 -sandboxingKind 0 -prefsHandle 4460:41321 -prefMapHandle 4456:290867 -ipcHandle 4468 -initialChannelId {894ad78d-785d-4a0c-a427-d88552b1717a} -parentPid 9228 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9228" -win32kLockedDown -appDir "D:\task_174946738732101\build\application\firefox\browser" - 7 utility)
[task 2025-06-09T11:31:35.957+00:00] 11:31:35     INFO - ==> process 9228 launched child process 8988 ("D:\task_174946738732101\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 4592:38968 -prefMapHandle 4600:290867 -jsInitHandle 4604:248576 -parentBuildID 20250609101912 -ipcHandle 4616 -initialChannelId {7132319d-9fe9-4ba9-9db1-a658a62be7c2} -parentPid 9228 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9228" -win32kLockedDown -appDir "D:\task_174946738732101\build\application\firefox\browser" - 8 tab)
[task 2025-06-09T11:31:35.958+00:00] 11:31:35     INFO - ==> process 9228 launched child process 4264 ("D:\task_174946738732101\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 4660:38968 -prefMapHandle 4664:290867 -jsInitHandle 4668:248576 -parentBuildID 20250609101912 -ipcHandle 4680 -initialChannelId {0ae16fe8-9561-4ab7-8ec7-2f9c04e8d364} -parentPid 9228 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9228" -win32kLockedDown -appDir "D:\task_174946738732101\build\application\firefox\browser" - 9 tab)
[task 2025-06-09T11:31:35.959+00:00] 11:31:35     INFO - ==> process 9228 launched child process 8616 ("D:\task_174946738732101\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 5024:38968 -prefMapHandle 5000:290867 -jsInitHandle 4996:248576 -parentBuildID 20250609101912 -ipcHandle 4988 -initialChannelId {fb08a7b5-e26c-4692-a22f-1ca969b0c3a3} -parentPid 9228 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9228" -win32kLockedDown -appDir "D:\task_174946738732101\build\application\firefox\browser" - 10 tab)
[task 2025-06-09T11:31:35.960+00:00] 11:31:35     INFO - ==> process 9228 launched child process 8580 ("D:\task_174946738732101\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 4484:41724 -prefMapHandle 4460:290867 -jsInitHandle 4456:248576 -parentBuildID 20250609101912 -ipcHandle 4984 -initialChannelId {6e7a29ce-92e4-4182-99b8-b3b630f95368} -parentPid 9228 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9228" -win32kLockedDown -appDir "D:\task_174946738732101\build\application\firefox\browser" - 11 tab)
[task 2025-06-09T11:31:35.961+00:00] 11:31:35     INFO - ==> process 9228 launched child process 6204 ("D:\task_174946738732101\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 5784:39433 -prefMapHandle 5380:290867 -jsInitHandle 5968:248576 -parentBuildID 20250609101912 -ipcHandle 5960 -initialChannelId {0ca540b5-29e3-4642-a76f-6f187573acd4} -parentPid 9228 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9228" -win32kLockedDown -appDir "D:\task_174946738732101\build\application\firefox\browser" - 12 tab)
[task 2025-06-09T11:31:35.962+00:00] 11:31:35     INFO - ==> process 9228 launched child process 8956 ("D:\task_174946738732101\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 6260:39609 -prefMapHandle 6232:290867 -jsInitHandle 6364:248576 -parentBuildID 20250609101912 -ipcHandle 6400 -initialChannelId {aa6a75df-69e4-442c-8fdb-5fb1f84a3723} -parentPid 9228 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9228" -win32kLockedDown -appDir "D:\task_174946738732101\build\application\firefox\browser" - 13 tab)
[task 2025-06-09T11:31:35.963+00:00] 11:31:35     INFO - ==> process 9228 launched child process 3292 ("D:\task_174946738732101\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 6340:39593 -prefMapHandle 4424:290867 -jsInitHandle 6032:248576 -parentBuildID 20250609101912 -ipcHandle 4564 -initialChannelId {7c4ccf66-281a-4d56-9064-3568798b4ce4} -parentPid 9228 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9228" -win32kLockedDown -appDir "D:\task_174946738732101\build\application\firefox\browser" - 14 tab)
[task 2025-06-09T11:31:35.964+00:00] 11:31:35     INFO - ==> process 9228 launched child process 8012 ("D:\task_174946738732101\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 5048:39593 -prefMapHandle 4980:290867 -jsInitHandle 4964:248576 -parentBuildID 20250609101912 -ipcHandle 6600 -initialChannelId {6b57c615-4118-4886-9755-8265e8219c45} -parentPid 9228 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9228" -win32kLockedDown -appDir "D:\task_174946738732101\build\application\firefox\browser" - 15 tab)
[task 2025-06-09T11:31:35.965+00:00] 11:31:35     INFO - ==> process 9228 launched child process 9324 ("D:\task_174946738732101\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 5052:39651 -prefMapHandle 6560:290867 -jsInitHandle 4948:248576 -parentBuildID 20250609101912 -ipcHandle 6540 -initialChannelId {2e897ba7-0a16-4a53-a9e9-ad67f7b9dfa1} -parentPid 9228 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9228" -win32kLockedDown -appDir "D:\task_174946738732101\build\application\firefox\browser" - 16 tab)
[task 2025-06-09T11:31:35.967+00:00] 11:31:35     INFO - ==> process 9228 launched child process 1584 ("D:\task_174946738732101\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 6832:39619 -prefMapHandle 6028:290867 -jsInitHandle 6016:248576 -parentBuildID 20250609101912 -ipcHandle 5692 -initialChannelId {6a8ad06c-6238-4a82-a9cf-ccfbcd060fa5} -parentPid 9228 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9228" -win32kLockedDown -appDir "D:\task_174946738732101\build\application\firefox\browser" - 17 tab)
[task 2025-06-09T11:31:35.968+00:00] 11:31:35     INFO - ==> process 9228 launched child process 5408 ("D:\task_174946738732101\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 6740:40076 -prefMapHandle 5092:290867 -jsInitHandle 5796:248576 -parentBuildID 20250609101912 -ipcHandle 4800 -initialChannelId {46899110-b4b5-4f97-9d69-a477dcc507fe} -parentPid 9228 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9228" -win32kLockedDown -appDir "D:\task_174946738732101\build\application\firefox\browser" - 18 tab)
[task 2025-06-09T11:31:35.969+00:00] 11:31:35     INFO - ==> process 9228 launched child process 4860 ("D:\task_174946738732101\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 1664:40072 -prefMapHandle 4484:290867 -jsInitHandle 4460:248576 -parentBuildID 20250609101912 -ipcHandle 4800 -initialChannelId {c9f043c4-3008-4aa7-bf6f-b7d4b95ade49} -parentPid 9228 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9228" -win32kLockedDown -appDir "D:\task_174946738732101\build\application\firefox\browser" - 19 tab)
[task 2025-06-09T11:31:35.970+00:00] 11:31:35     INFO - ==> process 9228 launched child process 4852 ("D:\task_174946738732101\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 6760:40072 -prefMapHandle 3708:290867 -jsInitHandle 6720:248576 -parentBuildID 20250609101912 -ipcHandle 6032 -initialChannelId {9f123b7f-3bee-4a26-a1b4-52b114498285} -parentPid 9228 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9228" -win32kLockedDown -appDir "D:\task_174946738732101\build\application\firefox\browser" - 20 tab)
[task 2025-06-09T11:31:35.970+00:00] 11:31:35     INFO - ==> process 9228 launched child process 6368 ("D:\task_174946738732101\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 6580:40072 -prefMapHandle 5116:290867 -jsInitHandle 4396:248576 -parentBuildID 20250609101912 -ipcHandle 6564 -initialChannelId {d5ed0448-d753-432c-ba91-81a2a2581e29} -parentPid 9228 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9228" -win32kLockedDown -appDir "D:\task_174946738732101\build\application\firefox\browser" - 21 tab)
[task 2025-06-09T11:31:35.972+00:00] 11:31:35     INFO - ==> process 9228 launched child process 8452 ("D:\task_174946738732101\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 4460:40028 -prefMapHandle 6048:290867 -jsInitHandle 5116:248576 -parentBuildID 20250609101912 -ipcHandle 2780 -initialChannelId {086f0e32-1645-4feb-a3d5-403c43fb8836} -parentPid 9228 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9228" -win32kLockedDown -appDir "D:\task_174946738732101\build\application\firefox\browser" - 22 tab)
[task 2025-06-09T11:31:35.973+00:00] 11:31:35     INFO - ==> process 9228 launched child process 1828 ("D:\task_174946738732101\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 6536:40072 -prefMapHandle 6448:290867 -jsInitHandle 4484:248576 -parentBuildID 20250609101912 -ipcHandle 6276 -initialChannelId {0178ac90-e3c1-4caf-889e-d6af2a70acda} -parentPid 9228 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9228" -win32kLockedDown -appDir "D:\task_174946738732101\build\application\firefox\browser" - 23 tab)
[task 2025-06-09T11:31:35.974+00:00] 11:31:35     INFO - Found child pids: {8580, 4860, 8452, 9228, 8988, 5408, 1828, 4264, 8616, 9132, 9004, 1584, 7092, 6204, 8012, 9812, 1748, 3160, 3292, 6368, 6632, 9324, 4852, 8956}
[task 2025-06-09T11:31:35.974+00:00] 11:31:35     INFO - Failed to get child procs

This passed when run in confirm failure mode (rerun the test 4x in the same session).

This indicates that the test is probably a lower frequency intermittent and harder to reproduce/debug.

Whiteboard: [collect_confirm_failure]

Based on these retriggers and backfills it seems that your patch from https://bugzilla.mozilla.org/show_bug.cgi?id=1983376 caused this frequent failure. Can you please take a look?

Flags: needinfo?(adw)
Keywords: regression
Regressed by: 1983376

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

Thanks and sorry, I probably won't be able to get to this until a week or two from now.

Assignee: nobody → adw
Status: NEW → ASSIGNED
Flags: needinfo?(adw)
Summary: Intermittent browser/components/urlbar/tests/quicksuggest/browser/browser_telemetry_suggestMetrics.js | single tracking bug → High-freq browser/components/urlbar/tests/quicksuggest/browser/browser_telemetry_suggestMetrics.js | single tracking bug
Keywords: leave-open
Pushed by agoloman@mozilla.com: https://github.com/mozilla-firefox/firefox/commit/1edd0a32be7c https://hg.mozilla.org/integration/autoland/rev/8af7825724cc Disable browser_telemetry_suggestMetrics on linux/mac/win x64 for frequent failures r=intermittent-reviewers,jmaher DONTBUILD
Whiteboard: [stockwell disabled]
See Also: → 1885264
Whiteboard: [stockwell disabled] → [stockwell disabled][sng]
Status: ASSIGNED → RESOLVED
Closed: 5 months ago
Keywords: leave-open
Resolution: --- → INCOMPLETE
Blocks: 2017835

Still valid as the test must be re-enabled

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: