Open Bug 2023730 Opened 6 months ago Updated 1 month ago

Intermittent TV browser/components/extensions/test/browser/browser_ext_tabs_events.js | testTabCreateRelated - Test timed out

Categories

(WebExtensions :: General, defect, P5)

Product:
WebExtensions
Component:
General
Type:
defect

Tracking

(Not tracked)

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

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

Filed by: rperta [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=553970734&repo=autoland&task=B5k1cE-lTwaD03QKQbq5Cg.0
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/B5k1cE-lTwaD03QKQbq5Cg/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/default/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/B5k1cE-lTwaD03QKQbq5Cg/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

[task 2026-03-16T20:50:26.622+00:00] 20:50:26     INFO - TEST-PASS | browser/components/extensions/test/browser/browser_ext_tabs_events.js | testTabCreateRelated - tabs.onUpdated 60: monitor extension got expected incognito value
[task 2026-03-16T20:50:26.623+00:00] 20:50:26     INFO - Console message: [JavaScript Error: "TypeError: node.closest is not a function" {file: "resource://gre/actors/AutoScrollChild.sys.mjs" line: 73}]
[task 2026-03-16T20:50:26.623+00:00] 20:50:26     INFO - isAutoscrollBlocker@resource://gre/actors/AutoScrollChild.sys.mjs:73:42
[task 2026-03-16T20:50:26.623+00:00] 20:50:26     INFO - handleEvent@resource://gre/actors/AutoScrollChild.sys.mjs:376:17
[task 2026-03-16T20:50:26.625+00:00] 20:50:26     INFO - synthesizeMouseAtPoint@chrome://mochikit/content/tests/SimpleTest/EventUtils.js:806:30
[task 2026-03-16T20:50:26.625+00:00] 20:50:26     INFO - synthesizeMouse/<@resource://testing-common/BrowserTestUtilsChild.sys.mjs:357:34
[task 2026-03-16T20:50:26.625+00:00] 20:50:26     INFO - wrapHandlingUserInput@resource://gre/modules/E10SUtils.sys.mjs:784:7
[task 2026-03-16T20:50:26.625+00:00] 20:50:26     INFO - synthesizeMouse@resource://testing-common/BrowserTestUtilsChild.sys.mjs:353:20
[task 2026-03-16T20:50:26.625+00:00] 20:50:26     INFO - receiveMessage@resource://testing-common/BrowserTestUtilsChild.sys.mjs:143:21
[task 2026-03-16T20:50:26.625+00:00] 20:50:26     INFO - JSActor query*sendQuery@resource://testing-common/BrowserTestUtils.sys.mjs:2874:18
[task 2026-03-16T20:50:26.625+00:00] 20:50:26     INFO - synthesizeMouse@resource://testing-common/BrowserTestUtils.sys.mjs:1824:17
[task 2026-03-16T20:50:26.625+00:00] 20:50:26     INFO - synthesizeMouseAtCenter@resource://testing-common/BrowserTestUtils.sys.mjs:1906:29
[task 2026-03-16T20:50:26.625+00:00] 20:50:26     INFO - testTabCreateRelated@chrome://mochitests/content/browser/browser/components/extensions/test/browser/browser_ext_tabs_events.js:556:26
[task 2026-03-16T20:50:26.625+00:00] 20:50:26     INFO - async*handleTask@chrome://mochikit/content/browser-test.js:1376:26
[task 2026-03-16T20:50:26.625+00:00] 20:50:26     INFO - _runTaskBasedTest@chrome://mochikit/content/browser-test.js:1466:18
[task 2026-03-16T20:50:26.625+00:00] 20:50:26     INFO - async*Tester_execTest@chrome://mochikit/content/browser-test.js:1617:14
[task 2026-03-16T20:50:26.625+00:00] 20:50:26     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1350:14
[task 2026-03-16T20:50:26.625+00:00] 20:50:26     INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1060:13
[task 2026-03-16T20:50:26.625+00:00] 20:50:26     INFO - 
[task 2026-03-16T20:50:26.626+00:00] 20:50:26     INFO - Buffered messages finished
[task 2026-03-16T20:50:26.627+00:00] 20:50:26     INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/browser_ext_tabs_events.js | testTabCreateRelated - Test timed out
[task 2026-03-16T20:50:26.627+00:00] 20:50:26     INFO - TEST-UNEXPECTED-TIMEOUT | browser/components/extensions/test/browser/browser_ext_tabs_events.js | Test timed out
[task 2026-03-16T20:50:26.627+00:00] 20:50:26     INFO - TEST-INFO took 47834ms
[task 2026-03-16T20:50:26.627+00:00] 20:50:26     INFO - GECKO(5536) | Completed ShutdownLeaks collections in process 2412
[task 2026-03-16T20:50:26.628+00:00] 20:50:26     INFO - TEST-START | Shutdown
[task 2026-03-16T20:50:26.628+00:00] 20:50:26     INFO - Browser Chrome Test Summary
[task 2026-03-16T20:50:26.629+00:00] 20:50:26     INFO - Passed:  837
[task 2026-03-16T20:50:26.629+00:00] 20:50:26     INFO - Failed:  1
[task 2026-03-16T20:50:26.630+00:00] 20:50:26     INFO - Todo:    0
[task 2026-03-16T20:50:26.630+00:00] 20:50:26     INFO - Mode:    e10s
[task 2026-03-16T20:50:26.631+00:00] 20:50:26     INFO - *** End BrowserChrome Test Results ***
[task 2026-03-16T20:50:26.631+00:00] 20:50:26     INFO - GECKO(5536) | Exiting due to channel error.
[task 2026-03-16T20:50:26.631+00:00] 20:50:26     INFO - TEST-INFO | Main app process: exit 0
[task 2026-03-16T20:50:26.632+00:00] 20:50:26     INFO - runtests.py | Application ran for: 0:01:07.441418
[task 2026-03-16T20:50:26.632+00:00] 20:50:26     INFO - zombiecheck | Reading PID log: C:\Users\task_177369191610294\AppData\Local\Temp\tmp152vgd3tpidlog
[task 2026-03-16T20:50:26.632+00:00] 20:50:26     INFO - ==> process 2412 launched child process 8556 ("D:\task_177369191610294\build\application\firefox\firefox.exe" -contentproc -parentBuildID 20260316200754 -prefsHandle 2456:27503 -prefMapHandle 2460:306748 -ipcHandle 2488 -initialChannelId {d4cd9600-4ed2-42ff-a9a8-03bbc9462568} -parentPid 2412 -crashReporter "\\.\pipe\gecko-crash-server-pipe.2412" -crashHelper 2500 -appDir "D:\task_177369191610294\build\application\firefox\browser" - 1 gpu)
[task 2026-03-16T20:50:26.634+00:00] 20:50:26     INFO - ==> process 2412 launched child process 4292 ("D:\task_177369191610294\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 2564:27353 -prefMapHandle 2392:306748 -jsInitHandle 2676:162696 -parentBuildID 20260316200754 -ipcHandle 2904 -initialChannelId {72960ce8-f176-428d-a874-a713cdc5a17c} -parentPid 2412 -crashReporter "\\.\pipe\gecko-crash-server-pipe.2412" -crashHelper 2684 -win32kLockedDown -appDir "D:\task_177369191610294\build\application\firefox\browser" - 2 tab)
[task 2026-03-16T20:50:26.635+00:00] 20:50:26     INFO - ==> process 2412 launched child process 6220 ("D:\task_177369191610294\build\application\firefox\firefox.exe" -contentproc -parentBuildID 20260316200754 -prefsHandle 3088:28559 -prefMapHandle 3092:306748 -ipcHandle 3100 -initialChannelId {e8fc70dd-f45d-4e53-bfd8-11fee7a6a21c} -parentPid 2412 -crashReporter "\\.\pipe\gecko-crash-server-pipe.2412" -crashHelper 3112 -appDir "D:\task_177369191610294\build\application\firefox\browser" - 3 rdd)
[task 2026-03-16T20:50:26.635+00:00] 20:50:26     INFO - ==> process 2412 launched child process 6492 ("D:\task_177369191610294\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 3468:28808 -prefMapHandle 3460:306748 -jsInitHandle 3472:162696 -parentBuildID 20260316200754 -ipcHandle 3492 -initialChannelId {a49d75a5-82d3-4475-9a2e-9ee66b6ae728} -parentPid 2412 -crashReporter "\\.\pipe\gecko-crash-server-pipe.2412" -crashHelper 2304 -win32kLockedDown -appDir "D:\task_177369191610294\build\application\firefox\browser" - 4 tab)
[task 2026-03-16T20:50:26.636+00:00] 20:50:26     INFO - ==> process 2412 launched child process 6936 ("D:\task_177369191610294\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 4044:27720 -prefMapHandle 4048:306748 -jsInitHandle 4052:162696 -parentBuildID 20260316200754 -ipcHandle 4060 -initialChannelId {176be4c9-2a39-4ffd-8a9c-423f99de2268} -parentPid 2412 -crashReporter "\\.\pipe\gecko-crash-server-pipe.2412" -crashHelper 4068 -win32kLockedDown -appDir "D:\task_177369191610294\build\application\firefox\browser" - 5 tab)
[task 2026-03-16T20:50:26.637+00:00] 20:50:26     INFO - ==> process 2412 launched child process 604 ("D:\task_177369191610294\build\application\firefox\firefox.exe" -contentproc -parentBuildID 20260316200754 -sandboxingKind 0 -prefsHandle 4784:42524 -prefMapHandle 4780:306748 -ipcHandle 4816 -initialChannelId {960accaa-1436-4f7f-9837-4ff04c57287f} -parentPid 2412 -crashReporter "\\.\pipe\gecko-crash-server-pipe.2412" -crashHelper 4820 -win32kLockedDown -appDir "D:\task_177369191610294\build\application\firefox\browser" - 6 utility)
[task 2026-03-16T20:50:26.638+00:00] 20:50:26     INFO - ==> process 2412 launched child process 6600 ("D:\task_177369191610294\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 4868:40260 -prefMapHandle 4872:306748 -jsInitHandle 4876:162696 -parentBuildID 20260316200754 -ipcHandle 4884 -initialChannelId {31da87a9-3a64-4493-90d6-1a3de19b023b} -parentPid 2412 -crashReporter "\\.\pipe\gecko-crash-server-pipe.2412" -crashHelper 4796 -win32kLockedDown -appDir "D:\task_177369191610294\build\application\firefox\browser" - 7 tab)
[task 2026-03-16T20:50:26.639+00:00] 20:50:26     INFO - ==> process 2412 launched child process 3028 ("D:\task_177369191610294\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 4904:40260 -prefMapHandle 4908:306748 -jsInitHandle 4912:162696 -parentBuildID 20260316200754 -ipcHandle 4888 -initialChannelId {e80d79ef-1e8e-478e-9c20-f419067068e3} -parentPid 2412 -crashReporter "\\.\pipe\gecko-crash-server-pipe.2412" -crashHelper 5208 -win32kLockedDown -appDir "D:\task_177369191610294\build\application\firefox\browser" - 8 tab)
[task 2026-03-16T20:50:26.640+00:00] 20:50:26     INFO - ==> process 2412 launched child process 7944 ("D:\task_177369191610294\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 4972:40260 -prefMapHandle 4976:306748 -jsInitHandle 4980:162696 -parentBuildID 20260316200754 -ipcHandle 4988 -initialChannelId {4e469116-fafc-4441-886c-39718003eb75} -parentPid 2412 -crashReporter "\\.\pipe\gecko-crash-server-pipe.2412" -crashHelper 5220 -win32kLockedDown -appDir "D:\task_177369191610294\build\application\firefox\browser" - 9 tab)
[task 2026-03-16T20:50:26.641+00:00] 20:50:26     INFO - ==> process 2412 launched child process 6140 ("D:\task_177369191610294\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 4348:42705 -prefMapHandle 5436:306748 -jsInitHandle 5556:162696 -parentBuildID 20260316200754 -ipcHandle 5620 -initialChannelId {72bc4a52-1a8d-4fc8-b0d0-1b305cd7a9fe} -parentPid 2412 -crashReporter "\\.\pipe\gecko-crash-server-pipe.2412" -crashHelper 5468 -win32kLockedDown -appDir "D:\task_177369191610294\build\application\firefox\browser" - 10 tab)
[task 2026-03-16T20:50:26.641+00:00] 20:50:26     INFO - ==> process 2412 launched child process 4540 ("D:\task_177369191610294\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 6540:40532 -prefMapHandle 6544:306748 -jsInitHandle 6548:162696 -parentBuildID 20260316200754 -ipcHandle 4360 -initialChannelId {ddbaf6df-3b20-4340-af39-6b1bb34d776a} -parentPid 2412 -crashReporter "\\.\pipe\gecko-crash-server-pipe.2412" -crashHelper 6168 -win32kLockedDown -appDir "D:\task_177369191610294\build\application\firefox\browser" - 11 tab)
[task 2026-03-16T20:50:26.642+00:00] 20:50:26     INFO - ==> process 2412 launched child process 3328 ("D:\task_177369191610294\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 6720:40606 -prefMapHandle 6724:306748 -jsInitHandle 6728:162696 -parentBuildID 20260316200754 -ipcHandle 6736 -initialChannelId {f80f1650-cb2c-4fd0-98ef-98a4f30faaae} -parentPid 2412 -crashReporter "\\.\pipe\gecko-crash-server-pipe.2412" -crashHelper 6744 -win32kLockedDown -appDir "D:\task_177369191610294\build\application\firefox\browser" - 12 tab)
[task 2026-03-16T20:50:26.643+00:00] 20:50:26     INFO - ==> process 2412 launched child process 8648 ("D:\task_177369191610294\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 6536:40606 -prefMapHandle 6532:306748 -jsInitHandle 6916:162696 -parentBuildID 20260316200754 -ipcHandle 6928 -initialChannelId {e174ec61-a274-4126-947b-41998617bece} -parentPid 2412 -crashReporter "\\.\pipe\gecko-crash-server-pipe.2412" -crashHelper 6424 -win32kLockedDown -appDir "D:\task_177369191610294\build\application\firefox\browser" - 13 tab)
[task 2026-03-16T20:50:26.644+00:00] 20:50:26     INFO - ==> process 2412 launched child process 2216 ("D:\task_177369191610294\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 6964:40606 -prefMapHandle 6536:306748 -jsInitHandle 6532:162696 -parentBuildID 20260316200754 -ipcHandle 7128 -initialChannelId {157f8323-0f1d-4406-b29f-41699520687a} -parentPid 2412 -crashReporter "\\.\pipe\gecko-crash-server-pipe.2412" -crashHelper 7156 -win32kLockedDown -appDir "D:\task_177369191610294\build\application\firefox\browser" - 14 tab)
[task 2026-03-16T20:50:26.645+00:00] 20:50:26     INFO - ==> process 2412 launched child process 9128 ("D:\task_177369191610294\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 6624:40748 -prefMapHandle 6664:306748 -jsInitHandle 6440:162696 -parentBuildID 20260316200754 -ipcHandle 6736 -initialChannelId {290ac2a3-3651-4696-8daf-be71c62c0f61} -parentPid 2412 -crashReporter "\\.\pipe\gecko-crash-server-pipe.2412" -crashHelper 6876 -win32kLockedDown -appDir "D:\task_177369191610294\build\application\firefox\browser" - 15 tab)
[task 2026-03-16T20:50:26.646+00:00] 20:50:26     INFO - ==> process 2412 launched child process 7304 ("D:\task_177369191610294\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 7104:40748 -prefMapHandle 7340:306748 -jsInitHandle 6436:162696 -parentBuildID 20260316200754 -ipcHandle 5792 -initialChannelId {869a8ca0-3eda-4962-9117-30377c0e371e} -parentPid 2412 -crashReporter "\\.\pipe\gecko-crash-server-pipe.2412" -crashHelper 5772 -win32kLockedDown -appDir "D:\task_177369191610294\build\application\firefox\browser" - 16 tab)
[task 2026-03-16T20:50:26.647+00:00] 20:50:26     INFO - ==> process 2412 launched child process 1372 ("D:\task_177369191610294\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 7320:40787 -prefMapHandle 7328:306748 -jsInitHandle 7312:162696 -parentBuildID 20260316200754 -ipcHandle 6516 -initialChannelId {f23e45ee-e66b-49d1-be0a-6396c45c12b0} -parentPid 2412 -crashReporter "\\.\pipe\gecko-crash-server-pipe.2412" -crashHelper 6876 -win32kLockedDown -appDir "D:\task_177369191610294\build\application\firefox\browser" - 17 tab)
[task 2026-03-16T20:50:26.648+00:00] 20:50:26     INFO - ==> process 2412 launched child process 5808 ("D:\task_177369191610294\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 6708:40787 -prefMapHandle 6852:306748 -jsInitHandle 6840:162696 -parentBuildID 20260316200754 -ipcHandle 7836 -initialChannelId {7ff30a0c-75c3-4e17-aff4-0ddfb3e83d57} -parentPid 2412 -crashReporter "\\.\pipe\gecko-crash-server-pipe.2412" -crashHelper 6928 -win32kLockedDown -appDir "D:\task_177369191610294\build\application\firefox\browser" - 18 tab)
Keywords: regression
Regressed by: 2023419

:Sylvestre, since you are the author of the regressor, bug 2023419, could you take a look?

For more information, please visit BugBot documentation.

Flags: needinfo?(sledru)

bug 2023419 is unlikely to be a regressor of this bug. That other bug was just changing some comments.

This looks like a race condition; after opening a tab, the expectation is that the test can use middle-mousewheel to click on a link to open in a new tab: https://searchfox.org/firefox-main/rev/af3c77e22f88ca06e1b4ac822c1d056f263a64fe/browser/components/extensions/test/browser/browser_ext_tabs_events.js#553-563

But the test somehow gets stuck and times out. That is a sign that the tab did not open.
Moreover, the console contains TypeError: node.closest is not a function triggered from AutoScrollChild.sys.mjs. While that error is also a bug, it is likely not causing the failure. But the error does show that the attempt to click on an element failed, and that the click attempt did not target an <a> element, but something unrelated.

Component: Untriaged → General
Flags: needinfo?(sledru)
Keywords: regression
No longer regressed by: 2023419
See Also: → 2023741
You need to log in before you can comment on or make changes to this bug.