Open Bug 1855499 Opened 3 years ago Updated 3 years ago

Intermittent TV TEST-UNEXPECTED-TIMEOUT | browser/components/customizableui/test/browser_1042100_default_placements_update.js (finished) | single tracking bug

Categories

(Firefox :: Toolbars and Customization, defect, P5)

defect

Tracking

()

Tracking Status
firefox-esr115 --- unaffected
firefox118 --- unaffected
firefox119 --- unaffected
firefox120 --- affected

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: intermittent-failure, regression, test-verify-fail)

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


[task 2023-09-27T16:36:34.620Z] 16:36:34     INFO - TEST-OK | browser/components/customizableui/test/browser_1042100_default_placements_update.js | took 606ms
[task 2023-09-27T16:36:34.628Z] 16:36:34     INFO - GECKO(4480) | [Child 7364: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 2189e365800 == 6 [pid = 7364] [id = 5]
[task 2023-09-27T16:36:34.628Z] 16:36:34     INFO - GECKO(4480) | [Child 7364: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (2189c8f3b60) [pid = 7364] [serial = 11] [outer = 0]
[task 2023-09-27T16:36:34.629Z] 16:36:34     INFO - GECKO(4480) | [Child 7364: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 12 (2189e366000) [pid = 7364] [serial = 12] [outer = 2189c8f3b60]
[task 2023-09-27T16:36:34.651Z] 16:36:34     INFO - checking window state
[task 2023-09-27T16:36:34.682Z] 16:36:34     INFO - GECKO(4480) | [Parent 4704: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 15 (18dc4b8cc00) [pid = 4704] [serial = 15] [outer = 18dca3f2020]
[task 2023-09-27T16:36:34.760Z] 16:36:34     INFO - GECKO(4480) | [Child 7364: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 2189e364400 == 5 [pid = 7364] [id = 4] [url = about:blank]
[task 2023-09-27T16:36:34.766Z] 16:36:34     INFO - GECKO(4480) | [Child 7364: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 2189c83d000 == 4 [pid = 7364] [id = 1] [url = about:blank]
[task 2023-09-27T16:36:34.767Z] 16:36:34     INFO - GECKO(4480) | [Child 7364: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 2189c832400 == 3 [pid = 7364] [id = 0] [url = about:blank]
[task 2023-09-27T16:36:34.767Z] 16:36:34     INFO - GECKO(4480) | [Child 7364: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 2189e363000 == 2 [pid = 7364] [id = 3] [url = about:blank]
[task 2023-09-27T16:36:34.768Z] 16:36:34     INFO - GECKO(4480) | [Child 7364: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 2189c83e800 == 1 [pid = 7364] [id = 2] [url = about:blank]
[task 2023-09-27T16:36:34.791Z] 16:36:34     INFO - GECKO(4480) | [Child 10060: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (1569b5ce400) [pid = 10060] [serial = 10] [outer = 0] [url = about:blank]
[task 2023-09-27T16:36:34.797Z] 16:36:34     INFO - GECKO(4480) | [Child 10060: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (1569b5cb000) [pid = 10060] [serial = 8] [outer = 0] [url = about:blank]
[task 2023-09-27T16:36:34.798Z] 16:36:34     INFO - GECKO(4480) | [Child 10060: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (1569b5ca000) [pid = 10060] [serial = 6] [outer = 0] [url = about:blank]
[task 2023-09-27T16:36:34.799Z] 16:36:34     INFO - GECKO(4480) | [Child 10060: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (1569994f000) [pid = 10060] [serial = 4] [outer = 0] [url = about:blank]
[task 2023-09-27T16:36:34.799Z] 16:36:34     INFO - GECKO(4480) | [Child 10060: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (15699947400) [pid = 10060] [serial = 2] [outer = 0] [url = about:blank]
[task 2023-09-27T16:36:34.800Z] 16:36:34     INFO - GECKO(4480) | [Child 10060: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (15699948800) [pid = 10060] [serial = 3] [outer = 0] [url = about:blank]
[task 2023-09-27T16:36:34.803Z] 16:36:34     INFO - GECKO(4480) | [Child 10060: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 1569994f400 == 1 [pid = 10060] [id = 5] [url = about:blank]
[task 2023-09-27T16:36:34.803Z] 16:36:34     INFO - GECKO(4480) | [Child 10060: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 15699946800 == 0 [pid = 10060] [id = 4] [url = about:blank]
[task 2023-09-27T16:36:34.823Z] 16:36:34     INFO - GECKO(4480) | [Child 3060: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 16 (2d08e459000) [pid = 3060] [serial = 8] [outer = 0] [url = about:blank]
[task 2023-09-27T16:36:34.829Z] 16:36:34     INFO - GECKO(4480) | [Child 3060: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 15 (2d08e456800) [pid = 3060] [serial = 4] [outer = 0] [url = about:blank]
[task 2023-09-27T16:36:34.830Z] 16:36:34     INFO - GECKO(4480) | [Child 3060: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 14 (2d08e45a400) [pid = 3060] [serial = 10] [outer = 0] [url = about:blank]
[task 2023-09-27T16:36:34.830Z] 16:36:34     INFO - GECKO(4480) | [Child 3060: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 13 (2d08e455400) [pid = 3060] [serial = 2] [outer = 0] [url = about:blank]
[task 2023-09-27T16:36:34.831Z] 16:36:34     INFO - GECKO(4480) | [Child 3060: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 12 (2d08e458000) [pid = 3060] [serial = 6] [outer = 0] [url = about:blank]
[task 2023-09-27T16:36:34.832Z] 16:36:34     INFO - GECKO(4480) | [Child 3060: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (2d091305800) [pid = 3060] [serial = 17] [outer = 0] [url = about:blank]
[task 2023-09-27T16:36:34.832Z] 16:36:34     INFO - GECKO(4480) | [Child 3060: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (2d09008ec00) [pid = 3060] [serial = 13] [outer = 0] [url = moz-extension://793fe0ac-5d2d-43b6-8a0f-005298922a66/_generated_background_page.html]
[task 2023-09-27T16:36:34.854Z] 16:36:34     INFO - GECKO(4480) | [Child 4624: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 1dd71f35c00 == 0 [pid = 4624] [id = 0] [url = about:newtab]
[task 2023-09-27T16:36:34.868Z] 16:36:34     INFO - GECKO(4480) | [Child 4624, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/checkouts/gecko/dom/events/DOMEventTargetHelper.cpp:302
[task 2023-09-27T16:36:34.885Z] 16:36:34     INFO - GECKO(4480) | [Child 4624: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (1dd71f3e800) [pid = 4624] [serial = 2] [outer = 0] [url = about:blank]
[task 2023-09-27T16:36:34.979Z] 16:36:34     INFO - GECKO(4480) | [Child 4624: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (1dd6aa4d020) [pid = 4624] [serial = 1] [outer = 0] [url = about:newtab]
[task 2023-09-27T16:36:35.182Z] 16:36:35     INFO - GECKO(4480) | [Parent 4704: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 14 (18dc8736800) [pid = 4704] [serial = 6] [outer = 0] [url = about:blank]
[task 2023-09-27T16:36:35.186Z] 16:36:35     INFO - GECKO(4480) | [Parent 4704: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 13 (18dcd0e2000) [pid = 4704] [serial = 11] [outer = 0] [url = about:blank]
[task 2023-09-27T16:36:35.187Z] 16:36:35     INFO - GECKO(4480) | [Parent 4704: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 12 (18dc35f5020) [pid = 4704] [serial = 1] [outer = 0] [url = chrome://gfxsanity/content/sanityparent.html]
[task 2023-09-27T16:36:35.188Z] 16:36:35     INFO - GECKO(4480) | [Parent 4704: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (18dc4b8d400) [pid = 4704] [serial = 2] [outer = 0] [url = about:blank]
[task 2023-09-27T16:36:35.433Z] 16:36:35     INFO - GECKO(4480) | [Child 7364: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (2189c83dc00) [pid = 7364] [serial = 4] [outer = 0] [url = about:blank]
[task 2023-09-27T16:36:35.433Z] 16:36:35     INFO - GECKO(4480) | [Child 7364: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (2189e364c00) [pid = 7364] [serial = 10] [outer = 0] [url = about:blank]
[task 2023-09-27T16:36:35.433Z] 16:36:35     INFO - GECKO(4480) | [Child 7364: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (2189c83b400) [pid = 7364] [serial = 2] [outer = 0] [url = about:blank]
[task 2023-09-27T16:36:35.433Z] 16:36:35     INFO - GECKO(4480) | [Child 7364: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (2189e363800) [pid = 7364] [serial = 8] [outer = 0] [url = about:blank]
[task 2023-09-27T16:36:35.433Z] 16:36:35     INFO - GECKO(4480) | [Child 7364: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (2189e362400) [pid = 7364] [serial = 6] [outer = 0] [url = about:blank]
[task 2023-09-27T16:36:35.445Z] 16:36:35     INFO - GECKO(4480) | [Child 7364: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (2189c8f33e0) [pid = 7364] [serial = 3] [outer = 0] [url = about:blank]
[task 2023-09-27T16:36:35.446Z] 16:36:35     INFO - GECKO(4480) | [Child 7364: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (2189c8f3980) [pid = 7364] [serial = 9] [outer = 0] [url = about:blank]
[task 2023-09-27T16:36:35.446Z] 16:36:35     INFO - GECKO(4480) | [Child 7364: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (2189c8f3020) [pid = 7364] [serial = 1] [outer = 0] [url = about:blank]
[task 2023-09-27T16:36:35.446Z] 16:36:35     INFO - GECKO(4480) | [Child 7364: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (2189c8f37a0) [pid = 7364] [serial = 7] [outer = 0] [url = about:blank]
[task 2023-09-27T16:36:35.446Z] 16:36:35     INFO - GECKO(4480) | [Child 7364: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (2189c8f35c0) [pid = 7364] [serial = 5] [outer = 0] [url = about:blank]
[task 2023-09-27T16:36:35.448Z] 16:36:35     INFO - GECKO(4480) | [Child 4624: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (1dd71f40c00) [pid = 4624] [serial = 3] [outer = 0] [url = about:newtab]
[task 2023-09-27T16:36:35.464Z] 16:36:35     INFO - GECKO(4480) | [Child 10060: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (15699948400) [pid = 10060] [serial = 12] [outer = 0] [url = about:blank]
[task 2023-09-27T16:36:35.466Z] 16:36:35     INFO - GECKO(4480) | [Child 10060: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (1569b5c9400) [pid = 10060] [serial = 14] [outer = 0] [url = about:blank]
[task 2023-09-27T16:36:35.466Z] 16:36:35     INFO - GECKO(4480) | [Child 10060: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (1569254d020) [pid = 10060] [serial = 11] [outer = 0] [url = about:blank]
[task 2023-09-27T16:36:35.467Z] 16:36:35     INFO - GECKO(4480) | [Child 10060: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (1569254d3e0) [pid = 10060] [serial = 13] [outer = 0] [url = about:blank]
[task 2023-09-27T16:36:36.139Z] 16:36:36     INFO - GECKO(4480) | [Parent 4704: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (18dca02e400) [pid = 4704] [serial = 9] [outer = 0] [url = about:blank]
[task 2023-09-27T16:36:36.491Z] 16:36:36     INFO - GECKO(4480) | Completed ShutdownLeaks collections in process 7364
[task 2023-09-27T16:36:36.500Z] 16:36:36     INFO - GECKO(4480) | [Parent 4704: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (18dc3417800) [pid = 4704] [serial = 16] [outer = 18dca3f2020]
[task 2023-09-27T16:36:36.522Z] 16:36:36     INFO - GECKO(4480) | Completed ShutdownLeaks collections in process 10060
[task 2023-09-27T16:36:36.538Z] 16:36:36     INFO - GECKO(4480) | Completed ShutdownLeaks collections in process 4624
[task 2023-09-27T16:36:36.679Z] 16:36:36     INFO - GECKO(4480) | Completed ShutdownLeaks collections in process 3060
[task 2023-09-27T16:42:20.558Z] 16:42:20     INFO -  [Parent 1456, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/components/places/Database.cpp:512
[task 2023-09-27T16:42:20.581Z] 16:42:20     INFO -  console.error: (new TypeError("connection not specified or invalid.", "resource://gre/modules/Sqlite.sys.mjs", 1522))
[task 2023-09-27T16:42:20.585Z] 16:42:20     INFO -  console.error: (new TypeError("can't access property \"executeBeforeShutdown\", db is undefined", "resource://gre/modules/PlacesUtils.sys.mjs", 1467))
[task 2023-09-27T16:42:20.586Z] 16:42:20     INFO -  [Parent 1456, IPDL Background] WARNING: QM_TRY failure (ERROR): 'OkIf(gBasePath)', file dom/quota/ActorsParent.cpp:1702
[task 2023-09-27T16:42:20.586Z] 16:42:20     INFO -  [Parent 1456, IPDL Background] WARNING: Trying to create QuotaManager before profile-do-change! Forgot to call do_get_profile()?: file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:1701
[task 2023-09-27T16:42:20.587Z] 16:42:20     INFO -  [Parent 1456, IPDL Background] WARNING: QM_TRY failure (ERROR): 'GetOrCreate().map([](const auto& res) { return Ok{}; }) failed with resultCode 0x80004005, resultName NS_ERROR_FAILURE', file dom/quota/ActorsParent.cpp:1725
[task 2023-09-27T16:42:20.587Z] 16:42:20     INFO -  [Parent 1456, IPDL Background] WARNING: QM_TRY failure (ERROR): 'QuotaManager::EnsureCreated() failed with resultCode 0x80004005, resultName NS_ERROR_FAILURE', file dom/quota/QuotaParent.cpp:622
[task 2023-09-27T16:42:46.687Z] 16:42:46     INFO - TEST-UNEXPECTED-TIMEOUT | browser/components/customizableui/test/browser_1042100_default_placements_update.js (finished) | application timed out after 370 seconds with no output
[task 2023-09-27T16:42:46.687Z] 16:42:46     INFO - TEST-INFO 
[task 2023-09-27T16:42:46.688Z] 16:42:46     INFO - Buffered messages finished
[task 2023-09-27T16:42:46.689Z] 16:42:46  WARNING - Force-terminating active process(es).
[task 2023-09-27T16:42:46.689Z] 16:42:46     INFO - Determining child pids from psutil...
[task 2023-09-27T16:42:46.691Z] 16:42:46     INFO - [4704]
[task 2023-09-27T16:42:46.692Z] 16:42:46     INFO - ==> process 4704 launched child process 8708 ("Z:\task_169582893037434\build\application\firefox\firefox.exe" -contentproc --channel=2196 -parentBuildID 20230927151748 -prefsHandle 2124 -prefMapHandle 2104 -prefsLen 22716 -prefMapSize 249923 -appDir "Z:\task_169582893037434\build\application\firefox\browser" - {8dbfd0f7-b1b5-49ac-824c-0d3b06f18595} 4704 "\\.\pipe\gecko-crash-server-pipe.4704" 18dc46cdc48 gpu)
[task 2023-09-27T16:42:46.693Z] 16:42:46     INFO - ==> process 4704 launched child process 7440 ("Z:\task_169582893037434\build\application\firefox\firefox.exe" -contentproc --channel=2836 -childID 1 -isForBrowser -prefsHandle 2828 -prefMapHandle 2824 -prefsLen 22483 -prefMapSize 249923 -jsInitHandle 1412 -jsInitLen 290656 -parentBuildID 20230927151748 -win32kLockedDown -appDir "Z:\task_169582893037434\build\application\firefox\browser" - {18e5aef2-7997-4716-a0ec-b24c44262b77} 4704 "\\.\pipe\gecko-crash-server-pipe.4704" 18dc9404a48 tab)
[task 2023-09-27T16:42:46.694Z] 16:42:46     INFO - ==> process 4704 launched child process 3060 ("Z:\task_169582893037434\build\application\firefox\firefox.exe" -contentproc --channel=3292 -childID 2 -isForBrowser -prefsHandle 3284 -prefMapHandle 3280 -prefsLen 24026 -prefMapSize 249923 -jsInitHandle 1412 -jsInitLen 290656 -parentBuildID 20230927151748 -win32kLockedDown -appDir "Z:\task_169582893037434\build\application\firefox\browser" - {9bf37968-c613-485a-a631-364316804908} 4704 "\\.\pipe\gecko-crash-server-pipe.4704" 18dc8aa3948 tab)
[task 2023-09-27T16:42:46.695Z] 16:42:46     INFO - ==> process 4704 launched child process 10060 ("Z:\task_169582893037434\build\application\firefox\firefox.exe" -contentproc --channel=3312 -childID 3 -isForBrowser -prefsHandle 3448 -prefMapHandle 3488 -prefsLen 23649 -prefMapSize 249923 -jsInitHandle 1412 -jsInitLen 290656 -parentBuildID 20230927151748 -win32kLockedDown -appDir "Z:\task_169582893037434\build\application\firefox\browser" - {154d684d-76f0-40d6-b745-7d834a18d0c8} 4704 "\\.\pipe\gecko-crash-server-pipe.4704" 18dca43ff48 tab)
[task 2023-09-27T16:42:46.696Z] 16:42:46     INFO - ==> process 4704 launched child process 7364 ("Z:\task_169582893037434\build\application\firefox\firefox.exe" -contentproc --channel=3884 -childID 4 -isForBrowser -prefsHandle 3880 -prefMapHandle 3876 -prefsLen 23877 -prefMapSize 249923 -jsInitHandle 1412 -jsInitLen 290656 -parentBuildID 20230927151748 -win32kLockedDown -appDir "Z:\task_169582893037434\build\application\firefox\browser" - {2f06ca75-114c-4389-8e9f-9c7ecabcc02e} 4704 "\\.\pipe\gecko-crash-server-pipe.4704" 18dcc721848 tab)
[task 2023-09-27T16:42:46.697Z] 16:42:46     INFO - ==> process 4704 launched child process 1756 ("Z:\task_169582893037434\build\application\firefox\firefox.exe" -contentproc --channel=2840 -childID 5 -isForBrowser -prefsHandle 3872 -prefMapHandle 2928 -prefsLen 23877 -prefMapSize 249923 -jsInitHandle 1412 -jsInitLen 290656 -parentBuildID 20230927151748 -win32kLockedDown -appDir "Z:\task_169582893037434\build\application\firefox\browser" - {5f64c107-7294-4097-b0cf-6c3ac4f5e33d} 4704 "\\.\pipe\gecko-crash-server-pipe.4704" 18dcc952048 tab)
[task 2023-09-27T16:42:46.697Z] 16:42:46     INFO - ==> process 4704 launched child process 8656 ("Z:\task_169582893037434\build\application\firefox\firefox.exe" -contentproc --channel=3012 -childID 6 -isForBrowser -prefsHandle 3600 -prefMapHandle 3488 -prefsLen 23877 -prefMapSize 249923 -jsInitHandle 1412 -jsInitLen 290656 -parentBuildID 20230927151748 -win32kLockedDown -appDir "Z:\task_169582893037434\build\application\firefox\browser" - {4f7f10be-ceb8-4e2b-bcef-e88adba76454} 4704 "\\.\pipe\gecko-crash-server-pipe.4704" 18dc8aa3348 tab)
[task 2023-09-27T16:42:46.698Z] 16:42:46     INFO - ==> process 4704 launched child process 8492 ("Z:\task_169582893037434\build\application\firefox\firefox.exe" -contentproc --channel=5076 -parentBuildID 20230927151748 -sandboxingKind 0 -prefsHandle 4992 -prefMapHandle 4996 -prefsLen 32211 -prefMapSize 249923 -win32kLockedDown -appDir "Z:\task_169582893037434\build\application\firefox\browser" - {e62bc152-93e1-4038-bdd5-e4e57229861d} 4704 "\\.\pipe\gecko-crash-server-pipe.4704" 18dcfab2448 utility)
[task 2023-09-27T16:42:46.699Z] 16:42:46     INFO - ==> process 4704 launched child process 4624 ("Z:\task_169582893037434\build\application\firefox\firefox.exe" -contentproc --channel=5320 -childID 7 -isForBrowser -prefsHandle 5312 -prefMapHandle 5300 -prefsLen 32211 -prefMapSize 249923 -jsInitHandle 1412 -jsInitLen 290656 -parentBuildID 20230927151748 -win32kLockedDown -appDir "Z:\task_169582893037434\build\application\firefox\browser" - {f0941d1d-3f55-40a2-b880-4c80c0c107b3} 4704 "\\.\pipe\gecko-crash-server-pipe.4704" 18dcfab2748 tab)
[task 2023-09-27T16:42:46.700Z] 16:42:46     INFO - ==> process 4704 launched child process 6148 ("Z:\task_169582893037434\build\application\firefox\firefox.exe" -contentproc --channel=5784 -childID 8 -isForBrowser -prefsHandle 5716 -prefMapHandle 5764 -prefsLen 29630 -prefMapSize 249923 -jsInitHandle 1412 -jsInitLen 290656 -parentBuildID 20230927151748 -win32kLockedDown -appDir "Z:\task_169582893037434\build\application\firefox\browser" - {d167c9a3-5497-4b77-9b96-1243ddfd058e} 4704 "\\.\pipe\gecko-crash-server-pipe.4704" 18dd0a63f48 tab)
[task 2023-09-27T16:42:46.700Z] 16:42:46     INFO - Found child pids: {4704, 8708, 7364, 6148, 10060, 8492, 7440, 8656, 4624, 3060, 1756}
[task 2023-09-27T16:42:46.700Z] 16:42:46     INFO - Failed to get child procs
[task 2023-09-27T16:42:46.700Z] 16:42:46     INFO - Killing process: 4704
[task 2023-09-27T16:42:46.701Z] 16:42:46     INFO - TEST-INFO | started process screenshot
[task 2023-09-27T16:42:46.812Z] 16:42:46     INFO - TEST-INFO | screenshot: exit 0
[task 2023-09-27T16:42:46.825Z] 16:42:46     INFO - mozcrash Writing a dump to C:\Users\task_169582893037434\AppData\Local\Temp\tmp6sk7rc2g.mozrunner\minidumps\652a9dd8-736a-4ac2-b3bf-e21adf8193bb.dmp for [4704]
[task 2023-09-27T16:42:47.079Z] 16:42:47     INFO - Killing process: 8708
[task 2023-09-27T16:42:47.084Z] 16:42:47     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-09-27T16:42:47.085Z] 16:42:47     INFO - mozcrash Writing a dump to C:\Users\task_169582893037434\AppData\Local\Temp\tmp6sk7rc2g.mozrunner\minidumps\39876e53-e5e2-42b3-85bf-8d243d76fd8d.dmp for [8708]
[task 2023-09-27T16:42:47.085Z] 16:42:47  WARNING - mozcrash unable to get handle for pid 8708: 87
[task 2023-09-27T16:42:47.086Z] 16:42:47  WARNING - mozcrash kill_pid(): unable to get handle for pid 8708: 87
[task 2023-09-27T16:42:47.086Z] 16:42:47     INFO - Killing process: 7364
[task 2023-09-27T16:42:47.086Z] 16:42:47     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-09-27T16:42:47.087Z] 16:42:47     INFO - mozcrash Writing a dump to C:\Users\task_169582893037434\AppData\Local\Temp\tmp6sk7rc2g.mozrunner\minidumps\3544d828-0884-48db-94b0-32f47f80299a.dmp for [7364]
[task 2023-09-27T16:42:47.088Z] 16:42:47  WARNING - mozcrash unable to dump minidump file for pid 7364: 1
[task 2023-09-27T16:42:47.088Z] 16:42:47  WARNING - mozcrash kill_pid(): unable to get handle for pid 7364: 87
[task 2023-09-27T16:42:47.088Z] 16:42:47     INFO - Killing process: 6148
[task 2023-09-27T16:42:47.089Z] 16:42:47     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-09-27T16:42:47.089Z] 16:42:47     INFO - mozcrash Writing a dump to C:\Users\task_169582893037434\AppData\Local\Temp\tmp6sk7rc2g.mozrunner\minidumps\7c344f1b-ba43-4cb8-a2a2-3494c0051c0f.dmp for [6148]
[task 2023-09-27T16:42:47.089Z] 16:42:47  WARNING - mozcrash unable to get handle for pid 6148: 87
[task 2023-09-27T16:42:47.090Z] 16:42:47  WARNING - mozcrash kill_pid(): unable to get handle for pid 6148: 87
[task 2023-09-27T16:42:47.090Z] 16:42:47     INFO - Killing process: 10060
[task 2023-09-27T16:42:47.090Z] 16:42:47     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-09-27T16:42:47.091Z] 16:42:47     INFO - mozcrash Writing a dump to C:\Users\task_169582893037434\AppData\Local\Temp\tmp6sk7rc2g.mozrunner\minidumps\eb597a82-8f6d-4e57-98af-ae8e5ae5b793.dmp for [10060]
[task 2023-09-27T16:42:47.091Z] 16:42:47  WARNING - mozcrash unable to get handle for pid 10060: 87
[task 2023-09-27T16:42:47.091Z] 16:42:47  WARNING - mozcrash kill_pid(): unable to get handle for pid 10060: 87
[task 2023-09-27T16:42:47.091Z] 16:42:47     INFO - Killing process: 8492
[task 2023-09-27T16:42:47.092Z] 16:42:47     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-09-27T16:42:47.092Z] 16:42:47     INFO - mozcrash Writing a dump to C:\Users\task_169582893037434\AppData\Local\Temp\tmp6sk7rc2g.mozrunner\minidumps\14356081-bffd-46c0-8114-b8fa2dfdddf7.dmp for [8492]
[task 2023-09-27T16:42:47.092Z] 16:42:47  WARNING - mozcrash unable to get handle for pid 8492: 87
[task 2023-09-27T16:42:47.093Z] 16:42:47  WARNING - mozcrash kill_pid(): unable to get handle for pid 8492: 87
[task 2023-09-27T16:42:47.093Z] 16:42:47     INFO - Killing process: 7440
[task 2023-09-27T16:42:47.093Z] 16:42:47     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-09-27T16:42:47.094Z] 16:42:47     INFO - mozcrash Writing a dump to C:\Users\task_169582893037434\AppData\Local\Temp\tmp6sk7rc2g.mozrunner\minidumps\cad7caf6-5cec-4518-bc30-ea67743d2492.dmp for [7440]
[task 2023-09-27T16:42:47.095Z] 16:42:47  WARNING - mozcrash unable to get handle for pid 7440: 87
[task 2023-09-27T16:42:47.095Z] 16:42:47  WARNING - mozcrash kill_pid(): unable to get handle for pid 7440: 87
[task 2023-09-27T16:42:47.095Z] 16:42:47     INFO - Killing process: 8656
[task 2023-09-27T16:42:47.095Z] 16:42:47     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-09-27T16:42:47.096Z] 16:42:47     INFO - mozcrash Writing a dump to C:\Users\task_169582893037434\AppData\Local\Temp\tmp6sk7rc2g.mozrunner\minidumps\f4ad6d61-f958-446b-80d5-0859c7b8cd7e.dmp for [8656]
[task 2023-09-27T16:42:47.096Z] 16:42:47  WARNING - mozcrash unable to get handle for pid 8656: 87
[task 2023-09-27T16:42:47.096Z] 16:42:47  WARNING - mozcrash kill_pid(): unable to get handle for pid 8656: 87
[task 2023-09-27T16:42:47.097Z] 16:42:47     INFO - Killing process: 4624
[task 2023-09-27T16:42:47.097Z] 16:42:47     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-09-27T16:42:47.097Z] 16:42:47     INFO - mozcrash Writing a dump to C:\Users\task_169582893037434\AppData\Local\Temp\tmp6sk7rc2g.mozrunner\minidumps\ca0f59fb-1902-4fbb-81c2-b13c7bc5863c.dmp for [4624]
[task 2023-09-27T16:42:47.098Z] 16:42:47  WARNING - mozcrash unable to get handle for pid 4624: 87
[task 2023-09-27T16:42:47.098Z] 16:42:47  WARNING - mozcrash kill_pid(): unable to get handle for pid 4624: 87
[task 2023-09-27T16:42:47.098Z] 16:42:47     INFO - Killing process: 3060
[task 2023-09-27T16:42:47.099Z] 16:42:47     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-09-27T16:42:47.099Z] 16:42:47     INFO - mozcrash Writing a dump to C:\Users\task_169582893037434\AppData\Local\Temp\tmp6sk7rc2g.mozrunner\minidumps\fdb15ccb-ba33-4ecc-a8cd-9067de033999.dmp for [3060]
[task 2023-09-27T16:42:47.099Z] 16:42:47  WARNING - mozcrash unable to get handle for pid 3060: 87
[task 2023-09-27T16:42:47.100Z] 16:42:47  WARNING - mozcrash kill_pid(): unable to get handle for pid 3060: 87
[task 2023-09-27T16:42:47.100Z] 16:42:47     INFO - Killing process: 1756
[task 2023-09-27T16:42:47.100Z] 16:42:47     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-09-27T16:42:47.101Z] 16:42:47     INFO - mozcrash Writing a dump to C:\Users\task_169582893037434\AppData\Local\Temp\tmp6sk7rc2g.mozrunner\minidumps\db964fd5-956e-44dd-95cd-a98a35ac8243.dmp for [1756]
[task 2023-09-27T16:42:47.101Z] 16:42:47  WARNING - mozcrash unable to get handle for pid 1756: 87
[task 2023-09-27T16:42:47.102Z] 16:42:47  WARNING - mozcrash kill_pid(): unable to get handle for pid 1756: 87
[task 2023-09-27T16:42:47.102Z] 16:42:47     INFO - Killing process: 4480
[task 2023-09-27T16:42:47.102Z] 16:42:47     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-09-27T16:42:47.103Z] 16:42:47     INFO - mozcrash Writing a dump to C:\Users\task_169582893037434\AppData\Local\Temp\tmp6sk7rc2g.mozrunner\minidumps\a9b939f8-7a25-4e28-baa9-5195dcf24b87.dmp for [4480]
[task 2023-09-27T16:42:47.103Z] 16:42:47  WARNING - mozcrash unable to dump minidump file for pid 4480: 1
[task 2023-09-27T16:42:47.103Z] 16:42:47  WARNING - mozcrash kill_pid(): unable to terminate pid 4480: 5
[task 2023-09-27T16:42:47.103Z] 16:42:47     INFO - psutil found pid 4480 dead
[task 2023-09-27T16:42:47.104Z] 16:42:47     INFO - TEST-INFO | Main app process: exit 1
[task 2023-09-27T16:42:47.104Z] 16:42:47     INFO - TEST-INFO | Confirming we saw 26 DOCSHELL created and 15 destroyed log strings.
[task 2023-09-27T16:42:47.104Z] 16:42:47     INFO - TEST-INFO | Confirming we saw 66 DOMWINDOW created and 43 destroyed log strings.
[task 2023-09-27T16:42:47.104Z] 16:42:47     INFO - Buffered messages finished
[task 2023-09-27T16:42:47.105Z] 16:42:47     INFO - runtests.py | Application ran for: 0:06:26.370931
[task 2023-09-27T16:42:47.105Z] 16:42:47     INFO - zombiecheck | Reading PID log: C:\Users\task_169582893037434\AppData\Local\Temp\tmp5nnfefqypidlog
[task 2023-09-27T16:42:47.106Z] 16:42:47     INFO - ==> process 4704 launched child process 8708 ("Z:\task_169582893037434\build\application\firefox\firefox.exe" -contentproc --channel=2196 -parentBuildID 20230927151748 -prefsHandle 2124 -prefMapHandle 2104 -prefsLen 22716 -prefMapSize 249923 -appDir "Z:\task_169582893037434\build\application\firefox\browser" - {8dbfd0f7-b1b5-49ac-824c-0d3b06f18595} 4704 "\\.\pipe\gecko-crash-server-pipe.4704" 18dc46cdc48 gpu)
[task 2023-09-27T16:42:47.107Z] 16:42:47     INFO - ==> process 4704 launched child process 7440 ("Z:\task_169582893037434\build\application\firefox\firefox.exe" -contentproc --channel=2836 -childID 1 -isForBrowser -prefsHandle 2828 -prefMapHandle 2824 -prefsLen 22483 -prefMapSize 249923 -jsInitHandle 1412 -jsInitLen 290656 -parentBuildID 20230927151748 -win32kLockedDown -appDir "Z:\task_169582893037434\build\application\firefox\browser" - {18e5aef2-7997-4716-a0ec-b24c44262b77} 4704 "\\.\pipe\gecko-crash-server-pipe.4704" 18dc9404a48 tab)
[task 2023-09-27T16:42:47.108Z] 16:42:47     INFO - ==> process 4704 launched child process 3060 ("Z:\task_169582893037434\build\application\firefox\firefox.exe" -contentproc --channel=3292 -childID 2 -isForBrowser -prefsHandle 3284 -prefMapHandle 3280 -prefsLen 24026 -prefMapSize 249923 -jsInitHandle 1412 -jsInitLen 290656 -parentBuildID 20230927151748 -win32kLockedDown -appDir "Z:\task_169582893037434\build\application\firefox\browser" - {9bf37968-c613-485a-a631-364316804908} 4704 "\\.\pipe\gecko-crash-server-pipe.4704" 18dc8aa3948 tab)
[task 2023-09-27T16:42:47.108Z] 16:42:47     INFO - ==> process 4704 launched child process 10060 ("Z:\task_169582893037434\build\application\firefox\firefox.exe" -contentproc --channel=3312 -childID 3 -isForBrowser -prefsHandle 3448 -prefMapHandle 3488 -prefsLen 23649 -prefMapSize 249923 -jsInitHandle 1412 -jsInitLen 290656 -parentBuildID 20230927151748 -win32kLockedDown -appDir "Z:\task_169582893037434\build\application\firefox\browser" - {154d684d-76f0-40d6-b745-7d834a18d0c8} 4704 "\\.\pipe\gecko-crash-server-pipe.4704" 18dca43ff48 tab)
[task 2023-09-27T16:42:47.109Z] 16:42:47     INFO - ==> process 4704 launched child process 7364 ("Z:\task_169582893037434\build\application\firefox\firefox.exe" -contentproc --channel=3884 -childID 4 -isForBrowser -prefsHandle 3880 -prefMapHandle 3876 -prefsLen 23877 -prefMapSize 249923 -jsInitHandle 1412 -jsInitLen 290656 -parentBuildID 20230927151748 -win32kLockedDown -appDir "Z:\task_169582893037434\build\application\firefox\browser" - {2f06ca75-114c-4389-8e9f-9c7ecabcc02e} 4704 "\\.\pipe\gecko-crash-server-pipe.4704" 18dcc721848 tab)
[task 2023-09-27T16:42:47.110Z] 16:42:47     INFO - ==> process 4704 launched child process 1756 ("Z:\task_169582893037434\build\application\firefox\firefox.exe" -contentproc --channel=2840 -childID 5 -isForBrowser -prefsHandle 3872 -prefMapHandle 2928 -prefsLen 23877 -prefMapSize 249923 -jsInitHandle 1412 -jsInitLen 290656 -parentBuildID 20230927151748 -win32kLockedDown -appDir "Z:\task_169582893037434\build\application\firefox\browser" - {5f64c107-7294-4097-b0cf-6c3ac4f5e33d} 4704 "\\.\pipe\gecko-crash-server-pipe.4704" 18dcc952048 tab)
[task 2023-09-27T16:42:47.111Z] 16:42:47     INFO - ==> process 4704 launched child process 8656 ("Z:\task_169582893037434\build\application\firefox\firefox.exe" -contentproc --channel=3012 -childID 6 -isForBrowser -prefsHandle 3600 -prefMapHandle 3488 -prefsLen 23877 -prefMapSize 249923 -jsInitHandle 1412 -jsInitLen 290656 -parentBuildID 20230927151748 -win32kLockedDown -appDir "Z:\task_169582893037434\build\application\firefox\browser" - {4f7f10be-ceb8-4e2b-bcef-e88adba76454} 4704 "\\.\pipe\gecko-crash-server-pipe.4704" 18dc8aa3348 tab)
[task 2023-09-27T16:42:47.111Z] 16:42:47     INFO - ==> process 4704 launched child process 8492 ("Z:\task_169582893037434\build\application\firefox\firefox.exe" -contentproc --channel=5076 -parentBuildID 20230927151748 -sandboxingKind 0 -prefsHandle 4992 -prefMapHandle 4996 -prefsLen 32211 -prefMapSize 249923 -win32kLockedDown -appDir "Z:\task_169582893037434\build\application\firefox\browser" - {e62bc152-93e1-4038-bdd5-e4e57229861d} 4704 "\\.\pipe\gecko-crash-server-pipe.4704" 18dcfab2448 utility)
[task 2023-09-27T16:42:47.112Z] 16:42:47     INFO - ==> process 4704 launched child process 4624 ("Z:\task_169582893037434\build\application\firefox\firefox.exe" -contentproc --channel=5320 -childID 7 -isForBrowser -prefsHandle 5312 -prefMapHandle 5300 -prefsLen 32211 -prefMapSize 249923 -jsInitHandle 1412 -jsInitLen 290656 -parentBuildID 20230927151748 -win32kLockedDown -appDir "Z:\task_169582893037434\build\application\firefox\browser" - {f0941d1d-3f55-40a2-b880-4c80c0c107b3} 4704 "\\.\pipe\gecko-crash-server-pipe.4704" 18dcfab2748 tab)
[task 2023-09-27T16:42:47.114Z] 16:42:47     INFO - ==> process 4704 launched child process 6148 ("Z:\task_169582893037434\build\application\firefox\firefox.exe" -contentproc --channel=5784 -childID 8 -isForBrowser -prefsHandle 5716 -prefMapHandle 5764 -prefsLen 29630 -prefMapSize 249923 -jsInitHandle 1412 -jsInitLen 290656 -parentBuildID 20230927151748 -win32kLockedDown -appDir "Z:\task_169582893037434\build\application\firefox\browser" - {d167c9a3-5497-4b77-9b96-1243ddfd058e} 4704 "\\.\pipe\gecko-crash-server-pipe.4704" 18dd0a63f48 tab)
[task 2023-09-27T16:42:47.114Z] 16:42:47     INFO - zombiecheck | Checking for orphan process with PID: 7364
[task 2023-09-27T16:42:47.114Z] 16:42:47     INFO - zombiecheck | Checking for orphan process with PID: 8708
[task 2023-09-27T16:42:47.115Z] 16:42:47     INFO - zombiecheck | Checking for orphan process with PID: 6148
[task 2023-09-27T16:42:47.115Z] 16:42:47     INFO - zombiecheck | Checking for orphan process with PID: 10060
[task 2023-09-27T16:42:47.115Z] 16:42:47     INFO - zombiecheck | Checking for orphan process with PID: 8492
[task 2023-09-27T16:42:47.115Z] 16:42:47     INFO - zombiecheck | Checking for orphan process with PID: 7440
[task 2023-09-27T16:42:47.116Z] 16:42:47     INFO - zombiecheck | Checking for orphan process with PID: 8656
[task 2023-09-27T16:42:47.116Z] 16:42:47     INFO - zombiecheck | Checking for orphan process with PID: 4624
[task 2023-09-27T16:42:47.117Z] 16:42:47     INFO - zombiecheck | Checking for orphan process with PID: 3060
[task 2023-09-27T16:42:47.117Z] 16:42:47     INFO - zombiecheck | Checking for orphan process with PID: 1756
[task 2023-09-27T16:42:47.119Z] 16:42:47     INFO - mozcrash Copy/paste: Z:/task_169582893037434/fetches\minidump-stackwalk\minidump-stackwalk.exe --symbols-url=https://symbols.mozilla.org/ --cyborg=C:\Users\task_169582893037434\AppData\Local\Temp\tmp6w1vey5u\a9b939f8-7a25-4e28-baa9-5195dcf24b87.trace C:\Users\task_169582893037434\AppData\Local\Temp\tmp6sk7rc2g.mozrunner\minidumps\a9b939f8-7a25-4e28-baa9-5195dcf24b87.dmp Z:\task_169582893037434\build\symbols
[task 2023-09-27T16:42:47.313Z] 16:42:47     INFO - mozcrash Saved minidump as Z:\task_169582893037434\build\blobber_upload_dir\a9b939f8-7a25-4e28-baa9-5195dcf24b87.dmp
[task 2023-09-27T16:42:47.323Z] 16:42:47     INFO - PROCESS-CRASH | application crashed [None] | browser/components/customizableui/test/browser_1042100_default_placements_update.js (finished) 
[task 2023-09-27T16:42:47.323Z] 16:42:47     INFO - Process type: unknown
[task 2023-09-27T16:42:47.323Z] 16:42:47     INFO - Process pid: None
[task 2023-09-27T16:42:47.323Z] 16:42:47     INFO - Crash dump filename: C:\Users\task_169582893037434\AppData\Local\Temp\tmp6sk7rc2g.mozrunner\minidumps\a9b939f8-7a25-4e28-baa9-5195dcf24b87.dmp
[task 2023-09-27T16:42:47.323Z] 16:42:47     INFO - stderr from minidump-stackwalk:
[task 2023-09-27T16:42:47.323Z] 16:42:47     INFO - ERROR MissingHeader - Error reading dump: Missing minidump header (empty minidump?)
[task 2023-09-27T16:42:47.326Z] 16:42:47     INFO - mozcrash Copy/paste: Z:/task_169582893037434/fetches\minidump-stackwalk\minidump-stackwalk.exe --symbols-url=https://symbols.mozilla.org/ --cyborg=C:\Users\task_169582893037434\AppData\Local\Temp\tmpc01hq97m\652a9dd8-736a-4ac2-b3bf-e21adf8193bb.trace C:\Users\task_169582893037434\AppData\Local\Temp\tmp6sk7rc2g.mozrunner\minidumps\652a9dd8-736a-4ac2-b3bf-e21adf8193bb.dmp Z:\task_169582893037434\build\symbols
[task 2023-09-27T16:43:00.366Z] 16:43:00     INFO - mozcrash Saved minidump as Z:\task_169582893037434\build\blobber_upload_dir\652a9dd8-736a-4ac2-b3bf-e21adf8193bb.dmp
[task 2023-09-27T16:43:00.882Z] 16:43:00     INFO - PROCESS-CRASH | application crashed [None] | browser/components/customizableui/test/browser_1042100_default_placements_update.js (finished) 
[task 2023-09-27T16:43:00.882Z] 16:43:00     INFO - Process type: unknown
[task 2023-09-27T16:43:00.882Z] 16:43:00     INFO - Process pid: 4704
[task 2023-09-27T16:43:00.891Z] 16:43:00     INFO - Crash dump filename: C:\Users\task_169582893037434\AppData\Local\Temp\tmp6sk7rc2g.mozrunner\minidumps\652a9dd8-736a-4ac2-b3bf-e21adf8193bb.dmp
[task 2023-09-27T16:43:00.891Z] 16:43:00     INFO - Operating system: Windows NT
[task 2023-09-27T16:43:00.891Z] 16:43:00     INFO -                   10.0.22621
[task 2023-09-27T16:43:00.891Z] 16:43:00     INFO - CPU: amd64
[task 2023-09-27T16:43:00.891Z] 16:43:00     INFO -      family 6 model 106 stepping 6
[task 2023-09-27T16:43:00.891Z] 16:43:00     INFO -      8 CPUs
[task 2023-09-27T16:43:00.891Z] 16:43:00     INFO - 
[task 2023-09-27T16:43:00.891Z] 16:43:00     INFO - No crash
[task 2023-09-27T16:43:00.891Z] 16:43:00     INFO - Process uptime: 387 seconds
[task 2023-09-27T16:43:00.891Z] 16:43:00     INFO - 
[task 2023-09-27T16:43:00.891Z] 16:43:00     INFO - Thread 0 MainThread
[task 2023-09-27T16:43:00.891Z] 16:43:00     INFO -  0  win32u.dll!NtUserMsgWaitForMultipleObjectsEx + 0x14
[task 2023-09-27T16:43:00.892Z] 16:43:00     INFO -      rax = 0x00000000000014c2    rdx = 0x0000000000000000
[task 2023-09-27T16:43:00.892Z] 16:43:00     INFO -      rcx = 0x0000000000000000    rbx = 0x0000000000000000
[task 2023-09-27T16:43:00.892Z] 16:43:00     INFO -      rsi = 0x00000000ffffffff    rdi = 0x000000000017aa88
[task 2023-09-27T16:43:00.892Z] 16:43:00     INFO -      rbp = 0x00000000ffffffff    rsp = 0x0000007567bfe758
[task 2023-09-27T16:43:00.892Z] 16:43:00     INFO -       r8 = 0x0000007567bfbde8     r9 = 0x0000000000000001
[task 2023-09-27T16:43:00.892Z] 16:43:00     INFO -      r10 = 0x0000000000000000    r11 = 0x0000000000000246
[task 2023-09-27T16:43:00.892Z] 16:43:00     INFO -      r12 = 0x00007ffbd7080e80    r13 = 0x0000007567bfe790
[task 2023-09-27T16:43:00.892Z] 16:43:00     INFO -      r14 = 0x00007ffbd7a62260    r15 = 0x00007ffbd7a62310
[task 2023-09-27T16:43:00.892Z] 16:43:00     INFO -      rip = 0x00007ffbd5fdacf4
[task 2023-09-27T16:43:00.892Z] 16:43:00     INFO -     Found by: given as instruction pointer in context
[task 2023-09-27T16:43:00.892Z] 16:43:00     INFO -  1  xul.dll!mozilla::widget::WinUtils::WaitForMessage(unsigned long) [WinUtils.cpp:2382e5ee161cefc24d2ea9a5abad05e5ca4e9e67 : 454 + 0x19]
[task 2023-09-27T16:43:00.892Z] 16:43:00     INFO -      rbx = 0x0000000000000000    rbp = 0x00000000ffffffff
[task 2023-09-27T16:43:00.892Z] 16:43:00     INFO -      rsp = 0x0000007567bfe760    r12 = 0x00007ffbd7080e80
[task 2023-09-27T16:43:00.892Z] 16:43:00     INFO -      r13 = 0x0000007567bfe790    r14 = 0x00007ffbd7a62260
[task 2023-09-27T16:43:00.892Z] 16:43:00     INFO -      r15 = 0x00007ffbd7a62310    rip = 0x00007ffb9648685e
[task 2023-09-27T16:43:00.892Z] 16:43:00     INFO -     Found by: call frame info
[task 2023-09-27T16:43:00.892Z] 16:43:00     INFO -  2  xul.dll!nsAppShell::ProcessNextNativeEvent(bool) [nsAppShell.cpp:2382e5ee161cefc24d2ea9a5abad05e5ca4e9e67 : 780 + 0x9]
[task 2023-09-27T16:43:00.892Z] 16:43:00     INFO -      rbx = 0x0000000000000000    rsi = 0x0000018db5a71ca0
[task 2023-09-27T16:43:00.892Z] 16:43:00     INFO -      rdi = 0x0000018db5a4b040    rbp = 0x0000000000000001
[task 2023-09-27T16:43:00.892Z] 16:43:00     INFO -      rsp = 0x0000007567bfe830    r12 = 0xaaaaaaaaaaaaaaaa
[task 2023-09-27T16:43:00.892Z] 16:43:00     INFO -      r13 = 0x0000000000000001    r14 = 0x0000007567bfe9a8
[task 2023-09-27T16:43:00.893Z] 16:43:00     INFO -      r15 = 0x0000007567bfe870    rip = 0x00007ffb9649912a
[task 2023-09-27T16:43:00.893Z] 16:43:00     INFO -     Found by: call frame info
[task 2023-09-27T16:43:00.893Z] 16:43:00     INFO -  3  xul.dll!nsBaseAppShell::DoProcessNextNativeEvent(bool) [nsBaseAppShell.cpp:2382e5ee161cefc24d2ea9a5abad05e5ca4e9e67 : 131]
[task 2023-09-27T16:43:00.893Z] 16:43:00     INFO -     Found by: inlining
[task 2023-09-27T16:43:00.893Z] 16:43:00     INFO -  4  xul.dll!nsBaseAppShell::OnProcessNextEvent(nsIThreadInternal*, bool) [nsBaseAppShell.cpp:2382e5ee161cefc24d2ea9a5abad05e5ca4e9e67 : 267 + 0x29]
[task 2023-09-27T16:43:00.893Z] 16:43:00     INFO -      rbx = 0x0000000000000000    rsi = 0x0000018db5a6f200
[task 2023-09-27T16:43:00.893Z] 16:43:00     INFO -      rdi = 0x0000018db5a71ca8    rbp = 0x0000000000000001
[task 2023-09-27T16:43:00.893Z] 16:43:00     INFO -      rsp = 0x0000007567bfebc0    r12 = 0x0000000000000000
[task 2023-09-27T16:43:00.893Z] 16:43:00     INFO -      r13 = 0x00007ffbcd44c760    r14 = 0x0000018db5a71ca0
[task 2023-09-27T16:43:00.893Z] 16:43:00     INFO -      r15 = 0x00007ffbcd44c760    rip = 0x00007ffb963bcb02
[task 2023-09-27T16:43:00.893Z] 16:43:00     INFO -     Found by: call frame info
[task 2023-09-27T16:43:00.893Z] 16:43:00     INFO -  5  xul.dll!nsThread::ProcessNextEvent(bool, bool*) [nsThread.cpp:2382e5ee161cefc24d2ea9a5abad05e5ca4e9e67 : 1114 + 0x12]
[task 2023-09-27T16:43:00.893Z] 16:43:00     INFO -      rbx = 0x0000018dc35db000    rsi = 0x0000018db5a6f200
[task 2023-09-27T16:43:00.893Z] 16:43:00     INFO -      rdi = 0x0000007567bfee6f    rbp = 0x0000018db5a6f2ea
[task 2023-09-27T16:43:00.893Z] 16:43:00     INFO -      rsp = 0x0000007567bfec50    r12 = 0x0000018db5a6f218
[task 2023-09-27T16:43:00.893Z] 16:43:00     INFO -      r13 = 0xaaaaaaaaaaaaaaaa    r14 = 0x0000018db5a0b901
[task 2023-09-27T16:43:00.893Z] 16:43:00     INFO -      r15 = 0x0000000000000001    rip = 0x00007ffb91873412
[task 2023-09-27T16:43:00.893Z] 16:43:00     INFO -     Found by: call frame info
[task 2023-09-27T16:43:00.893Z] 16:43:00     INFO -  6  xul.dll!NS_ProcessNextEvent(nsIThread*, bool) [nsThreadUtils.cpp:2382e5ee161cefc24d2ea9a5abad05e5ca4e9e67 : 480 + 0x16]
[task 2023-09-27T16:43:00.893Z] 16:43:00     INFO -      rbx = 0x00000000b5a0b901    rsi = 0x0000018db5a0b980
[task 2023-09-27T16:43:00.893Z] 16:43:00     INFO -      rdi = 0x0000018db5a781a0    rbp = 0x00000000b5a0b900
[task 2023-09-27T16:43:00.893Z] 16:43:00     INFO -      rsp = 0x0000007567bfee40    r12 = 0xaaaaaaaaaaaaaaaa
[task 2023-09-27T16:43:00.894Z] 16:43:00     INFO -      r13 = 0x00007ffb9b3af89a    r14 = 0x0000018db5a0b9b0
[task 2023-09-27T16:43:00.894Z] 16:43:00     INFO -      r15 = 0x0000018db5a0b9a0    rip = 0x00007ffb9187ae95
[task 2023-09-27T16:43:00.894Z] 16:43:00     INFO -     Found by: call frame info
[task 2023-09-27T16:43:00.894Z] 16:43:00     INFO -  7  xul.dll!mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate*) [MessagePump.cpp:2382e5ee161cefc24d2ea9a5abad05e5ca4e9e67 : 107 + 0x9]
[task 2023-09-27T16:43:00.894Z] 16:43:00     INFO -      rbx = 0x0000018db5a6f200    rsi = 0x0000018db5a0b980
[task 2023-09-27T16:43:00.894Z] 16:43:00     INFO -      rbp = 0x00000000b5a0b900    rsp = 0x0000007567bfee90
[task 2023-09-27T16:43:00.894Z] 16:43:00     INFO -      r12 = 0xaaaaaaaaaaaaaaaa    r13 = 0x00007ffb9b3af89a
[task 2023-09-27T16:43:00.894Z] 16:43:00     INFO -      r14 = 0x0000018db5a0b9b0    r15 = 0x0000018db5a0b9a0
[task 2023-09-27T16:43:00.894Z] 16:43:00     INFO -      rip = 0x00007ffb923567eb
[task 2023-09-27T16:43:00.894Z] 16:43:00     INFO -     Found by: call frame info
[task 2023-09-27T16:43:00.894Z] 16:43:00     INFO -  8  xul.dll!MessageLoop::RunHandler() [message_loop.cc:2382e5ee161cefc24d2ea9a5abad05e5ca4e9e67 : 363 + 0x7]
[task 2023-09-27T16:43:00.894Z] 16:43:00     INFO -      rbx = 0x00000000b5a71c01    rsi = 0x0000018db5a781a0
[task 2023-09-27T16:43:00.894Z] 16:43:00     INFO -      rdi = 0x0000018db5a6f200    rbp = 0x0000007567bfef20
[task 2023-09-27T16:43:00.894Z] 16:43:00     INFO -      rsp = 0x0000007567bfef00    r12 = 0xaaaaaaaaaaaaaaaa
[task 2023-09-27T16:43:00.894Z] 16:43:00     INFO -      r13 = 0x00007ffb9b3af89a    r14 = 0x0000007567bff320
[task 2023-09-27T16:43:00.894Z] 16:43:00     INFO -      r15 = 0x0002002100000018    rip = 0x00007ffb922d95e0
[task 2023-09-27T16:43:00.894Z] 16:43:00     INFO -     Found by: call frame info
[task 2023-09-27T16:43:00.894Z] 16:43:00     INFO -  9  xul.dll!MessageLoop::Run() [message_loop.cc:2382e5ee161cefc24d2ea9a5abad05e5ca4e9e67 : 345 + 0x4]
[task 2023-09-27T16:43:00.894Z] 16:43:00     INFO -      rbx = 0x00000000b5a71c01    rsi = 0x0000018db5a71ca0
[task 2023-09-27T16:43:00.894Z] 16:43:00     INFO -      rdi = 0x0000018db5a6f200    rbp = 0x0002000100000000
[task 2023-09-27T16:43:00.894Z] 16:43:00     INFO -      rsp = 0x0000007567bfef50    r12 = 0xaaaaaaaaaaaaaaaa
[task 2023-09-27T16:43:00.894Z] 16:43:00     INFO -      r13 = 0x00007ffb9b3af89a    r14 = 0x0000007567bff320
[task 2023-09-27T16:43:00.894Z] 16:43:00     INFO -      r15 = 0x0002002100000018    rip = 0x00007ffb922d949f
[task 2023-09-27T16:43:00.894Z] 16:43:00     INFO -     Found by: call frame info
[task 2023-09-27T16:43:00.895Z] 16:43:00     INFO - 10  xul.dll!nsBaseAppShell::Run() [nsBaseAppShell.cpp:2382e5ee161cefc24d2ea9a5abad05e5ca4e9e67 : 148 + 0xc]
[task 2023-09-27T16:43:00.895Z] 16:43:00     INFO -      rbx = 0x00000000b5a71c01    rbp = 0x0002000100000000
[task 2023-09-27T16:43:00.895Z] 16:43:00     INFO -      rsp = 0x0000007567bfefa0    r12 = 0xaaaaaaaaaaaaaaaa
[task 2023-09-27T16:43:00.895Z] 16:43:00     INFO -      r13 = 0x00007ffb9b3af89a    r14 = 0x0000007567bff320
[task 2023-09-27T16:43:00.895Z] 16:43:00     INFO -      r15 = 0x0002002100000018    rip = 0x00007ffb963bc7d8
[task 2023-09-27T16:43:00.895Z] 16:43:00     INFO -     Found by: call frame info
[task 2023-09-27T16:43:00.895Z] 16:43:00     INFO - 11  xul.dll!nsAppShell::Run() [nsAppShell.cpp:2382e5ee161cefc24d2ea9a5abad05e5ca4e9e67 : 639 + 0x7]
[task 2023-09-27T16:43:00.895Z] 16:43:00     INFO -      rbx = 0x00000000b5a71c01    rsi = 0x0000018db5a71ca0
[task 2023-09-27T16:43:00.895Z] 16:43:00     INFO -      rdi = 0x0000018dc3429dc0    rbp = 0x0002000100000000
[task 2023-09-27T16:43:00.895Z] 16:43:00     INFO -      rsp = 0x0000007567bfefe0    r12 = 0xaaaaaaaaaaaaaaaa
[task 2023-09-27T16:43:00.895Z] 16:43:00     INFO -      r13 = 0x00007ffb9b3af89a    r14 = 0x0000007567bff320
[task 2023-09-27T16:43:00.895Z] 16:43:00     INFO -      r15 = 0x0002002100000018    rip = 0x00007ffb96498b6a
[task 2023-09-27T16:43:00.895Z] 16:43:00     INFO -     Found by: call frame info
[task 2023-09-27T16:43:00.895Z] 16:43:00     INFO - 12  xul.dll!nsAppStartup::Run() [nsAppStartup.cpp:2382e5ee161cefc24d2ea9a5abad05e5ca4e9e67 : 295 + 0xc]
[task 2023-09-27T16:43:00.895Z] 16:43:00     INFO -      rbx = 0x0000000000000000    rsi = 0x0000018dc34fb820
[task 2023-09-27T16:43:00.895Z] 16:43:00     INFO -      rdi = 0x0000007567bff110    rbp = 0x0002000100000000
[task 2023-09-27T16:43:00.895Z] 16:43:00     INFO -      rsp = 0x0000007567bff050    r12 = 0xaaaaaaaaaaaaaaaa
[task 2023-09-27T16:43:00.895Z] 16:43:00     INFO -      r13 = 0x00007ffb9b3af89a    r14 = 0x0000007567bff320
[task 2023-09-27T16:43:00.895Z] 16:43:00     INFO -      r15 = 0x0002002100000018    rip = 0x00007ffb98491ca8
[task 2023-09-27T16:43:00.895Z] 16:43:00     INFO -     Found by: call frame info
[task 2023-09-27T16:43:00.895Z] 16:43:00     INFO - 13  xul.dll!XREMain::XRE_mainRun() [nsAppRunner.cpp:2382e5ee161cefc24d2ea9a5abad05e5ca4e9e67 : 5683 + 0xc]
[task 2023-09-27T16:43:00.895Z] 16:43:00     INFO -      rbx = 0x0000000000000000    rsi = 0x0000000080004005
[task 2023-09-27T16:43:00.895Z] 16:43:00     INFO -      rdi = 0x0000007567bff110    rbp = 0x0002000100000000
[task 2023-09-27T16:43:00.895Z] 16:43:00     INFO -      rsp = 0x0000007567bff0b0    r12 = 0xaaaaaaaaaaaaaaaa
[task 2023-09-27T16:43:00.895Z] 16:43:00     INFO -      r13 = 0x00007ffb9b3af89a    r14 = 0x0000007567bff320
[task 2023-09-27T16:43:00.896Z] 16:43:00     INFO -      r15 = 0x0002002100000018    rip = 0x00007ffb98634a12
[task 2023-09-27T16:43:00.896Z] 16:43:00     INFO -     Found by: call frame info
[task 2023-09-27T16:43:00.896Z] 16:43:00     INFO - 14  xul.dll!XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) [nsAppRunner.cpp:2382e5ee161cefc24d2ea9a5abad05e5ca4e9e67 : 5884 + 0x7]
[task 2023-09-27T16:43:00.896Z] 16:43:00     INFO -      rbx = 0x0000000000000000    rsi = 0x0000007567bff520
[task 2023-09-27T16:43:00.896Z] 16:43:00     INFO -      rdi = 0x0000000000000000    rbp = 0x0000000000000000
[task 2023-09-27T16:43:00.896Z] 16:43:00     INFO -      rsp = 0x0000007567bff420    r12 = 0xaaaaaaaaaaaaaaaa
[task 2023-09-27T16:43:00.896Z] 16:43:00     INFO -      r13 = 0x0000000000000005    r14 = 0x0000007567bff4a0
[task 2023-09-27T16:43:00.896Z] 16:43:00     INFO -      r15 = 0x0000000000000000    rip = 0x00007ffb98637a15
[task 2023-09-27T16:43:00.896Z] 16:43:00     INFO -     Found by: call frame info
[task 2023-09-27T16:43:00.896Z] 16:43:00     INFO - 15  xul.dll!XRE_main(int, char**, mozilla::BootstrapConfig const&) [nsAppRunner.cpp:2382e5ee161cefc24d2ea9a5abad05e5ca4e9e67 : 5940 + 0xf]
[task 2023-09-27T16:43:00.896Z] 16:43:00     INFO -      rbx = 0x0000000000000005    rsi = 0x0000007567bff660
[task 2023-09-27T16:43:00.896Z] 16:43:00     INFO -      rdi = 0x0000018db5a043d0    rbp = 0x00000000000000d3
[task 2023-09-27T16:43:00.896Z] 16:43:00     INFO -      rsp = 0x0000007567bff500    r12 = 0x0000018db57fcda0
[task 2023-09-27T16:43:00.896Z] 16:43:00     INFO -      r13 = 0x0000000000000005    r14 = 0x0000007567bff520
[task 2023-09-27T16:43:00.896Z] 16:43:00     INFO -      r15 = 0xaaaaaaaaaaaaaaaa    rip = 0x00007ffb986385a7
[task 2023-09-27T16:43:00.896Z] 16:43:00     INFO -     Found by: call frame info
[task 2023-09-27T16:43:00.896Z] 16:43:00     INFO - 16  firefox.exe!do_main(int, char**, char**) [nsBrowserApp.cpp:2382e5ee161cefc24d2ea9a5abad05e5ca4e9e67 : 196]
[task 2023-09-27T16:43:00.896Z] 16:43:00     INFO -     Found by: inlining
[task 2023-09-27T16:43:00.896Z] 16:43:00     INFO - 17  firefox.exe!NS_internal_main(int, char**, char**) [nsBrowserApp.cpp:2382e5ee161cefc24d2ea9a5abad05e5ca4e9e67 : 445 + 0x185]
[task 2023-09-27T16:43:00.896Z] 16:43:00     INFO -      rbx = 0x0000018db57fe0b0    rsi = 0x0000018db5a043d0
[task 2023-09-27T16:43:00.896Z] 16:43:00     INFO -      rdi = 0x0000000000000005    rbp = 0x00000000000000d3
[task 2023-09-27T16:43:00.896Z] 16:43:00     INFO -      rsp = 0x0000007567bff5e0    r12 = 0x0000018db57fcda0
[task 2023-09-27T16:43:00.896Z] 16:43:00     INFO -      r13 = 0x0000000000000005    r14 = 0x00007ff72329a5cf
[task 2023-09-27T16:43:00.896Z] 16:43:00     INFO -      r15 = 0xaaaaaaaaaaaaaaaa    rip = 0x00007ff7232017f8
[task 2023-09-27T16:43:00.896Z] 16:43:00     INFO -     Found by: call frame info
[task 2023-09-27T16:43:00.897Z] 16:43:00     INFO - 18  firefox.exe!wmain(int, wchar_t**) [nsWindowsWMain.cpp:2382e5ee161cefc24d2ea9a5abad05e5ca4e9e67 : 151 + 0x14]
[task 2023-09-27T16:43:00.897Z] 16:43:00     INFO -      rbx = 0x0000018db57fce8e    rsi = 0x0000018db5a04610
[task 2023-09-27T16:43:00.897Z] 16:43:00     INFO -      rdi = 0x0000018db5a043d0    rbp = 0x00000000000000d3
[task 2023-09-27T16:43:00.897Z] 16:43:00     INFO -      rsp = 0x0000007567bff7b0    r12 = 0x0000018db57fcda0
[task 2023-09-27T16:43:00.897Z] 16:43:00     INFO -      r13 = 0x0000000000000005    r14 = 0x0000000000000046
[task 2023-09-27T16:43:00.897Z] 16:43:00     INFO -      r15 = 0x0000018db5a07e40    rip = 0x00007ff723201227
[task 2023-09-27T16:43:00.897Z] 16:43:00     INFO -     Found by: call frame info
[task 2023-09-27T16:43:00.897Z] 16:43:00     INFO - 19  firefox.exe!invoke_main() [exe_common.inl : 90]
[task 2023-09-27T16:43:00.897Z] 16:43:00     INFO -     Found by: inlining
[task 2023-09-27T16:43:00.897Z] 16:43:00     INFO - 20  firefox.exe!__scrt_common_main_seh() [exe_common.inl : 288 + 0x21]
[task 2023-09-27T16:43:00.897Z] 16:43:00     INFO -      rbx = 0x0000018db57fcda0    rsi = 0x0000000000000000
[task 2023-09-27T16:43:00.897Z] 16:43:00     INFO -      rdi = 0x0000018db580c820    rbp = 0x0000000000000000
[task 2023-09-27T16:43:00.897Z] 16:43:00     INFO -      rsp = 0x0000007567bff870    r12 = 0x0000000000000000
[task 2023-09-27T16:43:00.897Z] 16:43:00     INFO -      r13 = 0x0000000000000000    r14 = 0x0000000000000000
[task 2023-09-27T16:43:00.897Z] 16:43:00     INFO -      r15 = 0x0000000000000000    rip = 0x00007ff723296c58
[task 2023-09-27T16:43:00.897Z] 16:43:00     INFO -     Found by: call frame info
[task 2023-09-27T16:43:00.897Z] 16:43:00     INFO - 21  kernel32.dll!BaseThreadInitThunk + 0x1c
[task 2023-09-27T16:43:00.897Z] 16:43:00     INFO -      rbx = 0x0000000000000000    rsi = 0x0000000000000000
[task 2023-09-27T16:43:00.897Z] 16:43:00     INFO -      rdi = 0x0000000000000000    rbp = 0x0000000000000000
[task 2023-09-27T16:43:00.897Z] 16:43:00     INFO -      rsp = 0x0000007567bff8b0    r12 = 0x0000000000000000
[task 2023-09-27T16:43:00.897Z] 16:43:00     INFO -      r13 = 0x0000000000000000    r14 = 0x0000000000000000
[task 2023-09-27T16:43:00.897Z] 16:43:00     INFO -      r15 = 0x0000000000000000    rip = 0x00007ffbd708269d
[task 2023-09-27T16:43:00.898Z] 16:43:00     INFO -     Found by: call frame info
[task 2023-09-27T16:43:00.898Z] 16:43:00     INFO - 22  ntdll.dll!RtlUserThreadStart + 0x27
[task 2023-09-27T16:43:00.898Z] 16:43:00     INFO -      rbx = 0x0000000000000000    rsi = 0x0000000000000000
[task 2023-09-27T16:43:00.898Z] 16:43:00     INFO -      rdi = 0x0000000000000000    rbp = 0x0000000000000000
[task 2023-09-27T16:43:00.898Z] 16:43:00     INFO -      rsp = 0x0000007567bff8e0    r12 = 0x0000000000000000
[task 2023-09-27T16:43:00.898Z] 16:43:00     INFO -      r13 = 0x0000000000000000    r14 = 0x0000000000000000
[task 2023-09-27T16:43:00.898Z] 16:43:00     INFO -      r15 = 0x0000000000000000    rip = 0x00007ffbd8aca9f8
[task 2023-09-27T16:43:00.898Z] 16:43:00     INFO -     Found by: call frame info
[task 2023-09-27T16:43:00.898Z] 16:43:00     INFO - Thread 1 BrokerEvent

:pbz, since you are the author of the regressor, bug 1853698, could you take a look?

For more information, please visit BugBot documentation.

Flags: needinfo?(pbz)

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

Not reproducible locally on macOS artifact-opt. Could have changed the timing slightly so this times out on debug. Testing with requestLongerTimeout.

This failed once, let's see if it even fails again.

Flags: needinfo?(pbz)
You need to log in before you can comment on or make changes to this bug.