Closed Bug 1885455 Opened 2 years ago Closed 4 months ago

Intermittent dom/events/test/clipboard/browser_navigator_clipboard_contextmenu_suppression.js | single tracking bug

Categories

(Core :: DOM: Copy & Paste and Drag & Drop, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure, intermittent-testcase)

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


[task 2024-03-14T22:47:05.263Z] 22:47:05     INFO - TEST-PASS | dom/events/test/clipboard/browser_navigator_clipboard_contextmenu_suppression.js | Request should be resolved - 
[task 2024-03-14T22:47:05.264Z] 22:47:05     INFO - Test read in paste event handler
[task 2024-03-14T22:47:05.264Z] 22:47:05     INFO - Trigger paste event by keyboard shortcut
[task 2024-03-14T22:47:05.264Z] 22:47:05     INFO - Buffered messages logged at 22:45:35
[task 2024-03-14T22:47:05.264Z] 22:47:05     INFO - Longer timeout required, waiting longer...  Remaining timeouts: 1
[task 2024-03-14T22:47:05.264Z] 22:47:05     INFO - Buffered messages finished
[task 2024-03-14T22:47:05.265Z] 22:47:05     INFO - TEST-UNEXPECTED-FAIL | dom/events/test/clipboard/browser_navigator_clipboard_contextmenu_suppression.js | Test timed out - 
[task 2024-03-14T22:47:05.265Z] 22:47:05     INFO - GECKO(10568) | Completed ShutdownLeaks collections in process 10568
[task 2024-03-14T22:47:05.265Z] 22:47:05     INFO - TEST-START | Shutdown
[task 2024-03-14T22:47:05.265Z] 22:47:05     INFO - Browser Chrome Test Summary
[task 2024-03-14T22:47:05.265Z] 22:47:05     INFO - Passed:  87
[task 2024-03-14T22:47:05.266Z] 22:47:05     INFO - Failed:  1
[task 2024-03-14T22:47:05.266Z] 22:47:05     INFO - Todo:    0
[task 2024-03-14T22:47:05.266Z] 22:47:05     INFO - Mode:    e10s
[task 2024-03-14T22:47:05.266Z] 22:47:05     INFO - *** End BrowserChrome Test Results ***
[task 2024-03-14T22:47:05.266Z] 22:47:05     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-03-14T22:47:05.267Z] 22:47:05     INFO - TEST-UNEXPECTED-FAIL | dom/events/test/clipboard/browser_navigator_clipboard_contextmenu_suppression.js | Uncaught exception received from previously timed out test bound test_context_menu_suppression_paste_event - AbortError: Actor 'SpecialPowers' destroyed before query 'Spawn' was resolved
[task 2024-03-14T22:47:05.267Z] 22:47:05     INFO - Stack trace:
[task 2024-03-14T22:47:05.267Z] 22:47:05     INFO - unregisterActor@resource://testing-common/SpecialPowersParent.sys.mjs:200:17
[task 2024-03-14T22:47:05.267Z] 22:47:05     INFO - onShutdown@jar:file:///tmp/tmp05n44i4p.mozrunner/extensions/special-powers@mozilla.org.xpi!/api.js:64:25
[task 2024-03-14T22:47:05.267Z] 22:47:05     INFO - ExtensionAPI/<@resource://gre/modules/ExtensionCommon.sys.mjs:364:14
[task 2024-03-14T22:47:05.268Z] 22:47:05     INFO - wrapper@resource://gre/modules/ExtensionCommon.sys.mjs:308:14
[task 2024-03-14T22:47:05.268Z] 22:47:05     INFO - emit@resource://gre/modules/ExtensionCommon.sys.mjs:335:32
[task 2024-03-14T22:47:05.268Z] 22:47:05     INFO - emit@resource://gre/modules/Extension.sys.mjs:3027:25
[task 2024-03-14T22:47:05.268Z] 22:47:05     INFO - shutdown@resource://gre/modules/Extension.sys.mjs:3800:10
[task 2024-03-14T22:47:05.268Z] 22:47:05     INFO - shutdown@resource://gre/modules/Extension.sys.mjs:2724:39
[task 2024-03-14T22:47:05.268Z] 22:47:05     INFO - callBootstrapMethod@resource://gre/modules/addons/XPIProvider.sys.mjs:1850:33
[task 2024-03-14T22:47:05.269Z] 22:47:05     INFO - _shutdown@resource://gre/modules/addons/XPIProvider.sys.mjs:1998:17
[task 2024-03-14T22:47:05.269Z] 22:47:05     INFO - async*shutdown@resource://gre/modules/addons/XPIProvider.sys.mjs:1991:33
[task 2024-03-14T22:47:05.269Z] 22:47:05     INFO - startup/<@resource://gre/modules/addons/XPIProvider.sys.mjs:2664:33
[task 2024-03-14T22:47:05.269Z] 22:47:05     INFO - async*trigger@resource://gre/modules/AsyncShutdown.sys.mjs:754:23
[task 2024-03-14T22:47:05.269Z] 22:47:05     INFO - _wait@resource://gre/modules/AsyncShutdown.sys.mjs:914:15
[task 2024-03-14T22:47:05.269Z] 22:47:05     INFO - wait@resource://gre/modules/AsyncShutdown.sys.mjs:895:34
[task 2024-03-14T22:47:05.269Z] 22:47:05     INFO - observe@resource://gre/modules/AsyncShutdown.sys.mjs:554:10
[task 2024-03-14T22:47:05.269Z] 22:47:05     INFO - testsFinished@chrome://mochikit/content/browser-harness.xhtml:340:28
[task 2024-03-14T22:47:05.270Z] 22:47:05     INFO - Tester_finish@chrome://mochikit/content/browser-test.js:556:10
[task 2024-03-14T22:47:05.270Z] 22:47:05     INFO - timeoutFn@chrome://mochikit/content/browser-test.js:1448:20
[task 2024-03-14T22:47:05.270Z] 22:47:05     INFO - setTimeout handler*SimpleTest_setTimeoutShim@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:922:41
[task 2024-03-14T22:47:05.270Z] 22:47:05     INFO - timeoutFn@chrome://mochikit/content/browser-test.js:1406:52
[task 2024-03-14T22:47:05.270Z] 22:47:05     INFO - setTimeout handler*Tester_execTest@chrome://mochikit/content/browser-test.js:1387:45
[task 2024-03-14T22:47:05.270Z] 22:47:05     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1128:14
[task 2024-03-14T22:47:05.271Z] 22:47:05     INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13
[task 2024-03-14T22:47:05.271Z] 22:47:05     INFO - Entering test bound test_context_menu_suppression_paste_event
[task 2024-03-14T22:47:05.306Z] 22:47:05     INFO - GECKO(10568) | 1710456425304	Marionette	TRACE	Received observer notification quit-application
[task 2024-03-14T22:47:05.307Z] 22:47:05     INFO - GECKO(10568) | 1710456425305	Marionette	INFO	Stopped listening on port 2828
[task 2024-03-14T22:47:05.320Z] 22:47:05     INFO - GECKO(10568) | 1710456425312	Marionette	DEBUG	Marionette stopped listening
[task 2024-03-14T22:47:05.416Z] 22:47:05     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-03-14T22:47:05.417Z] 22:47:05     INFO - TEST-UNEXPECTED-FAIL | dom/events/test/clipboard/browser_navigator_clipboard_contextmenu_suppression.js | Uncaught exception received from previously timed out test bound test_context_menu_suppression_paste_event - at resource://testing-common/BrowserTestUtils.sys.mjs:538 - Error: The window unloaded while we were waiting for the browser to load - this should never happen.
[task 2024-03-14T22:47:05.417Z] 22:47:05     INFO - Stack trace:
[task 2024-03-14T22:47:05.418Z] 22:47:05     INFO - listener@resource://testing-common/BrowserTestUtils.sys.mjs:538:15
[task 2024-03-14T22:47:05.418Z] 22:47:05     INFO - EventListener.handleEvent*browserLoaded/<@resource://testing-common/BrowserTestUtils.sys.mjs:553:27
[task 2024-03-14T22:47:05.418Z] 22:47:05     INFO - browserLoaded@resource://testing-common/BrowserTestUtils.sys.mjs:503:12
[task 2024-03-14T22:47:05.418Z] 22:47:05     INFO - openNewForegroundTab@resource://testing-common/BrowserTestUtils.sys.mjs:260:40
[task 2024-03-14T22:47:05.418Z] 22:47:05     INFO - withNewTab@resource://testing-common/BrowserTestUtils.sys.mjs:142:38
[task 2024-03-14T22:47:05.418Z] 22:47:05     INFO - test_context_menu_suppression_paste_event@chrome://mochitests/content/browser/dom/events/test/clipboard/browser_navigator_clipboard_contextmenu_suppression.js:266:28
[task 2024-03-14T22:47:05.418Z] 22:47:05     INFO - handleTask@chrome://mochikit/content/browser-test.js:1139:26
[task 2024-03-14T22:47:05.418Z] 22:47:05     INFO - _runTaskBasedTest@chrome://mochikit/content/browser-test.js:1211:18
[task 2024-03-14T22:47:05.418Z] 22:47:05     INFO - async*Tester_execTest@chrome://mochikit/content/browser-test.js:1353:14
[task 2024-03-14T22:47:05.418Z] 22:47:05     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1128:14
[task 2024-03-14T22:47:05.418Z] 22:47:05     INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13
[task 2024-03-14T22:47:05.419Z] 22:47:05     INFO - Entering test bound test_context_menu_suppression_paste_event
[task 2024-03-14T22:47:05.420Z] 22:47:05     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-03-14T22:47:05.421Z] 22:47:05     INFO - TEST-UNEXPECTED-FAIL | dom/events/test/clipboard/browser_navigator_clipboard_contextmenu_suppression.js | Uncaught exception received from previously timed out test bound test_context_menu_suppression_paste_event - at resource://testing-common/BrowserTestUtils.sys.mjs:138 - TypeError: can't access property "gBrowser", Services.wm.getMostRecentWindow(...) is null
[task 2024-03-14T22:47:05.421Z] 22:47:05     INFO - Stack trace:
[task 2024-03-14T22:47:05.421Z] 22:47:05     INFO - withNewTab@resource://testing-common/BrowserTestUtils.sys.mjs:138:31
[task 2024-03-14T22:47:05.421Z] 22:47:05     INFO - test_context_menu_suppression_paste_event@chrome://mochitests/content/browser/dom/events/test/clipboard/browser_navigator_clipboard_contextmenu_suppression.js:266:28
[task 2024-03-14T22:47:05.422Z] 22:47:05     INFO - handleTask@chrome://mochikit/content/browser-test.js:1139:26
[task 2024-03-14T22:47:05.422Z] 22:47:05     INFO - _runTaskBasedTest@chrome://mochikit/content/browser-test.js:1211:18
[task 2024-03-14T22:47:05.422Z] 22:47:05     INFO - async*Tester_execTest@chrome://mochikit/content/browser-test.js:1353:14
[task 2024-03-14T22:47:05.422Z] 22:47:05     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1128:14
[task 2024-03-14T22:47:05.422Z] 22:47:05     INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13
[task 2024-03-14T22:47:05.458Z] 22:47:05     INFO - GECKO(10568) | Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Failed to connect WebRenderBridgeChild. isParent=false (t=0.23335) [GFX1-]: Failed to connect WebRenderBridgeChild. isParent=false
[task 2024-03-14T22:47:08.295Z] 22:47:08     INFO - GECKO(10568) | [Parent 10568, IPC I/O Parent] WARNING: Process 10641 may be hanging at shutdown; will wait for up to 40000ms: file /builds/worker/checkouts/gecko/ipc/chromium/src/chrome/common/process_watcher_posix_sigchld.cc:184
[task 2024-03-14T22:47:09.644Z] 22:47:09     INFO - GECKO(10568) | -----------------------------------------------------
[task 2024-03-14T22:47:09.645Z] 22:47:09     INFO - GECKO(10568) | Suppressions used:
[task 2024-03-14T22:47:09.645Z] 22:47:09     INFO - GECKO(10568) |   count      bytes template
[task 2024-03-14T22:47:09.646Z] 22:47:09     INFO - GECKO(10568) |      31      16288 nsComponentManagerImpl
[task 2024-03-14T22:47:09.647Z] 22:47:09     INFO - GECKO(10568) |       2        288 libfontconfig.so
[task 2024-03-14T22:47:09.648Z] 22:47:09     INFO - GECKO(10568) |       1       9248 style::sharing::SHARING_CACHE_KEY
[task 2024-03-14T22:47:09.648Z] 22:47:09     INFO - GECKO(10568) |       1       4112 style::bloom::BLOOM_KEY
[task 2024-03-14T22:47:09.648Z] 22:47:09     INFO - GECKO(10568) | -----------------------------------------------------
[task 2024-03-14T22:47:09.803Z] 22:47:09     INFO - GECKO(10568) | -----------------------------------------------------
[task 2024-03-14T22:47:09.803Z] 22:47:09     INFO - GECKO(10568) | Suppressions used:
[task 2024-03-14T22:47:09.804Z] 22:47:09     INFO - GECKO(10568) |   count      bytes template
[task 2024-03-14T22:47:09.804Z] 22:47:09     INFO - GECKO(10568) |      31      16288 nsComponentManagerImpl
[task 2024-03-14T22:47:09.805Z] 22:47:09     INFO - GECKO(10568) |       2        288 libfontconfig.so
[task 2024-03-14T22:47:09.805Z] 22:47:09     INFO - GECKO(10568) |       1       9248 style::sharing::SHARING_CACHE_KEY
[task 2024-03-14T22:47:09.806Z] 22:47:09     INFO - GECKO(10568) |       1       4112 style::bloom::BLOOM_KEY
[task 2024-03-14T22:47:09.806Z] 22:47:09     INFO - GECKO(10568) | -----------------------------------------------------
[task 2024-03-14T22:47:09.896Z] 22:47:09     INFO - GECKO(10568) | [Parent 10568, IPC I/O Parent] WARNING: Process 11733 may be hanging at shutdown; will wait for up to 38400ms: file /builds/worker/checkouts/gecko/ipc/chromium/src/chrome/common/process_watcher_posix_sigchld.cc:184
[task 2024-03-14T22:47:09.953Z] 22:47:09     INFO - GECKO(10568) | -----------------------------------------------------
[task 2024-03-14T22:47:09.954Z] 22:47:09     INFO - GECKO(10568) | Suppressions used:
[task 2024-03-14T22:47:09.955Z] 22:47:09     INFO - GECKO(10568) |   count      bytes template
[task 2024-03-14T22:47:09.955Z] 22:47:09     INFO - GECKO(10568) |      31      16288 nsComponentManagerImpl
[task 2024-03-14T22:47:09.956Z] 22:47:09     INFO - GECKO(10568) |       2        288 libfontconfig.so
[task 2024-03-14T22:47:09.957Z] 22:47:09     INFO - GECKO(10568) | -----------------------------------------------------
[task 2024-03-14T22:47:09.968Z] 22:47:09     INFO - GECKO(10568) | -----------------------------------------------------
[task 2024-03-14T22:47:09.969Z] 22:47:09     INFO - GECKO(10568) | Suppressions used:
[task 2024-03-14T22:47:09.970Z] 22:47:09     INFO - GECKO(10568) |   count      bytes template
[task 2024-03-14T22:47:09.970Z] 22:47:09     INFO - GECKO(10568) |      31      16288 nsComponentManagerImpl
[task 2024-03-14T22:47:09.971Z] 22:47:09     INFO - GECKO(10568) |       2        288 libfontconfig.so
[task 2024-03-14T22:47:09.972Z] 22:47:09     INFO - GECKO(10568) | -----------------------------------------------------
[task 2024-03-14T22:47:10.061Z] 22:47:10     INFO - GECKO(10568) | -----------------------------------------------------
[task 2024-03-14T22:47:10.061Z] 22:47:10     INFO - GECKO(10568) | Suppressions used:
[task 2024-03-14T22:47:10.061Z] 22:47:10     INFO - GECKO(10568) |   count      bytes template
[task 2024-03-14T22:47:10.061Z] 22:47:10     INFO - GECKO(10568) |      31      16288 nsComponentManagerImpl
[task 2024-03-14T22:47:10.061Z] 22:47:10     INFO - GECKO(10568) |       2        288 libfontconfig.so
[task 2024-03-14T22:47:10.061Z] 22:47:10     INFO - GECKO(10568) | -----------------------------------------------------
[task 2024-03-14T22:47:10.076Z] 22:47:10     INFO - GECKO(10568) | -----------------------------------------------------
[task 2024-03-14T22:47:10.076Z] 22:47:10     INFO - GECKO(10568) | Suppressions used:
[task 2024-03-14T22:47:10.076Z] 22:47:10     INFO - GECKO(10568) |   count      bytes template
[task 2024-03-14T22:47:10.076Z] 22:47:10     INFO - GECKO(10568) |      31      16288 nsComponentManagerImpl
[task 2024-03-14T22:47:10.077Z] 22:47:10     INFO - GECKO(10568) |       2        288 libfontconfig.so
[task 2024-03-14T22:47:10.077Z] 22:47:10     INFO - GECKO(10568) | -----------------------------------------------------
[task 2024-03-14T22:47:10.125Z] 22:47:10     INFO - GECKO(10568) | -----------------------------------------------------
[task 2024-03-14T22:47:10.125Z] 22:47:10     INFO - GECKO(10568) | Suppressions used:
[task 2024-03-14T22:47:10.125Z] 22:47:10     INFO - GECKO(10568) |   count      bytes template
[task 2024-03-14T22:47:10.125Z] 22:47:10     INFO - GECKO(10568) |      31      16288 nsComponentManagerImpl
[task 2024-03-14T22:47:10.125Z] 22:47:10     INFO - GECKO(10568) |       2        288 libfontconfig.so
[task 2024-03-14T22:47:10.125Z] 22:47:10     INFO - GECKO(10568) | -----------------------------------------------------
[task 2024-03-14T22:47:10.154Z] 22:47:10     INFO - GECKO(10568) | -----------------------------------------------------
[task 2024-03-14T22:47:10.154Z] 22:47:10     INFO - GECKO(10568) | Suppressions used:
[task 2024-03-14T22:47:10.154Z] 22:47:10     INFO - GECKO(10568) |   count      bytes template
[task 2024-03-14T22:47:10.154Z] 22:47:10     INFO - GECKO(10568) |      31      16288 nsComponentManagerImpl
[task 2024-03-14T22:47:10.154Z] 22:47:10     INFO - GECKO(10568) |       2        288 libfontconfig.so
[task 2024-03-14T22:47:10.154Z] 22:47:10     INFO - GECKO(10568) |       1       9248 style::sharing::SHARING_CACHE_KEY
[task 2024-03-14T22:47:10.154Z] 22:47:10     INFO - GECKO(10568) |       1       4112 style::bloom::BLOOM_KEY
[task 2024-03-14T22:47:10.155Z] 22:47:10     INFO - GECKO(10568) | -----------------------------------------------------
[task 2024-03-14T22:47:10.163Z] 22:47:10     INFO - GECKO(10568) | -----------------------------------------------------
[task 2024-03-14T22:47:10.163Z] 22:47:10     INFO - GECKO(10568) | Suppressions used:
[task 2024-03-14T22:47:10.163Z] 22:47:10     INFO - GECKO(10568) |   count      bytes template
[task 2024-03-14T22:47:10.163Z] 22:47:10     INFO - GECKO(10568) |      31      16288 nsComponentManagerImpl
[task 2024-03-14T22:47:10.163Z] 22:47:10     INFO - GECKO(10568) |       2        288 libfontconfig.so
[task 2024-03-14T22:47:10.163Z] 22:47:10     INFO - GECKO(10568) |       1       9248 style::sharing::SHARING_CACHE_KEY
[task 2024-03-14T22:47:10.163Z] 22:47:10     INFO - GECKO(10568) |       1       4112 style::bloom::BLOOM_KEY
[task 2024-03-14T22:47:10.163Z] 22:47:10     INFO - GECKO(10568) | -----------------------------------------------------
[task 2024-03-14T22:47:10.199Z] 22:47:10     INFO - GECKO(10568) | -----------------------------------------------------
[task 2024-03-14T22:47:10.200Z] 22:47:10     INFO - GECKO(10568) | Suppressions used:
[task 2024-03-14T22:47:10.202Z] 22:47:10     INFO - GECKO(10568) |   count      bytes template
[task 2024-03-14T22:47:10.202Z] 22:47:10     INFO - GECKO(10568) |      31      16288 nsComponentManagerImpl
[task 2024-03-14T22:47:10.202Z] 22:47:10     INFO - GECKO(10568) |       2        288 libfontconfig.so
[task 2024-03-14T22:47:10.202Z] 22:47:10     INFO - GECKO(10568) |       1       9248 style::sharing::SHARING_CACHE_KEY
[task 2024-03-14T22:47:10.202Z] 22:47:10     INFO - GECKO(10568) |       1       4112 style::bloom::BLOOM_KEY
[task 2024-03-14T22:47:10.202Z] 22:47:10     INFO - GECKO(10568) | -----------------------------------------------------
[task 2024-03-14T22:47:10.898Z] 22:47:10     INFO - GECKO(10568) | -----------------------------------------------------
[task 2024-03-14T22:47:10.899Z] 22:47:10     INFO - GECKO(10568) | Suppressions used:
[task 2024-03-14T22:47:10.899Z] 22:47:10     INFO - GECKO(10568) |   count      bytes template
[task 2024-03-14T22:47:10.899Z] 22:47:10     INFO - GECKO(10568) |       3         96 nsComponentManagerImpl
[task 2024-03-14T22:47:10.900Z] 22:47:10     INFO - GECKO(10568) |     633      18115 libfontconfig.so
[task 2024-03-14T22:47:10.900Z] 22:47:10     INFO - GECKO(10568) |      40      20329 libglib-2.0.so
[task 2024-03-14T22:47:10.900Z] 22:47:10     INFO - GECKO(10568) | -----------------------------------------------------
[task 2024-03-14T22:47:10.959Z] 22:47:10     INFO - TEST-INFO | Main app process: exit 0

edgar, this seems to be coming up somewhat often - might be worth looking into what's going wrong with the clipboard stuff?

Flags: needinfo?(echen)

It is reproduced by Pernosco, let me check if I can find anything from the Pernosco session: https://pernos.co/debug/LE-7RNT0FtwcaM7Y8PM-TA/index.html.

From the Pernosco session, the test timed out because test added the listener after the paste event has already been dispatched here.

Depends on: 1967450
Flags: needinfo?(echen)
Component: DOM: UI Events & Focus Handling → DOM: Copy & Paste and Drag & Drop
See Also: → 1989339

The spike is actually bug 1989339

Mostl(In reply to Intermittent Failures Robot from comment #89)

16 failures were associated with this bug in the last 7 days.

Repository breakdown:

  • mozilla-release: 9
  • mozilla-beta: 4
  • autoland: 3

Table

msix no_variant spi-nw
windows11-x86/opt 3
windows11-x86_64/opt 8 3 2

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1885455&startday=2025-09-29&endday=2025-10-05&tree=all

Mostly are on beta and release before we uplift the patch in bug 1989339.

Status: NEW → RESOLVED
Closed: 4 months ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.