Intermittent browser/components/sessionstore/test/browser_tab_groups_undo.js | single tracking bug
Categories
(Firefox :: Session Restore, defect, P5)
Tracking
()
Tracking | Status | |
---|---|---|
firefox-esr128 | --- | unaffected |
firefox134 | --- | unaffected |
firefox135 | --- | unaffected |
firefox136 | --- | affected |
firefox137 | --- | affected |
People
(Reporter: intermittent-bug-filer, Unassigned, NeedInfo)
References
(Regression)
Details
(Keywords: intermittent-failure, regression, test-verify-fail)
Filed by: amarc [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=491151152&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/AZ-5r5pwQdqt-BkVHwhA4A/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/AZ-5r5pwQdqt-BkVHwhA4A/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1
[task 2025-01-21T23:53:25.592Z] 23:53:25 INFO - TEST-START | browser/components/sessionstore/test/browser_tab_groups_undo.js
[task 2025-01-21T23:53:25.772Z] 23:53:25 INFO - GECKO(9360) | console.log: adding to tab group
[task 2025-01-21T23:53:25.774Z] 23:53:25 INFO - GECKO(9360) | console.log: waiting for tab group removed
[task 2025-01-21T23:53:25.781Z] 23:53:25 INFO - GECKO(9360) | console.log: flushing window
[task 2025-01-21T23:53:25.783Z] 23:53:25 INFO - GECKO(9360) | console.log: waiting for getLastClosedTabGroupId
[task 2025-01-21T23:53:25.818Z] 23:53:25 INFO - TEST-INFO | started process screenshot
[task 2025-01-21T23:53:25.971Z] 23:53:25 INFO - TEST-INFO | screenshot: exit 0
[task 2025-01-21T23:53:25.971Z] 23:53:25 INFO - Buffered messages logged at 23:53:25
[task 2025-01-21T23:53:25.972Z] 23:53:25 INFO - Entering test bound test_tabGroupsUndo
[task 2025-01-21T23:53:25.973Z] 23:53:25 INFO - TEST-PASS | browser/components/sessionstore/test/browser_tab_groups_undo.js | Should start without a last closed tab group id - null == null -
[task 2025-01-21T23:53:25.973Z] 23:53:25 INFO - TEST-PASS | browser/components/sessionstore/test/browser_tab_groups_undo.js | Should start with no tab groups - 0 == 0 -
[task 2025-01-21T23:53:25.974Z] 23:53:25 INFO - TEST-PASS | browser/components/sessionstore/test/browser_tab_groups_undo.js | SessionStore saves the ID of the last closed tab group - "1737503605771-85" == "1737503605771-85" -
[task 2025-01-21T23:53:25.974Z] 23:53:25 INFO - TEST-PASS | browser/components/sessionstore/test/browser_tab_groups_undo.js | Tab group was restored - 1 == 1 -
[task 2025-01-21T23:53:25.976Z] 23:53:25 INFO - TEST-PASS | browser/components/sessionstore/test/browser_tab_groups_undo.js | SessionStore reset the ID of the last closed tab group - null == null -
[task 2025-01-21T23:53:25.976Z] 23:53:25 INFO - TEST-PASS | browser/components/sessionstore/test/browser_tab_groups_undo.js | SessionStore last closed group ID is null after closing a tab within a group - null == null -
[task 2025-01-21T23:53:25.977Z] 23:53:25 INFO - Buffered messages finished
[task 2025-01-21T23:53:25.979Z] 23:53:25 INFO - TEST-UNEXPECTED-FAIL | browser/components/sessionstore/test/browser_tab_groups_undo.js | Uncaught exception in test bound test_tabGroupsUndo - at chrome://global/content/elements/browser-custom-element.js:857 - TypeError: can't access property "userTyped", this.urlbarChangeTracker is undefined
[task 2025-01-21T23:53:25.979Z] 23:53:25 INFO - Stack trace:
[task 2025-01-21T23:53:25.979Z] 23:53:25 INFO - set userTypedValue@chrome://global/content/elements/browser-custom-element.js:857:7
[task 2025-01-21T23:53:25.979Z] 23:53:25 INFO - _kickOffBrowserLoad@chrome://browser/content/tabbrowser/tabbrowser.js:3303:11
[task 2025-01-21T23:53:25.979Z] 23:53:25 INFO - addTab@chrome://browser/content/tabbrowser/tabbrowser.js:2831:14
[task 2025-01-21T23:53:25.979Z] 23:53:25 INFO - addTab@resource://testing-common/BrowserTestUtils.sys.mjs:2676:23
[task 2025-01-21T23:53:25.979Z] 23:53:25 INFO - test_tabGroupsUndo@chrome://mochitests/content/browser/browser/components/sessionstore/test/browser_tab_groups_undo.js:74:39
[task 2025-01-21T23:53:25.980Z] 23:53:25 INFO - async*handleTask@chrome://mochikit/content/browser-test.js:1147:26
[task 2025-01-21T23:53:25.980Z] 23:53:25 INFO - _runTaskBasedTest@chrome://mochikit/content/browser-test.js:1219:18
[task 2025-01-21T23:53:25.980Z] 23:53:25 INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1360:14
[task 2025-01-21T23:53:25.980Z] 23:53:25 INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1130:14
[task 2025-01-21T23:53:25.980Z] 23:53:25 INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13
[task 2025-01-21T23:53:25.980Z] 23:53:25 INFO - Leaving test bound test_tabGroupsUndo
[task 2025-01-21T23:53:25.982Z] 23:53:25 INFO - Console message: [JavaScript Error: "Cannot attach ID to a tab in a closed window." {file: "chrome://browser/content/parent/ext-browser.js" line: 343}]
[task 2025-01-21T23:53:25.982Z] 23:53:25 INFO - setId@chrome://browser/content/parent/ext-browser.js:343:13
[task 2025-01-21T23:53:25.982Z] 23:53:25 INFO - getId@chrome://browser/content/parent/ext-browser.js:319:10
[task 2025-01-21T23:53:25.982Z] 23:53:25 INFO - emitRemoved@chrome://browser/content/parent/ext-browser.js:683:22
[task 2025-01-21T23:53:25.982Z] 23:53:25 INFO - _handleWindowClose@chrome://browser/content/parent/ext-browser.js:610:14
[task 2025-01-21T23:53:25.982Z] 23:53:25 INFO - observe@chrome://extensions/content/parent/ext-tabs-base.js:1761:19
[task 2025-01-21T23:53:25.982Z] 23:53:25 INFO -
[task 2025-01-21T23:53:26.102Z] 23:53:26 INFO - GECKO(9360) | 1737503606099 Marionette TRACE Received observer notification browser-delayed-startup-finished
[task 2025-01-21T23:55:29.947Z] 23:55:29 INFO - GECKO(9360) | console.warn: WindowsJumpLists: "Failed to fetch favicon for " "https://example.com/" ({})
[task 2025-01-21T23:57:29.952Z] 23:57:29 INFO - GECKO(9360) | console.warn: WindowsJumpLists: "Failed to fetch favicon for " "https://example.com/" ({})
[task 2025-01-21T23:59:19.842Z] 23:59:19 INFO - console.trace: Places - FrecencyRecalculator: "Initializing Frecency Recalculator"
[task 2025-01-21T23:59:19.843Z] 23:59:19 INFO - resource://gre/modules/PlacesFrecencyRecalculator.sys.mjs 118 PlacesFrecencyRecalculator
[task 2025-01-21T23:59:19.844Z] 23:59:19 INFO - D:\task_173750169683613\build\tests\mochitest\server.js 178 runServer
[task 2025-01-21T23:59:19.844Z] 23:59:19 INFO - D:\task_173750169683613\build\tests\mochitest\server.js 71
[task 2025-01-21T23:59:19.847Z] 23:59:19 INFO - console.trace: Places - FrecencyRecalculator: "Arm frecency recalculation"
[task 2025-01-21T23:59:19.848Z] 23:59:19 INFO - resource://gre/modules/PlacesFrecencyRecalculator.sys.mjs 380 maybeStartFrecencyRecalculation
[task 2025-01-21T23:59:19.849Z] 23:59:19 INFO - resource://gre/modules/PlacesFrecencyRecalculator.sys.mjs 158 PlacesFrecencyRecalculator
[task 2025-01-21T23:59:19.849Z] 23:59:19 INFO - D:\task_173750169683613\build\tests\mochitest\server.js 178 runServer
[task 2025-01-21T23:59:19.849Z] 23:59:19 INFO - D:\task_173750169683613\build\tests\mochitest\server.js 71
[task 2025-01-21T23:59:19.850Z] 23:59:19 INFO - console.trace: Places - FrecencyRecalculator: "Got places-init-complete topic"
[task 2025-01-21T23:59:19.850Z] 23:59:19 INFO - resource://gre/modules/PlacesFrecencyRecalculator.sys.mjs 436 observe
[task 2025-01-21T23:59:19.850Z] 23:59:19 INFO - D:\task_173750169683613\build\tests\mochitest\server.js 178 runServer
[task 2025-01-21T23:59:19.850Z] 23:59:19 INFO - D:\task_173750169683613\build\tests\mochitest\server.js 71
[task 2025-01-21T23:59:19.851Z] 23:59:19 INFO - console.error: (new TypeError("connection not specified or invalid.", "resource://gre/modules/Sqlite.sys.mjs", 1590))
[task 2025-01-21T23:59:19.851Z] 23:59:19 INFO - console.error: (new TypeError("can't access property \"executeBeforeShutdown\", db is undefined", "resource://gre/modules/PlacesUtils.sys.mjs", 1510))
[task 2025-01-22T00:01:19.864Z] 00:01:19 INFO - console.trace: Places - FrecencyRecalculator: "Recalculate 50 frecency values"
[task 2025-01-22T00:01:19.865Z] 00:01:19 INFO - resource://gre/modules/PlacesFrecencyRecalculator.sys.mjs 254 recalculateSomeFrecencies
[task 2025-01-22T00:01:19.865Z] 00:01:19 INFO - resource://gre/modules/PlacesFrecencyRecalculator.sys.mjs 189 #taskFn
[task 2025-01-22T00:01:19.865Z] 00:01:19 INFO - resource://gre/modules/DeferredTask.sys.mjs 341 _runTask
[task 2025-01-22T00:01:19.866Z] 00:01:19 INFO - resource://gre/modules/DeferredTask.sys.mjs 312 _timerCallback/<
[task 2025-01-22T00:01:19.866Z] 00:01:19 INFO - resource://gre/modules/DeferredTask.sys.mjs 331 _timerCallback
[task 2025-01-22T00:01:19.866Z] 00:01:19 INFO - resource://gre/modules/DeferredTask.sys.mjs 179 _startTimer/callback/<
[task 2025-01-22T00:01:19.867Z] 00:01:19 INFO - D:\task_173750169683613\build\tests\mochitest\server.js 178 runServer
[task 2025-01-22T00:01:19.867Z] 00:01:19 INFO - D:\task_173750169683613\build\tests\mochitest\server.js 71
[task 2025-01-22T00:01:19.868Z] 00:01:19 INFO - console.error: (new TypeError("can't access property \"executeTransaction\", db is undefined", "resource://gre/modules/PlacesFrecencyRecalculator.sys.mjs", 260))
[task 2025-01-22T00:01:19.868Z] 00:01:19 INFO - console.error: Places - FrecencyRecalculator: (new TypeError("can't access property \"executeTransaction\", db is undefined", "resource://gre/modules/PlacesFrecencyRecalculator.sys.mjs", 260))
[task 2025-01-22T00:03:39.956Z] 00:03:39 INFO - TEST-UNEXPECTED-TIMEOUT | browser/components/sessionstore/test/browser_tab_groups_undo.js | application timed out after 370 seconds with no output
[task 2025-01-22T00:03:39.956Z] 00:03:39 INFO - TEST-INFO took 613410ms
[task 2025-01-22T00:03:39.957Z] 00:03:39 INFO - Buffered messages finished
[task 2025-01-22T00:03:39.957Z] 00:03:39 WARNING - Force-terminating active process(es).
[task 2025-01-22T00:03:39.958Z] 00:03:39 INFO - profiler Not sending a signal to start the profiler - not on MacOS or Linux. See Bug 1823370.
[task 2025-01-22T00:03:39.958Z] 00:03:39 INFO - Determining child pids from psutil...
[task 2025-01-22T00:03:39.961Z] 00:03:39 INFO - [9196]
[task 2025-01-22T00:03:39.962Z] 00:03:39 INFO - ==> process 9196 launched child process 8992 ("D:\task_173750169683613\build\application\firefox\firefox.exe" -contentproc -parentBuildID 20250121230943 -prefsHandle 2272 -prefsLen 26467 -prefMapHandle 2276 -prefMapSize 282560 -ipcHandle 2340 -initialChannelId {31b03f8e-61cc-449f-a237-3fe403b6eae9} -parentPid 9196 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9196" -appDir "D:\task_173750169683613\build\application\firefox\browser" - 1 gpu)
<...>
[task 2025-01-22T00:03:40.888Z] 00:03:40 INFO - zombiecheck | Checking for orphan process with PID: 6520
[task 2025-01-22T00:03:40.889Z] 00:03:40 WARNING - mozcrash Found 12 dump files -- limited to 10!
[task 2025-01-22T00:03:40.889Z] 00:03:40 INFO - mozcrash Downloading symbols from: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/AlekIzjiRAeJUw0xKe2lOA/artifacts/public/build/target.crashreporter-symbols.zip
[task 2025-01-22T00:03:57.812Z] 00:03:57 INFO - mozcrash Copy/paste: D:/task_173750169683613/fetches\minidump-stackwalk\minidump-stackwalk.exe --symbols-url=https://symbols.mozilla.org/ --cyborg=C:\Users\task_173750169683613\AppData\Local\Temp\tmpos0d25ul\fd91c925-3274-4a9b-9ed7-577fec157ce2.trace C:\Users\task_173750169683613\AppData\Local\Temp\tmp6cyco7u7.mozrunner\minidumps\fd91c925-3274-4a9b-9ed7-577fec157ce2.dmp C:\Users\task_173750169683613\AppData\Local\Temp\tmpcmcge6ef
[task 2025-01-22T00:04:06.178Z] 00:04:06 INFO - mozcrash Saved minidump as D:\task_173750169683613\build\blobber_upload_dir\fd91c925-3274-4a9b-9ed7-577fec157ce2.dmp
[task 2025-01-22T00:04:06.397Z] 00:04:06 INFO - PROCESS-CRASH | application crashed [None] | browser/components/sessionstore/test/browser_tab_groups_undo.js
[task 2025-01-22T00:04:06.397Z] 00:04:06 INFO - Process type: unknown
[task 2025-01-22T00:04:06.397Z] 00:04:06 INFO - Process pid: 9540
[task 2025-01-22T00:04:06.401Z] 00:04:06 INFO - Crash dump filename: C:\Users\task_173750169683613\AppData\Local\Temp\tmp6cyco7u7.mozrunner\minidumps\fd91c925-3274-4a9b-9ed7-577fec157ce2.dmp
[task 2025-01-22T00:04:06.401Z] 00:04:06 INFO - Operating system: Windows NT
[task 2025-01-22T00:04:06.401Z] 00:04:06 INFO - 10.0.22621
[task 2025-01-22T00:04:06.401Z] 00:04:06 INFO - CPU: amd64
[task 2025-01-22T00:04:06.401Z] 00:04:06 INFO - family 6 model 106 stepping 6
[task 2025-01-22T00:04:06.401Z] 00:04:06 INFO - 8 CPUs
[task 2025-01-22T00:04:06.401Z] 00:04:06 INFO -
[task 2025-01-22T00:04:06.401Z] 00:04:06 INFO - No crash
[task 2025-01-22T00:04:06.401Z] 00:04:06 INFO - Process uptime: 617 seconds
[task 2025-01-22T00:04:06.402Z] 00:04:06 INFO -
[task 2025-01-22T00:04:06.402Z] 00:04:06 INFO - Thread 0 MainThread - tid: 1620
[task 2025-01-22T00:04:06.402Z] 00:04:06 INFO - 0 ntdll.dll!ZwWaitForAlertByThreadId + 0x14
[task 2025-01-22T00:04:06.402Z] 00:04:06 INFO - rax = 0x00000000000001e0 rdx = 0x0000000000000000
[task 2025-01-22T00:04:06.402Z] 00:04:06 INFO - rcx = 0x0000021877480080 rbx = 0x0000000000000000
[task 2025-01-22T00:04:06.402Z] 00:04:06 INFO - rsi = 0x0000000000000000 rdi = 0x0000021877480080
[task 2025-01-22T00:04:06.402Z] 00:04:06 INFO - rbp = 0x000000206f3ff220 rsp = 0x000000206f3ff1c8
[task 2025-01-22T00:04:06.402Z] 00:04:06 INFO - r8 = 0x00000000000003c0 r9 = 0x0000000000000040
[task 2025-01-22T00:04:06.402Z] 00:04:06 INFO - r10 = 0x00000fff57c47c1e r11 = 0x1001004041104040
[task 2025-01-22T00:04:06.402Z] 00:04:06 INFO - r12 = 0x0000000000000000 r13 = 0x0000000000000000
[task 2025-01-22T00:04:06.402Z] 00:04:06 INFO - r14 = 0x0000000000000102 r15 = 0x0000021877480100
[task 2025-01-22T00:04:06.402Z] 00:04:06 INFO - rip = 0x00007ffb00393f94
[task 2025-01-22T00:04:06.403Z] 00:04:06 INFO - Found by: given as instruction pointer in context
[task 2025-01-22T00:04:06.403Z] 00:04:06 INFO - 1 ntdll.dll!RtlSleepConditionVariableSRW + 0x13a
[task 2025-01-22T00:04:06.403Z] 00:04:06 INFO - rbx = 0x0000000000000000 rbp = 0x000000206f3ff220
[task 2025-01-22T00:04:06.403Z] 00:04:06 INFO - rsp = 0x000000206f3ff1d0 r12 = 0x0000000000000000
[task 2025-01-22T00:04:06.403Z] 00:04:06 INFO - r13 = 0x0000000000000000 r14 = 0x0000000000000102
[task 2025-01-22T00:04:06.403Z] 00:04:06 INFO - r15 = 0x0000021877480100 rip = 0x00007ffb003597db
[task 2025-01-22T00:04:06.403Z] 00:04:06 INFO - Found by: call frame info
[task 2025-01-22T00:04:06.403Z] 00:04:06 INFO - 2 KERNELBASE.dll!SleepConditionVariableSRW + 0x28
[task 2025-01-22T00:04:06.403Z] 00:04:06 INFO - rbx = 0x0000000080460001 rsi = 0x0000021877480100
[task 2025-01-22T00:04:06.403Z] 00:04:06 INFO - rdi = 0x0000021877480100 rbp = 0x0000000000000001
[task 2025-01-22T00:04:06.403Z] 00:04:06 INFO - rsp = 0x000000206f3ff250 r12 = 0x000000206f3ff2e8
[task 2025-01-22T00:04:06.403Z] 00:04:06 INFO - r13 = 0x0000021877450040 r14 = 0x00007ffad7338f80
[task 2025-01-22T00:04:06.403Z] 00:04:06 INFO - r15 = 0x00007ffac4bab5b6 rip = 0x00007ffafdb2f2e9
[task 2025-01-22T00:04:06.403Z] 00:04:06 INFO - Found by: call frame info
[task 2025-01-22T00:04:06.403Z] 00:04:06 INFO - 3 mozglue.dll!mozilla::detail::ConditionVariableImpl::wait(mozilla::detail::MutexImpl&) [ConditionVariable_windows.cpp:9d45c0c056cdeabf6790d37dfb3a8bf6f3d24256 : 50 + 0x14]
[task 2025-01-22T00:04:06.404Z] 00:04:06 INFO - rbx = 0x0000000080460001 rbp = 0x0000000000000001
[task 2025-01-22T00:04:06.404Z] 00:04:06 INFO - rsp = 0x000000206f3ff290 r12 = 0x000000206f3ff2e8
[task 2025-01-22T00:04:06.404Z] 00:04:06 INFO - r13 = 0x0000021877450040 r14 = 0x00007ffad7338f80
[task 2025-01-22T00:04:06.404Z] 00:04:06 INFO - r15 = 0x00007ffac4bab5b6 rip = 0x00007ffad7338fa5
[task 2025-01-22T00:04:06.404Z] 00:04:06 INFO - Found by: call frame info
[task 2025-01-22T00:04:06.404Z] 00:04:06 INFO - 4 xul.dll!mozilla::OffTheBooksCondVar::Wait() [CondVar.h:9d45c0c056cdeabf6790d37dfb3a8bf6f3d24256 : 58]
[task 2025-01-22T00:04:06.404Z] 00:04:06 INFO - Found by: inlining
[task 2025-01-22T00:04:06.404Z] 00:04:06 INFO - 5 xul.dll!mozilla::TaskController::GetRunnableForMTTask(bool) [TaskController.cpp:9d45c0c056cdeabf6790d37dfb3a8bf6f3d24256 : 765 + 0xe]
[task 2025-01-22T00:04:06.404Z] 00:04:06 INFO - rbx = 0x0000000080460001 rsi = 0x0000021877480080
[task 2025-01-22T00:04:06.404Z] 00:04:06 INFO - rbp = 0x0000000000000001 rsp = 0x000000206f3ff2c0
[task 2025-01-22T00:04:06.404Z] 00:04:06 INFO - r12 = 0x000000206f3ff2e8 r13 = 0x0000021877450040
[task 2025-01-22T00:04:06.404Z] 00:04:06 INFO - r14 = 0x00007ffad7338f80 r15 = 0x00007ffac4bab5b6
[task 2025-01-22T00:04:06.404Z] 00:04:06 INFO - rip = 0x00007ffabd290c3b
[task 2025-01-22T00:04:06.404Z] 00:04:06 INFO - Found by: call frame info
[task 2025-01-22T00:04:06.404Z] 00:04:06 INFO - 6 xul.dll!nsThread::ProcessNextEvent(bool, bool*) [nsThread.cpp:9d45c0c056cdeabf6790d37dfb3a8bf6f3d24256 : 1118 + 0x7]
[task 2025-01-22T00:04:06.404Z] 00:04:06 INFO - rbx = 0x0000000000000000 rsi = 0x00000218774830a0
[task 2025-01-22T00:04:06.405Z] 00:04:06 INFO - rdi = 0x000000206f3ff527 rbp = 0x0000000000000001
[task 2025-01-22T00:04:06.405Z] 00:04:06 INFO - rsp = 0x000000206f3ff340 r12 = 0x0000000080460001
[task 2025-01-22T00:04:06.405Z] 00:04:06 INFO - r13 = 0x0000021877483162 r14 = 0x00000218774830b8
[task 2025-01-22T00:04:06.405Z] 00:04:06 INFO - r15 = 0x0000000000000001 rip = 0x00007ffabd2af60a
[task 2025-01-22T00:04:06.405Z] 00:04:06 INFO - Found by: call frame info
[task 2025-01-22T00:04:06.405Z] 00:04:06 INFO - 7 xul.dll!NS_ProcessNextEvent(nsIThread*, bool) [nsThreadUtils.cpp:9d45c0c056cdeabf6790d37dfb3a8bf6f3d24256 : 480 + 0x14]
[task 2025-01-22T00:04:06.405Z] 00:04:06 INFO - rbx = 0x00000218774830a0 rsi = 0x0000021877411380
[task 2025-01-22T00:04:06.405Z] 00:04:06 INFO - rdi = 0x000000206f3ff850 rbp = 0x0000000000000000
[task 2025-01-22T00:04:06.405Z] 00:04:06 INFO - rsp = 0x000000206f3ff500 r12 = 0x00000218770aafe0
[task 2025-01-22T00:04:06.405Z] 00:04:06 INFO - r13 = 0x000000000000001f r14 = 0x00000218774113a0
[task 2025-01-22T00:04:06.405Z] 00:04:06 INFO - r15 = 0x000002187740b0c0 rip = 0x00007ffabd2b4867
[task 2025-01-22T00:04:06.405Z] 00:04:06 INFO - Found by: call frame info
[task 2025-01-22T00:04:06.405Z] 00:04:06 INFO - 8 xul.dll!mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate*) [MessagePump.cpp:9d45c0c056cdeabf6790d37dfb3a8bf6f3d24256 : 107 + 0x9]
[task 2025-01-22T00:04:06.405Z] 00:04:06 INFO - rbx = 0x00000218774830a0 rbp = 0x0000000000000000
[task 2025-01-22T00:04:06.405Z] 00:04:06 INFO - rsp = 0x000000206f3ff540 r12 = 0x00000218770aafe0
[task 2025-01-22T00:04:06.405Z] 00:04:06 INFO - r13 = 0x000000000000001f r14 = 0x00000218774113a0
[task 2025-01-22T00:04:06.405Z] 00:04:06 INFO - r15 = 0x000002187740b0c0 rip = 0x00007ffabda020a1
[task 2025-01-22T00:04:06.406Z] 00:04:06 INFO - Found by: call frame info
[task 2025-01-22T00:04:06.406Z] 00:04:06 INFO - 9 xul.dll!MessageLoop::RunInternal() [message_loop.cc:9d45c0c056cdeabf6790d37dfb3a8bf6f3d24256 : 369]
[task 2025-01-22T00:04:06.406Z] 00:04:06 INFO - Found by: inlining
[task 2025-01-22T00:04:06.406Z] 00:04:06 INFO - 10 xul.dll!MessageLoop::RunHandler() [message_loop.cc:9d45c0c056cdeabf6790d37dfb3a8bf6f3d24256 : 362 + 0x15]
[task 2025-01-22T00:04:06.406Z] 00:04:06 INFO - rbx = 0x0000000077465001 rsi = 0x000000206f3ff850
[task 2025-01-22T00:04:06.406Z] 00:04:06 INFO - rdi = 0x00000218774830a0 rbp = 0x000000206f3ff5c0
[task 2025-01-22T00:04:06.406Z] 00:04:06 INFO - rsp = 0x000000206f3ff5a0 r12 = 0x00000218770aafe0
[task 2025-01-22T00:04:06.406Z] 00:04:06 INFO - r13 = 0x000000000000001f r14 = 0x000002187746a100
[task 2025-01-22T00:04:06.406Z] 00:04:06 INFO - r15 = 0x000002187740b0c0 rip = 0x00007ffabd9b7ae0
[task 2025-01-22T00:04:06.406Z] 00:04:06 INFO - Found by: call frame info
[task 2025-01-22T00:04:06.406Z] 00:04:06 INFO - 11 xul.dll!MessageLoop::Run() [message_loop.cc:9d45c0c056cdeabf6790d37dfb3a8bf6f3d24256 : 344 + 0x4]
[task 2025-01-22T00:04:06.406Z] 00:04:06 INFO - rbx = 0x0000000077465001 rsi = 0x000002187c1df040
[task 2025-01-22T00:04:06.406Z] 00:04:06 INFO - rdi = 0x00000218774830a0 rbp = 0x000000206f3ff750
[task 2025-01-22T00:04:06.406Z] 00:04:06 INFO - rsp = 0x000000206f3ff5f0 r12 = 0x00000218770aafe0
[task 2025-01-22T00:04:06.406Z] 00:04:06 INFO - r13 = 0x000000000000001f r14 = 0x000002187746a100
[task 2025-01-22T00:04:06.407Z] 00:04:06 INFO - r15 = 0x000002187740b0c0 rip = 0x00007ffabd9b7a57
[task 2025-01-22T00:04:06.407Z] 00:04:06 INFO - Found by: call frame info
[task 2025-01-22T00:04:06.407Z] 00:04:06 INFO - 12 xul.dll!nsBaseAppShell::Run() [nsBaseAppShell.cpp:9d45c0c056cdeabf6790d37dfb3a8bf6f3d24256 : 148 + 0xc]
[task 2025-01-22T00:04:06.407Z] 00:04:06 INFO - rbx = 0x0000000077465001 rbp = 0x000000206f3ff750
[task 2025-01-22T00:04:06.407Z] 00:04:06 INFO - rsp = 0x000000206f3ff640 r12 = 0x00000218770aafe0
[task 2025-01-22T00:04:06.407Z] 00:04:06 INFO - r13 = 0x000000000000001f r14 = 0x000002187746a100
[task 2025-01-22T00:04:06.407Z] 00:04:06 INFO - r15 = 0x000002187740b0c0 rip = 0x00007ffac0a531f8
[task 2025-01-22T00:04:06.407Z] 00:04:06 INFO - Found by: call frame info
[task 2025-01-22T00:04:06.407Z] 00:04:06 INFO - 13 xul.dll!nsAppShell::Run() [nsAppShell.cpp:9d45c0c056cdeabf6790d37dfb3a8bf6f3d24256 : 679 + 0x7]
[task 2025-01-22T00:04:06.407Z] 00:04:06 INFO - rbx = 0x0000000077465001 rsi = 0x000002187c1df040
[task 2025-01-22T00:04:06.407Z] 00:04:06 INFO - rdi = 0x000002187c1df040 rbp = 0x000000206f3ff750
[task 2025-01-22T00:04:06.407Z] 00:04:06 INFO - rsp = 0x000000206f3ff680 r12 = 0x00000218770aafe0
[task 2025-01-22T00:04:06.407Z] 00:04:06 INFO - r13 = 0x000000000000001f r14 = 0x000002187746a100
[task 2025-01-22T00:04:06.407Z] 00:04:06 INFO - r15 = 0x000002187740b0c0 rip = 0x00007ffac0b0c884
[task 2025-01-22T00:04:06.407Z] 00:04:06 INFO - Found by: call frame info
[task 2025-01-22T00:04:06.407Z] 00:04:06 INFO - 14 xul.dll!XRE_RunAppShell() [nsEmbedFunctions.cpp:9d45c0c056cdeabf6790d37dfb3a8bf6f3d24256 : 646 + 0xf]
[task 2025-01-22T00:04:06.407Z] 00:04:06 INFO - rbx = 0x0000000077465001 rsi = 0x000000206f3ff850
[task 2025-01-22T00:04:06.407Z] 00:04:06 INFO - rdi = 0x000002187c1df040 rbp = 0x000000206f3ff750
[task 2025-01-22T00:04:06.408Z] 00:04:06 INFO - rsp = 0x000000206f3ff6e0 r12 = 0x00000218770aafe0
[task 2025-01-22T00:04:06.408Z] 00:04:06 INFO - r13 = 0x000000000000001f r14 = 0x000002187746a100
[task 2025-01-22T00:04:06.408Z] 00:04:06 INFO - r15 = 0x000002187740b0c0 rip = 0x00007ffac15c0695
[task 2025-01-22T00:04:06.408Z] 00:04:06 INFO - Found by: call frame info
[task 2025-01-22T00:04:06.408Z] 00:04:06 INFO - 15 xul.dll!MessageLoop::RunInternal() [message_loop.cc:9d45c0c056cdeabf6790d37dfb3a8bf6f3d24256 : 369]
[task 2025-01-22T00:04:06.408Z] 00:04:06 INFO - Found by: inlining
[task 2025-01-22T00:04:06.408Z] 00:04:06 INFO - 16 xul.dll!MessageLoop::RunHandler() [message_loop.cc:9d45c0c056cdeabf6790d37dfb3a8bf6f3d24256 : 362 + 0x15]
[task 2025-01-22T00:04:06.408Z] 00:04:06 INFO - rbx = 0x0000000077465001 rsi = 0x000000206f3ff850
[task 2025-01-22T00:04:06.408Z] 00:04:06 INFO - rdi = 0x000002187c183c90 rbp = 0x000000206f3ff750
[task 2025-01-22T00:04:06.408Z] 00:04:06 INFO - rsp = 0x000000206f3ff730 r12 = 0x00000218770aafe0
[task 2025-01-22T00:04:06.408Z] 00:04:06 INFO - r13 = 0x000000000000001f r14 = 0x000002187746a100
[task 2025-01-22T00:04:06.408Z] 00:04:06 INFO - r15 = 0x000002187740b0c0 rip = 0x00007ffabd9b7ae0
[task 2025-01-22T00:04:06.408Z] 00:04:06 INFO - Found by: call frame info
[task 2025-01-22T00:04:06.408Z] 00:04:06 INFO - 17 xul.dll!MessageLoop::Run() [message_loop.cc:9d45c0c056cdeabf6790d37dfb3a8bf6f3d24256 : 344 + 0x4]
[task 2025-01-22T00:04:06.408Z] 00:04:06 INFO - rbx = 0x0000000077465001 rsi = 0x00007ff7866d5d50
[task 2025-01-22T00:04:06.408Z] 00:04:06 INFO - rdi = 0x000002187c183c90 rbp = 0x0000000080004005
[task 2025-01-22T00:04:06.408Z] 00:04:06 INFO - rsp = 0x000000206f3ff780 r12 = 0x00000218770aafe0
[task 2025-01-22T00:04:06.408Z] 00:04:06 INFO - r13 = 0x000000000000001f r14 = 0x000002187746a100
[task 2025-01-22T00:04:06.409Z] 00:04:06 INFO - r15 = 0x000002187740b0c0 rip = 0x00007ffabd9b7a57
[task 2025-01-22T00:04:06.409Z] 00:04:06 INFO - Found by: call frame info
[task 2025-01-22T00:04:06.409Z] 00:04:06 INFO - 18 xul.dll!XRE_InitChildProcess(int, char**, XREChildData const*) [nsEmbedFunctions.cpp:9d45c0c056cdeabf6790d37dfb3a8bf6f3d24256 : 584 + 0xc]
[task 2025-01-22T00:04:06.409Z] 00:04:06 INFO - rbx = 0x0000000077465001 rbp = 0x0000000080004005
[task 2025-01-22T00:04:06.409Z] 00:04:06 INFO - rsp = 0x000000206f3ff7d0 r12 = 0x00000218770aafe0
[task 2025-01-22T00:04:06.409Z] 00:04:06 INFO - r13 = 0x000000000000001f r14 = 0x000002187746a100
[task 2025-01-22T00:04:06.409Z] 00:04:06 INFO - r15 = 0x000002187740b0c0 rip = 0x00007ffac15bfc91
[task 2025-01-22T00:04:06.409Z] 00:04:06 INFO - Found by: call frame info
[task 2025-01-22T00:04:06.410Z] 00:04:06 INFO - 19 firefox.exe!NS_internal_main(int, char**, char**) [nsBrowserApp.cpp:9d45c0c056cdeabf6790d37dfb3a8bf6f3d24256 : 397 + 0x16]
[task 2025-01-22T00:04:06.410Z] 00:04:06 INFO - rbx = 0x00000218770a9c00 rsi = 0x0000021877404100
[task 2025-01-22T00:04:06.410Z] 00:04:06 INFO - rdi = 0x000000000000001c rbp = 0x000000000000000a
[task 2025-01-22T00:04:06.410Z] 00:04:06 INFO - rsp = 0x000000206f3ffa50 r12 = 0x00000218770aafe0
[task 2025-01-22T00:04:06.410Z] 00:04:06 INFO - r13 = 0x000000000000001f r14 = 0x00007ffad738c668
[task 2025-01-22T00:04:06.410Z] 00:04:06 INFO - r15 = 0x000002187740b0c0 rip = 0x00007ff7866b17b7
[task 2025-01-22T00:04:06.410Z] 00:04:06 INFO - Found by: call frame info
[task 2025-01-22T00:04:06.410Z] 00:04:06 INFO - 20 firefox.exe!wmain(int, wchar_t**) [nsWindowsWMain.cpp:9d45c0c056cdeabf6790d37dfb3a8bf6f3d24256 : 151 + 0x14]
[task 2025-01-22T00:04:06.410Z] 00:04:06 INFO - rbx = 0x00000218770ab47a rsi = 0x0000021877404200
[task 2025-01-22T00:04:06.410Z] 00:04:06 INFO - rdi = 0x0000021877404100 rbp = 0x000000000000000a
[task 2025-01-22T00:04:06.410Z] 00:04:06 INFO - rsp = 0x000000206f3ffc00 r12 = 0x00000218770aafe0
[task 2025-01-22T00:04:06.410Z] 00:04:06 INFO - r13 = 0x000000000000001f r14 = 0x0000000000000003
[task 2025-01-22T00:04:06.410Z] 00:04:06 INFO - r15 = 0x00000218774030d0 rip = 0x00007ff7866b1237
[task 2025-01-22T00:04:06.410Z] 00:04:06 INFO - Found by: call frame info
[task 2025-01-22T00:04:06.410Z] 00:04:06 INFO - 21 firefox.exe!invoke_main() [exe_common.inl : 90]
[task 2025-01-22T00:04:06.410Z] 00:04:06 INFO - Found by: inlining
[task 2025-01-22T00:04:06.410Z] 00:04:06 INFO - 22 firefox.exe!__scrt_common_main_seh() [exe_common.inl : 288 + 0x21]
[task 2025-01-22T00:04:06.410Z] 00:04:06 INFO - rbx = 0x00000218770aafe0 rsi = 0x0000000000000000
[task 2025-01-22T00:04:06.411Z] 00:04:06 INFO - rdi = 0x00000218770a9900 rbp = 0x0000000000000000
[task 2025-01-22T00:04:06.411Z] 00:04:06 INFO - rsp = 0x000000206f3ffcc0 r12 = 0x0000000000000000
[task 2025-01-22T00:04:06.411Z] 00:04:06 INFO - r13 = 0x0000000000000000 r14 = 0x0000000000000000
[task 2025-01-22T00:04:06.411Z] 00:04:06 INFO - r15 = 0x0000000000000000 rip = 0x00007ff786711f08
[task 2025-01-22T00:04:06.411Z] 00:04:06 INFO - Found by: call frame info
[task 2025-01-22T00:04:06.411Z] 00:04:06 INFO - 23 kernel32.dll + 0x1259c
[task 2025-01-22T00:04:06.411Z] 00:04:06 INFO - rbx = 0x0000000000000000 rsi = 0x0000000000000000
[task 2025-01-22T00:04:06.411Z] 00:04:06 INFO - rdi = 0x0000000000000000 rbp = 0x0000000000000000
[task 2025-01-22T00:04:06.411Z] 00:04:06 INFO - rsp = 0x000000206f3ffd00 r12 = 0x0000000000000000
[task 2025-01-22T00:04:06.411Z] 00:04:06 INFO - r13 = 0x0000000000000000 r14 = 0x0000000000000000
[task 2025-01-22T00:04:06.411Z] 00:04:06 INFO - r15 = 0x0000000000000000 rip = 0x00007ffafed3259d
[task 2025-01-22T00:04:06.411Z] 00:04:06 INFO - Found by: call frame info
[task 2025-01-22T00:04:06.411Z] 00:04:06 INFO - 24 ntdll.dll!RtlUserThreadStart + 0x27
[task 2025-01-22T00:04:06.411Z] 00:04:06 INFO - rsp = 0x000000206f3ffd30 rip = 0x00007ffb0034af38
[task 2025-01-22T00:04:06.411Z] 00:04:06 INFO - Found by: stack scanning
[task 2025-01-22T00:04:06.412Z] 00:04:06 INFO - Thread 1 IPC I/O Child - tid: 7292
[task 2025-01-22T00:04:06.412Z] 00:04:06 INFO - 0 ntdll.dll!NtRemoveIoCompletion + 0x14
[task 2025-01-22T00:04:06.412Z] 00:04:06 INFO - rax = 0x0000000000000009 rdx = 0x000000206fbff550
[task 2025-01-22T00:04:06.412Z] 00:04:06 INFO - rcx = 0x0000000000000238 rbx = 0x000000206fbff548
[task 2025-01-22T00:04:06.412Z] 00:04:06 INFO - rsi = 0x000000206fbff5b0 rdi = 0x000000206fbff5c0
[task 2025-01-22T00:04:06.412Z] 00:04:06 INFO - rbp = 0x0000000000000000 rsp = 0x000000206fbff4a8
[task 2025-01-22T00:04:06.412Z] 00:04:06 INFO - r8 = 0x0000000000000008 r9 = 0x00007ffaeec00000
[task 2025-01-22T00:04:06.412Z] 00:04:06 INFO - r10 = 0x0000021877473000 r11 = 0x000002180131f000
[task 2025-01-22T00:04:06.412Z] 00:04:06 INFO - r12 = 0x0000000000000000 r13 = 0x0000000000000000
[task 2025-01-22T00:04:06.412Z] 00:04:06 INFO - r14 = 0x0000021877450190 r15 = 0x0000000000000000
[task 2025-01-22T00:04:06.412Z] 00:04:06 INFO - rip = 0x00007ffb003904c4
[task 2025-01-22T00:04:06.412Z] 00:04:06 INFO - Found by: given as instruction pointer in context
[task 2025-01-22T00:04:06.412Z] 00:04:06 INFO - 1 KERNELBASE.dll!GetQueuedCompletionStatus + 0x52
[task 2025-01-22T00:04:06.412Z] 00:04:06 INFO - rbx = 0x000000206fbff548 rbp = 0x0000000000000000
[task 2025-01-22T00:04:06.412Z] 00:04:06 INFO - rsp = 0x000000206fbff4b0 r12 = 0x0000000000000000
[task 2025-01-22T00:04:06.413Z] 00:04:06 INFO - r13 = 0x0000000000000000 r14 = 0x0000021877450190
[task 2025-01-22T00:04:06.413Z] 00:04:06 INFO - r15 = 0x0000000000000000 rip = 0x00007ffafdae9263
[task 2025-01-22T00:04:06.413Z] 00:04:06 INFO - Found by: call frame info
[task 2025-01-22T00:04:06.413Z] 00:04:06 INFO - 2 xul.dll!base::MessagePumpForIO::GetIOItem(unsigned long, base::MessagePumpForIO::IOItem*) [message_pump_win.cc:9d45c0c056cdeabf6790d37dfb3a8bf6f3d24256 : 514 + 0x13]
[task 2025-01-22T00:04:06.413Z] 00:04:06 INFO - rbx = 0x00000218774148e0 rdi = 0x00000000ffffffff
[task 2025-01-22T00:04:06.413Z] 00:04:06 INFO - rbp = 0x0000000000000000 rsp = 0x000000206fbff510
[task 2025-01-22T00:04:06.413Z] 00:04:06 INFO - r12 = 0x0000000000000000 r13 = 0x0000000000000000
[task 2025-01-22T00:04:06.413Z] 00:04:06 INFO - r14 = 0x0000021877450190 r15 = 0x0000000000000000
[task 2025-01-22T00:04:06.413Z] 00:04:06 INFO - rip = 0x00007ffabd9abfb3
[task 2025-01-22T00:04:06.413Z] 00:04:06 INFO - Found by: call frame info
[task 2025-01-22T00:04:06.413Z] 00:04:06 INFO - 3 xul.dll!base::MessagePumpForIO::WaitForIOCompletion(unsigned long, base::MessagePumpForIO::IOHandler*) [message_pump_win.cc:9d45c0c056cdeabf6790d37dfb3a8bf6f3d24256 : 479 + 0xc]
[task 2025-01-22T00:04:06.413Z] 00:04:06 INFO - rbx = 0x000000206fbff618 rsi = 0x00000218774148e0
[task 2025-01-22T00:04:06.413Z] 00:04:06 INFO - rdi = 0x0000000000000000 rbp = 0x0000000000000000
[task 2025-01-22T00:04:06.413Z] 00:04:06 INFO - rsp = 0x000000206fbff590 r12 = 0x0000000000000000
[task 2025-01-22T00:04:06.413Z] 00:04:06 INFO - r13 = 0x0000000000000000 r14 = 0x000000206fbff620
[task 2025-01-22T00:04:06.413Z] 00:04:06 INFO - r15 = 0x0000000000000000 rip = 0x00007ffabd9abde2
[task 2025-01-22T00:04:06.414Z] 00:04:06 INFO - Found by: call frame info
[task 2025-01-22T00:04:06.414Z] 00:04:06 INFO - 4 xul.dll!base::MessagePumpForIO::WaitForWork() [message_pump_win.cc:9d45c0c056cdeabf6790d37dfb3a8bf6f3d24256 : 472]
[task 2025-01-22T00:04:06.414Z] 00:04:06 INFO - Found by: inlining
[task 2025-01-22T00:04:06.414Z] 00:04:06 INFO - 5 xul.dll!base::MessagePumpForIO::DoRunLoop() [message_pump_win.cc:9d45c0c056cdeabf6790d37dfb3a8bf6f3d24256 : 457 + 0x43]
[task 2025-01-22T00:04:06.414Z] 00:04:06 INFO - rbx = 0x000000206fbff618 rsi = 0x00000218774148e0
[task 2025-01-22T00:04:06.414Z] 00:04:06 INFO - rdi = 0x0000021877414910 rbp = 0x0000000000000000
[task 2025-01-22T00:04:06.414Z] 00:04:06 INFO - rsp = 0x000000206fbff5f0 r12 = 0x0000000000000000
[task 2025-01-22T00:04:06.414Z] 00:04:06 INFO - r13 = 0x0000000000000000 r14 = 0x000000206fbff620
[task 2025-01-22T00:04:06.414Z] 00:04:06 INFO - r15 = 0x0000000000000000 rip = 0x00007ffabd9abcfb
[task 2025-01-22T00:04:06.414Z] 00:04:06 INFO - Found by: call frame info
<...>
[task 2025-01-22T00:04:59.458Z] 00:04:59 INFO - 0x7ffb002f0000 - 0x7ffb00506fff ntdll.dll 10.0.22621.4541
[task 2025-01-22T00:04:59.458Z] 00:04:59 INFO -
[task 2025-01-22T00:04:59.458Z] 00:04:59 INFO - Unloaded modules:
[task 2025-01-22T00:04:59.459Z] 00:04:59 INFO -
[task 2025-01-22T00:04:59.459Z] 00:04:59 INFO - Unimplemented streams encountered:
[task 2025-01-22T00:04:59.459Z] 00:04:59 INFO - Stream 0x00000000 UnusedStream (Official) @ 0x00000000
[task 2025-01-22T00:04:59.459Z] 00:04:59 INFO - Stream 0x0000000d FunctionTable (Official) @ 0x00004850
[task 2025-01-22T00:04:59.459Z] 00:04:59 INFO - Stream 0x00000015 SystemMemoryInfoStream (Official) @ 0x00005138
[task 2025-01-22T00:04:59.459Z] 00:04:59 INFO - Stream 0x00000016 ProcessVmCountersStream (Official) @ 0x00005324
[task 2025-01-22T00:04:59.649Z] 00:04:59 INFO - Stopping web server
[task 2025-01-22T00:04:59.654Z] 00:04:59 INFO - Server shut down.
[task 2025-01-22T00:04:59.654Z] 00:04:59 INFO - Web server killed.
[task 2025-01-22T00:04:59.655Z] 00:04:59 INFO - Stopping web socket server
[task 2025-01-22T00:04:59.655Z] 00:04:59 INFO - Stopping ssltunnel
[task 2025-01-22T00:04:59.655Z] 00:04:59 INFO - runtests.py | Running tests: end.
[task 2025-01-22T00:04:59.711Z] 00:04:59 INFO - Buffered messages finished
[task 2025-01-22T00:04:59.721Z] 00:04:59 INFO - TEST-INFO | checking window state
[task 2025-01-22T00:04:59.721Z] 00:04:59 INFO - Browser Chrome Test Summary
[task 2025-01-22T00:04:59.722Z] 00:04:59 INFO - Passed: 0
[task 2025-01-22T00:04:59.722Z] 00:04:59 INFO - Failed: 0
[task 2025-01-22T00:04:59.722Z] 00:04:59 INFO - Todo: 0
[task 2025-01-22T00:04:59.723Z] 00:04:59 INFO - Mode: e10s
[task 2025-01-22T00:04:59.723Z] 00:04:59 INFO - *** End BrowserChrome Test Results ***
[task 2025-01-22T00:04:59.723Z] 00:04:59 INFO - Buffered messages finished
[task 2025-01-22T00:04:59.724Z] 00:04:59 INFO - SUITE-END | took 700s
[task 2025-01-22T00:04:59.724Z] 00:04:59 INFO - SUITE-START | Running 1 tests
[task 2025-01-22T00:04:59.725Z] 00:04:59 INFO - :::
[task 2025-01-22T00:04:59.725Z] 00:04:59 INFO - ::: Test verification summary for:
[task 2025-01-22T00:04:59.725Z] 00:04:59 INFO - :::
[task 2025-01-22T00:04:59.726Z] 00:04:59 INFO - ::: browser/components/sessionstore/test/browser_tab_groups_undo.js
[task 2025-01-22T00:04:59.726Z] 00:04:59 INFO - :::
[task 2025-01-22T00:04:59.727Z] 00:04:59 INFO - ::: 1. Run each test 10 times in one browser. : FAIL
[task 2025-01-22T00:04:59.727Z] 00:04:59 INFO - ::: 2. Run each test 5 times in a new browser each time. : not run / incomplete
[task 2025-01-22T00:04:59.728Z] 00:04:59 INFO - ::: 3. Run each test 10 times in one browser, in chaos mode. : not run / incomplete
[task 2025-01-22T00:04:59.728Z] 00:04:59 INFO - ::: 4. Run each test 5 times in a new browser each time, in chaos mode. : not run / incomplete
[task 2025-01-22T00:04:59.729Z] 00:04:59 INFO - :::
[task 2025-01-22T00:04:59.729Z] 00:04:59 INFO - ::: Test verification FAILED!
[task 2025-01-22T00:04:59.730Z] 00:04:59 INFO - :::
[task 2025-01-22T00:04:59.730Z] 00:04:59 INFO - Buffered messages finished
[task 2025-01-22T00:04:59.731Z] 00:04:59 INFO - SUITE-END | took 0s
[task 2025-01-22T00:04:59.774Z] 00:04:59 INFO - Return code: 0
[task 2025-01-22T00:04:59.788Z] 00:04:59 WARNING - Got 2 unexpected statuses
[task 2025-01-22T00:04:59.788Z] 00:04:59 WARNING - Got 10 unexpected crashes
[task 2025-01-22T00:04:59.788Z] 00:04:59 INFO - TinderboxPrint: mochitest-mochitest-browser-chrome<br/>7/<em class="testfail">2</em>/0 <em class="testfail">CRASH</em>
[task 2025-01-22T00:04:59.788Z] 00:04:59 WARNING - setting return code to 1
[task 2025-01-22T00:04:59.788Z] 00:04:59 WARNING - TinderboxPrint: Per-test run of ...\test\browser_tab_groups_undo.js<br/>: WARNING
[task 2025-01-22T00:04:59.788Z] 00:04:59 INFO - Per-test suites: {}
[task 2025-01-22T00:04:59.788Z] 00:04:59 INFO - Per-test suites: {}
[task 2025-01-22T00:04:59.788Z] 00:04:59 INFO - Running post-action listener: _package_coverage_data
[task 2025-01-22T00:04:59.788Z] 00:04:59 INFO - Running post-action listener: _resource_record_post_action
[task 2025-01-22T00:04:59.788Z] 00:04:59 INFO - Running post-action listener: process_java_coverage_data
[task 2025-01-22T00:04:59.788Z] 00:04:59 INFO - [mozharness: 2025-01-22 00:04:59.788744Z] Finished run-tests step (success)
[task 2025-01-22T00:04:59.788Z] 00:04:59 INFO - [mozharness: 2025-01-22 00:04:59.788744Z] Running uninstall step.
[task 2025-01-22T00:04:59.789Z] 00:04:59 INFO - Running pre-action listener: _resource_record_pre_action
[task 2025-01-22T00:04:59.789Z] 00:04:59 INFO - Running main action method: uninstall
[task 2025-01-22T00:04:59.789Z] 00:04:59 INFO - Skipping uninstall for non-MSIX test
[task 2025-01-22T00:04:59.789Z] 00:04:59 INFO - Running post-action listener: _resource_record_post_action
[task 2025-01-22T00:04:59.789Z] 00:04:59 INFO - [mozharness: 2025-01-22 00:04:59.789270Z] Finished uninstall step (success)
[task 2025-01-22T00:04:59.789Z] 00:04:59 INFO - Running post-run listener: _resource_record_post_run
[task 2025-01-22T00:05:00.383Z] 00:05:00 INFO - Validating Perfherder data against D:\task_173750169683613\mozharness\external_tools\performance-artifact-schema.json
[task 2025-01-22T00:05:00.399Z] 00:05:00 INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": ".overall", "extraOptions": ["e10s", "taskcluster-Standard_F8s_v2"], "subtests": [{"name": "cpu_percent", "value": 38.89590343437151}, {"name": "io_write_bytes", "value": 2473132544}, {"name": "io.read_bytes", "value": 373157376}, {"name": "io_write_time", "value": 2}, {"name": "io_read_time", "value": 1}]}, {"name": ".start-pulseaudio", "subtests": [{"name": "time", "value": 0.0}, {"name": "cpu_percent", "value": 0}]}, {"name": ".install", "subtests": [{"name": "time", "value": 3.562000000000012}, {"name": "cpu_percent", "value": 47.385416666666664}]}, {"name": ".stage-files", "subtests": [{"name": "time", "value": 0.0}, {"name": "cpu_percent", "value": 0}]}, {"name": ".run-tests", "subtests": [{"name": "time", "value": 715.984}, {"name": "cpu_percent", "value": 38.859286412512205}]}, {"name": ".uninstall", "subtests": [{"name": "time", "value": 0.0}, {"name": "cpu_percent", "value": 0}]}]}
[task 2025-01-22T00:05:00.399Z] 00:05:00 INFO - Total resource usage - Wall time: 720s; CPU: Can't collect data; Read bytes: 373157376; Write bytes: 2473132544; Read time: 1; Write time: 2
[task 2025-01-22T00:05:00.399Z] 00:05:00 INFO - TinderboxPrint: I/O read bytes / time<br/>373,157,376 / 1
[task 2025-01-22T00:05:00.399Z] 00:05:00 INFO - TinderboxPrint: I/O write bytes / time<br/>2,473,132,544 / 2
[task 2025-01-22T00:05:00.400Z] 00:05:00 INFO - TinderboxPrint: CPU idle<br/>3,524.6 (61.2%)
[task 2025-01-22T00:05:00.400Z] 00:05:00 INFO - TinderboxPrint: CPU system<br/>703.8 (12.2%)
[task 2025-01-22T00:05:00.400Z] 00:05:00 INFO - TinderboxPrint: CPU user<br/>1,523.4 (26.5%)
[task 2025-01-22T00:05:00.403Z] 00:05:00 INFO - start-pulseaudio - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2025-01-22T00:05:00.408Z] 00:05:00 INFO - install - Wall time: 4s; CPU: 47%; Read bytes: 110592; Write bytes: 92610560; Read time: 0; Write time: 0
[task 2025-01-22T00:05:00.411Z] 00:05:00 INFO - stage-files - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2025-01-22T00:05:00.461Z] 00:05:00 INFO - run-tests - Wall time: 716s; CPU: 39%; Read bytes: 373009920; Write bytes: 2380521984; Read time: 1; Write time: 2
[task 2025-01-22T00:05:00.471Z] 00:05:00 INFO - uninstall - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2025-01-22T00:05:02.932Z] 00:05:02 WARNING - returning nonzero exit status 1
[taskcluster 2025-01-22T00:05:03.121Z] Exit Code: 1
[taskcluster 2025-01-22T00:05:03.121Z] User Time: 15.625ms
[taskcluster 2025-01-22T00:05:03.121Z] Kernel Time: 0s
[taskcluster 2025-01-22T00:05:03.121Z] Wall Time: 15m36.6507577s
[taskcluster 2025-01-22T00:05:03.121Z] Result: FAILED
[taskcluster 2025-01-22T00:05:03.122Z] === Task Finished ===
[taskcluster 2025-01-22T00:05:03.122Z] Task Duration: 15m36.6531895s
[taskcluster 2025-01-22T00:05:04.964Z] Uploading artifact public/test_info/433e2848-7029-4188-9f96-5e8f04133407.dmp from file D:\task_173750169683613\build\blobber_upload_dir\433e2848-7029-4188-9f96-5e8f04133407.dmp with content encoding "gzip", mime type "application/octet-stream" and expiry 2026-01-21T23:15:43.254Z
[taskcluster 2025-01-22T00:05:04.969Z] Uploading artifact public/test_info/baa2c179-b62b-4e9f-8685-22e8eec1beb6.dmp from file D:\task_173750169683613\build\blobber_upload_dir\baa2c179-b62b-4e9f-8685-22e8eec1beb6.dmp with content encoding "gzip", mime type "application/octet-stream" and expiry 2026-01-21T23:15:43.254Z
[taskcluster 2025-01-22T00:05:04.969Z] Uploading artifact public/test_info/c1dd7bce-ab88-457f-95df-9b9bc310aea6.dmp from file D:\task_173750169683613\build\blobber_upload_dir\c1dd7bce-ab88-457f-95df-9b9bc310aea6.dmp with content encoding "gzip", mime type "application/octet-stream" and expiry 2026-01-21T23:15:43.254Z
[taskcluster 2025-01-22T00:05:04.971Z] Uploading artifact public/test_info/f0a11ddc-50e4-447f-8efd-d9af4c8ea665.dmp from file D:\task_173750169683613\build\blobber_upload_dir\f0a11ddc-50e4-447f-8efd-d9af4c8ea665.dmp with content encoding "gzip", mime type "application/octet-stream" and expiry 2026-01-21T23:15:43.254Z
[taskcluster 2025-01-22T00:05:04.973Z] Uploading artifact public/test_info/manifests.list from file D:\task_173750169683613\build\blobber_upload_dir\manifests.list with content encoding "gzip", mime type "application/octet-stream" and expiry 2026-01-21T23:15:43.254Z
[taskcluster 2025-01-22T00:05:04.974Z] Uploading artifact public/test_info/8a4452bb-a932-4a06-8e96-44c40d1388e3.dmp from file D:\task_173750169683613\build\blobber_upload_dir\8a4452bb-a932-4a06-8e96-44c40d1388e3.dmp with content encoding "gzip", mime type "application/octet-stream" and expiry 2026-01-21T23:15:43.254Z
[taskcluster 2025-01-22T00:05:04.975Z] Uploading artifact public/test_info/mozilla-test-fail-screenshot_ehge9l4c.png from file D:\task_173750169683613\build\blobber_upload_dir\mozilla-test-fail-screenshot_ehge9l4c.png with content encoding "identity", mime type "image/png" and expiry 2026-01-21T23:15:43.254Z
[taskcluster 2025-01-22T00:05:04.978Z] Uploading artifact public/test_info/4f23e5f0-58d0-4332-9152-d32dc936bbe4.dmp from file D:\task_173750169683613\build\blobber_upload_dir\4f23e5f0-58d0-4332-9152-d32dc936bbe4.dmp with content encoding "gzip", mime type "application/octet-stream" and expiry 2026-01-21T23:15:43.254Z
[taskcluster 2025-01-22T00:05:04.980Z] Uploading artifact public/test_info/fd91c925-3274-4a9b-9ed7-577fec157ce2.dmp from file D:\task_173750169683613\build\blobber_upload_dir\fd91c925-3274-4a9b-9ed7-577fec157ce2.dmp with content encoding "gzip", mime type "application/octet-stream" and expiry 2026-01-21T23:15:43.254Z
[taskcluster 2025-01-22T00:05:04.980Z] Uploading artifact public/test_info/resource-usage.json from file D:\task_173750169683613\build\blobber_upload_dir\resource-usage.json with content encoding "gzip", mime type "application/json" and expiry 2026-01-21T23:15:43.254Z
[taskcluster 2025-01-22T00:05:04.981Z] Uploading artifact public/test_info/system-info.log from file D:\task_173750169683613\build\blobber_upload_dir\system-info.log with content encoding "gzip", mime type "text/plain" and expiry 2026-01-21T23:15:43.254Z
[taskcluster 2025-01-22T00:05:04.983Z] Uploading artifact public/test_info/8ea9aece-6fb2-4d43-9aa2-7694bdf0ce28.dmp from file D:\task_173750169683613\build\blobber_upload_dir\8ea9aece-6fb2-4d43-9aa2-7694bdf0ce28.dmp with content encoding "gzip", mime type "application/octet-stream" and expiry 2026-01-21T23:15:43.254Z
[taskcluster 2025-01-22T00:05:04.986Z] Uploading artifact public/logs/localconfig.json from file D:\task_173750169683613\logs\localconfig.json with content encoding "gzip", mime type "application/json" and expiry 2026-01-21T23:15:43.254Z
[taskcluster 2025-01-22T00:05:04.990Z] Uploading artifact public/test_info/mochitest-browser-chrome-test1_errorsummary.log from file D:\task_173750169683613\build\blobber_upload_dir\mochitest-browser-chrome-test1_errorsummary.log with content encoding "gzip", mime type "text/plain" and expiry 2026-01-21T23:15:43.254Z
[taskcluster 2025-01-22T00:05:04.992Z] Uploading artifact public/test_info/450ad424-380a-4a8a-9984-30a63896c216.dmp from file D:\task_173750169683613\build\blobber_upload_dir\450ad424-380a-4a8a-9984-30a63896c216.dmp with content encoding "gzip", mime type "application/octet-stream" and expiry 2026-01-21T23:15:43.254Z
[taskcluster 2025-01-22T00:05:04.992Z] Uploading artifact public/test_info/b842d217-8690-4edb-bb75-04463b8698db.dmp from file D:\task_173750169683613\build\blobber_upload_dir\b842d217-8690-4edb-bb75-04463b8698db.dmp with content encoding "gzip", mime type "application/octet-stream" and expiry 2026-01-21T23:15:43.254Z
[taskcluster 2025-01-22T00:05:04.999Z] Uploading artifact public/test_info/profile_resource-usage.json from file D:\task_173750169683613\build\blobber_upload_dir\profile_resource-usage.json with content encoding "gzip", mime type "application/json" and expiry 2026-01-21T23:15:43.254Z
[taskcluster 2025-01-22T00:05:05.394Z] Uploading link artifact public/logs/live.log to artifact public/logs/live_backing.log with expiry 2026-01-21T23:15:43.254Z
[taskcluster:error] exit status 1
Comment 1•23 days ago
|
||
:jswinarton, since you are the author of the regressor, bug 1940752, could you take a look?
For more information, please visit BugBot documentation.
Comment 2•23 days ago
|
||
Set release status flags based on info from the regressing bug 1940752
Comment 3•23 days ago
|
||
This is now failing on Tier 1 jobs.
TEST-PASS | browser/components/sessionstore/test/browser_tab_groups_undo.js | SessionStore last closed group ID is null after closing a tab within a group - null == null -
[task 2025-01-22T06:59:43.991Z] 06:59:43 INFO - Buffered messages finished
[task 2025-01-22T06:59:43.991Z] 06:59:43 INFO - TEST-UNEXPECTED-FAIL | browser/components/sessionstore/test/browser_tab_groups_undo.js | Uncaught exception in test bound test_tabGroupsUndo - at chrome://global/content/elements/browser-custom-element.js:857 - TypeError: can't access property "userTyped", this.urlbarChangeTracker is undefined
[task 2025-01-22T06:59:43.991Z] 06:59:43 INFO - Stack trace:
[task 2025-01-22T06:59:43.991Z] 06:59:43 INFO - set userTypedValue@chrome://global/content/elements/browser-custom-element.js:857:7
[task 2025-01-22T06:59:43.991Z] 06:59:43 INFO - _kickOffBrowserLoad@chrome://browser/content/tabbrowser/tabbrowser.js:3303:11
[task 2025-01-22T06:59:43.991Z] 06:59:43 INFO - addTab@chrome://browser/content/tabbrowser/tabbrowser.js:2831:14
[task 2025-01-22T06:59:43.992Z] 06:59:43 INFO - addTab@resource://testing-common/BrowserTestUtils.sys.mjs:2676:23
[task 2025-01-22T06:59:43.992Z] 06:59:43 INFO - test_tabGroupsUndo@chrome://mochitests/content/browser/browser/components/sessionstore/test/browser_tab_groups_undo.js:74:39
[task 2025-01-22T06:59:43.992Z] 06:59:43 INFO - async*handleTask@chrome://mochikit/content/browser-test.js:1147:26
[task 2025-01-22T06:59:43.992Z] 06:59:43 INFO - _runTaskBasedTest@chrome://mochikit/content/browser-test.js:1219:18
[task 2025-01-22T06:59:43.992Z] 06:59:43 INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1360:14
[task 2025-01-22T06:59:43.992Z] 06:59:43 INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1136:14
[task 2025-01-22T06:59:43.992Z] 06:59:43 INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13
[task 2025-01-22T06:59:43.992Z] 06:59:43 INFO - Leaving test bound test_tabGroupsUndo
[task 2025-01-22T06:59:43.992Z] 06:59:43 INFO - GECKO(14965) | console.error: (new TypeError("can't access property \"currentWindowGlobal\", aBrowser.browsingContext is null", "resource://gre/modules/PageThumbs.sys.mjs", 494))
[task 2025-01-22T06:59:43.993Z] 06:59:43 INFO - GECKO(14965) | JavaScript error: chrome://browser/content/tabbrowser/tab-hover-preview.mjs, line 332: TypeError: can't access property "spec", this._tab.linkedBrowser.currentURI is null
[task 2025-01-22T06:59:43.993Z] 06:59:43 INFO - GECKO(14965) | console.error: "Given tab is not restoring."
[task 2025-01-22T06:59:43.993Z] 06:59:43 INFO - running
[task 2025-01-22T06:59:43.994Z] 06:59:43 INFO - Console message: [JavaScript Error: "TypeError: can't access property "spec", this._tab.linkedBrowser.currentURI is null" {file: "chrome://browser/content/tabbrowser/tab-hover-preview.mjs" line: 332}]
[task 2025-01-22T06:59:43.994Z] 06:59:43 INFO - get _displayURI@chrome://browser/content/tabbrowser/tab-hover-preview.mjs:332:30
[task 2025-01-22T06:59:43.994Z] 06:59:43 INFO - _updatePreview@chrome://browser/content/tabbrowser/tab-hover-preview.mjs:235:1
[task 2025-01-22T06:59:43.994Z] 06:59:43 INFO - handleEvent@chrome://browser/content/tabbrowser/tab-hover-preview.mjs:223:14
[task 2025-01-22T06:59:43.994Z] 06:59:43 INFO - TabHoverPreviewPanel/this._panelOpener<@chrome://browser/content/tabbrowser/tab-hover-preview.mjs:65:23
[task 2025-01-22T06:59:43.994Z] 06:59:43 INFO - execute/this._timer<@chrome://browser/content/tabbrowser/tab-hover-preview.mjs:376:14
[task 2025-01-22T06:59:43.994Z] 06:59:43 INFO - setTimeout handler*SimpleTest_setTimeoutShim@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:922:41
[task 2025-01-22T06:59:43.994Z] 06:59:43 INFO - execute@chrome://browser/content/tabbrowser/tab-hover-preview.mjs:373:29
[task 2025-01-22T06:59:43.994Z] 06:59:43 INFO - activate@chrome://browser/content/tabbrowser/tab-hover-preview.mjs:192:23
[task 2025-01-22T06:59:43.994Z] 06:59:43 INFO - on_TabHoverStart@chrome://browser/content/tabbrowser/tabs.js:281:25
[task 2025-01-22T06:59:43.994Z] 06:59:43 INFO - handleEvent@chrome://browser/content/tabbrowser/tabs.js:2790:29
[task 2025-01-22T06:59:43.994Z] 06:59:43 INFO - _mouseenter@chrome://browser/content/tabbrowser/tab.js:591:12
[task 2025-01-22T06:59:43.994Z] 06:59:43 INFO - on_mouseover@chrome://browser/content/tabbrowser/tab.js:393:14
[task 2025-01-22T06:59:43.994Z] 06:59:43 INFO - handleEvent@chrome://global/content/customElements.js:459:27
[task 2025-01-22T06:59:43.994Z] 06:59:43 INFO - EventListener.handleEvent*MozTabbrowserTab@chrome://browser/content/tabbrowser/tab.js:44:12
[task 2025-01-22T06:59:43.994Z] 06:59:43 INFO - _createTab@chrome://browser/content/tabbrowser/tabbrowser.js:3091:24
[task 2025-01-22T06:59:43.994Z] 06:59:43 INFO - addTab@chrome://browser/content/tabbrowser/tabbrowser.js:2733:18
[task 2025-01-22T06:59:43.995Z] 06:59:43 INFO - addTab@resource://testing-common/BrowserTestUtils.sys.mjs:2676:23
[task 2025-01-22T06:59:43.995Z] 06:59:43 INFO - test_tabGroupsUndo@chrome://mochitests/content/browser/browser/components/sessionstore/test/browser_tab_groups_undo.js:74:39
[task 2025-01-22T06:59:43.995Z] 06:59:43 INFO - async*handleTask@chrome://mochikit/content/browser-test.js:1147:26
[task 2025-01-22T06:59:43.995Z] 06:59:43 INFO - _runTaskBasedTest@chrome://mochikit/content/browser-test.js:1219:18
[task 2025-01-22T06:59:43.995Z] 06:59:43 INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1360:14
[task 2025-01-22T06:59:43.995Z] 06:59:43 INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1136:14
[task 2025-01-22T06:59:43.995Z] 06:59:43 INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13
[task 2025-01-22T06:59:43.995Z] 06:59:43 INFO -
[task 2025-01-22T06:59:43.995Z] 06:59:43 INFO - Console message: [JavaScript Error: "Cannot attach ID to a tab in a closed window." {file: "chrome://browser/content/parent/ext-browser.js" line: 343}]
[task 2025-01-22T06:59:43.995Z] 06:59:43 INFO - setId@chrome://browser/content/parent/ext-browser.js:343:13
[task 2025-01-22T06:59:43.995Z] 06:59:43 INFO - getId@chrome://browser/content/parent/ext-browser.js:319:10
[task 2025-01-22T06:59:43.995Z] 06:59:43 INFO - emitRemoved@chrome://browser/content/parent/ext-browser.js:683:22
[task 2025-01-22T06:59:43.995Z] 06:59:43 INFO - _handleWindowClose@chrome://browser/content/parent/ext-browser.js:610:14
[task 2025-01-22T06:59:43.995Z] 06:59:43 INFO - observe@chrome://extensions/content/parent/ext-tabs-base.js:1761:19
[task 2025-01-22T06:59:43.996Z] 06:59:43 INFO -
[task 2025-01-22T06:59:44.242Z] 06:59:44 INFO - GECKO(14965) | 1737529184241 Marionette TRACE Received observer notification browser-delayed-startup-finished
[task 2025-01-22T06:59:44.577Z] 06:59:44 INFO - GECKO(14965) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpux8np81n.mozrunner/runtests_leaks_tab_pid26818.log
[task 2025-01-22T06:59:44.579Z] 06:59:44 INFO - GECKO(14965) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpux8np81n.mozrunner/runtests_leaks_tab_pid26817.log
[task 2025-01-22T06:59:44.641Z] 06:59:44 INFO - GECKO(14965) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpux8np81n.mozrunner/runtests_leaks_tab_pid26858.log
[task 2025-01-22T07:05:54.641Z] 07:05:54 INFO - TEST-UNEXPECTED-TIMEOUT | browser/components/sessionstore/test/browser_tab_groups_undo.js | application timed out after 370 seconds with no output
[task 2025-01-22T07:05:54.641Z] 07:05:54 INFO - TEST-INFO took 370475ms
[task 2025-01-22T07:05:54.641Z] 07:05:54 INFO - Buffered messages finished
Failure log -> https://treeherder.mozilla.org/logviewer?job_id=491192211&repo=mozilla-central&lineNumber=17923
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment 6•9 days ago
|
||
Set release status flags based on info from the regressing bug 1940752
Comment hidden (Intermittent Failures Robot) |
Description
•