Open Bug 1926771 Opened 2 days ago Updated 6 hours ago

Intermittent toolkit/components/extensions/test/mochitest/test_ext_clipboard_image.html | single tracking bug

Categories

(WebExtensions :: General, defect, P5)

defect

Tracking

(firefox-esr128 unaffected, firefox131 unaffected, firefox132 unaffected, firefox133 fixed)

133 Branch
Tracking Status
firefox-esr128 --- unaffected
firefox131 --- unaffected
firefox132 --- unaffected
firefox133 --- fixed

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regressed 1 open bug, Regression)

Details

(Keywords: intermittent-failure, intermittent-testcase, regression)

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


[task 2024-10-24T05:04:48.985Z] 05:04:48     INFO - TEST-START | toolkit/components/extensions/test/mochitest/test_ext_clipboard.html
[task 2024-10-24T05:04:49.191Z] 05:04:49     INFO - GECKO(21836) | Console message: [JavaScript Warning: "document.execCommand(‘cut’/‘copy’) was denied because it was not called from inside a short running user-generated event handler." {file: "moz-extension://1aca3954-bbcc-44bb-b1a5-4c1b28d482c0/%7Ba9e5d556-d830-48f4-aa82-19945a8b5dac%7D.js" line: 9}]
[task 2024-10-24T05:04:49.723Z] 05:04:49     INFO - GECKO(21836) | Console message: [JavaScript Warning: "document.execCommand(‘cut’/‘copy’) was denied because it was not called from inside a short running user-generated event handler." {file: "moz-extension://41b9b919-fa4f-4cad-b625-1ba4976c5450/shared.js" line: 9}]
[task 2024-10-24T05:04:50.919Z] 05:04:50     INFO - GECKO(21836) | MEMORY STAT | vsize 10613MB | residentFast 117MB | heapAllocated 16MB
[task 2024-10-24T05:04:50.995Z] 05:04:50     INFO - TEST-OK | toolkit/components/extensions/test/mochitest/test_ext_clipboard.html | took 2011ms
[task 2024-10-24T05:04:51.034Z] 05:04:51     INFO - TEST-START | toolkit/components/extensions/test/mochitest/test_ext_clipboard_image.html
[task 2024-10-24T05:04:51.526Z] 05:04:51     INFO - TEST-INFO | started process screentopng
[task 2024-10-24T05:04:54.245Z] 05:04:54     INFO - TEST-INFO | screentopng: exit 0
[task 2024-10-24T05:04:54.245Z] 05:04:54     INFO - Buffered messages logged at 05:04:51
[task 2024-10-24T05:04:54.246Z] 05:04:54     INFO - add_task | Entering test_without_clipboard_permission
[task 2024-10-24T05:04:54.248Z] 05:04:54     INFO - Extension loaded
[task 2024-10-24T05:04:54.249Z] 05:04:54     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_clipboard_image.html | clipboard API requires the clipboardWrite permission. - Expected: undefined, Actual: undefined 
[task 2024-10-24T05:04:54.251Z] 05:04:54     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_clipboard_image.html | undefined assertion name 
[task 2024-10-24T05:04:54.253Z] 05:04:54     INFO - add_task | Leaving test_without_clipboard_permission
[task 2024-10-24T05:04:54.256Z] 05:04:54     INFO - add_task | Entering test_copy_png
[task 2024-10-24T05:04:54.257Z] 05:04:54     INFO - Extension loaded
[task 2024-10-24T05:04:54.258Z] 05:04:54     INFO - Extension loaded
[task 2024-10-24T05:04:54.258Z] 05:04:54     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_clipboard_image.html | expected one type - Expected: 1, Actual: 1 
[task 2024-10-24T05:04:54.258Z] 05:04:54     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_clipboard_image.html | expected type - Expected: Files, Actual: Files 
[task 2024-10-24T05:04:54.258Z] 05:04:54     INFO - Buffered messages finished
[task 2024-10-24T05:04:54.260Z] 05:04:54     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_clipboard_image.html | expected one file - Expected: 1, Actual: 0 
[task 2024-10-24T05:04:54.261Z] 05:04:54     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:426:16
[task 2024-10-24T05:04:54.261Z] 05:04:54     INFO -     testHandler@SimpleTest/ExtensionTestUtils.js:83:18
[task 2024-10-24T05:04:54.261Z] 05:04:54     INFO -     testResult@SimpleTest/ExtensionTestUtils.js:97:18
[task 2024-10-24T05:04:54.262Z] 05:04:54     INFO -     Async*ExtensionTestUtils.loadExtension@SimpleTest/ExtensionTestUtils.js:128:33
[task 2024-10-24T05:04:54.262Z] 05:04:54     INFO -     checkClipboardHasTestImage@toolkit/components/extensions/test/mochitest/test_ext_clipboard_image.html:110:38
[task 2024-10-24T05:04:54.262Z] 05:04:54     INFO -     test_copy_png@toolkit/components/extensions/test/mochitest/test_ext_clipboard_image.html:160:9
[task 2024-10-24T05:04:54.262Z] 05:04:54     INFO -     async*add_task/nextTick/<@SimpleTest/SimpleTest.js:2183:34
[task 2024-10-24T05:04:54.263Z] 05:04:54     INFO -     async*nextTick@SimpleTest/SimpleTest.js:2227:11
[task 2024-10-24T05:04:54.264Z] 05:04:54     INFO -     setTimeout handler*SimpleTest_setTimeoutShim@SimpleTest/SimpleTest.js:922:41
[task 2024-10-24T05:04:54.264Z] 05:04:54     INFO -     add_task@SimpleTest/SimpleTest.js:2131:17
[task 2024-10-24T05:04:54.264Z] 05:04:54     INFO -     @toolkit/components/extensions/test/mochitest/test_ext_clipboard_image.html:121:9
[task 2024-10-24T05:04:54.265Z] 05:04:54     INFO - GECKO(21836) | JavaScript error: moz-extension://4ff21fd5-0995-422b-95ec-3f649714c2a1/%7B4230f7c9-d876-4bba-86e7-2681558c5921%7D.js, line 57: TypeError: can't access property "type", file is undefined
[task 2024-10-24T05:04:54.266Z] 05:04:54     INFO - GECKO(21836) | Console message: [JavaScript Error: "TypeError: can't access property "type", file is undefined" {file: "moz-extension://4ff21fd5-0995-422b-95ec-3f649714c2a1/%7B4230f7c9-d876-4bba-86e7-2681558c5921%7D.js" line: 57}]
[task 2024-10-24T05:04:54.268Z] 05:04:54     INFO - GECKO(21836) | backgroundScript@moz-extension://4ff21fd5-0995-422b-95ec-3f649714c2a1/%7B4230f7c9-d876-4bba-86e7-2681558c5921%7D.js:57:40
[task 2024-10-24T05:04:54.269Z] 05:04:54     INFO - GECKO(21836) | async*@moz-extension://4ff21fd5-0995-422b-95ec-3f649714c2a1/%7B4230f7c9-d876-4bba-86e7-2681558c5921%7D.js:81:5
[task 2024-10-24T05:04:54.897Z] 05:04:54     INFO - GECKO(21836) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmprukz19p4.mozrunner/runtests_leaks_tab_pid22600.log
[task 2024-10-24T05:04:54.922Z] 05:04:54     INFO - GECKO(21836) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmprukz19p4.mozrunner/runtests_leaks_tab_pid22606.log
[task 2024-10-24T05:04:54.923Z] 05:04:54     INFO - GECKO(21836) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmprukz19p4.mozrunner/runtests_leaks_tab_pid22608.log
[task 2024-10-24T05:10:03.157Z] 05:10:03     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-10-24T05:10:03.159Z] 05:10:03     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_clipboard_image.html | Test timed out. - 
[task 2024-10-24T05:10:04.158Z] 05:10:04     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-10-24T05:10:04.164Z] 05:10:04     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_clipboard_image.html | Extension left running at test shutdown 
[task 2024-10-24T05:10:04.164Z] 05:10:04     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:426:16
[task 2024-10-24T05:10:04.164Z] 05:10:04     INFO -     ExtensionTestUtils.loadExtension/<@SimpleTest/ExtensionTestUtils.js:132:18
[task 2024-10-24T05:10:04.164Z] 05:10:04     INFO -     executeCleanupFunction@SimpleTest/SimpleTest.js:1562:13
[task 2024-10-24T05:10:04.164Z] 05:10:04     INFO -     SimpleTest.finish@SimpleTest/SimpleTest.js:1576:3
[task 2024-10-24T05:10:04.164Z] 05:10:04     INFO -     killTest@SimpleTest/TestRunner.js:200:22
[task 2024-10-24T05:10:04.164Z] 05:10:04     INFO -     async*delayedKillTest@SimpleTest/TestRunner.js:243:17
[task 2024-10-24T05:10:04.164Z] 05:10:04     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:241:17
[task 2024-10-24T05:10:04.164Z] 05:10:04     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:255:15
[task 2024-10-24T05:10:04.164Z] 05:10:04     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:255:15
[task 2024-10-24T05:10:04.164Z] 05:10:04     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:255:15
[task 2024-10-24T05:10:04.164Z] 05:10:04     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:255:15
[task 2024-10-24T05:10:04.164Z] 05:10:04     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:255:15
[task 2024-10-24T05:10:04.164Z] 05:10:04     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:255:15
[task 2024-10-24T05:10:04.164Z] 05:10:04     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:255:15
[task 2024-10-24T05:10:04.164Z] 05:10:04     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:255:15
[task 2024-10-24T05:10:04.164Z] 05:10:04     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:255:15
[task 2024-10-24T05:10:04.164Z] 05:10:04     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:255:15
[task 2024-10-24T05:10:04.164Z] 05:10:04     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:255:15
[task 2024-10-24T05:10:04.164Z] 05:10:04     INFO -     TestRunner.runTests/<@SimpleTest/TestRunner.js:535:16
[task 2024-10-24T05:10:04.164Z] 05:10:04     INFO -     Async*TestRunner.runTests@SimpleTest/TestRunner.js:522:48
[task 2024-10-24T05:10:04.164Z] 05:10:04     INFO -     RunSet.runtests@SimpleTest/setup.js:302:14
[task 2024-10-24T05:10:04.165Z] 05:10:04     INFO -     RunSet.runall@SimpleTest/setup.js:281:12
[task 2024-10-24T05:10:04.165Z] 05:10:04     INFO -     getPrefList/<@SimpleTest/setup.js:368:14
[task 2024-10-24T05:10:04.165Z] 05:10:04     INFO -     loadFile/req.onload@SimpleTest/setup.js:80:19
[task 2024-10-24T05:10:04.165Z] 05:10:04     INFO -     EventHandlerNonNull*loadFile@SimpleTest/setup.js:75:3
[task 2024-10-24T05:10:04.165Z] 05:10:04     INFO -     getPrefList@SimpleTest/setup.js:366:13
[task 2024-10-24T05:10:04.165Z] 05:10:04     INFO -     hookupTests@SimpleTest/setup.js:389:5
[task 2024-10-24T05:10:04.165Z] 05:10:04     INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:53:13
[task 2024-10-24T05:10:04.165Z] 05:10:04     INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:66:28
[task 2024-10-24T05:10:04.165Z] 05:10:04     INFO - EventHandlerNonNull*getTestManifest@http://mochi.test:8888/manifestLibrary.js:62:3
[task 2024-10-24T05:10:04.165Z] 05:10:04     INFO -     hookup@SimpleTest/setup.js:354:20
[task 2024-10-24T05:10:04.165Z] 05:10:04     INFO - EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=%2Ftmp&cleanupCrashes=true&ignorePrefsFile=ignorePrefs.json:10:32
[task 2024-10-24T05:10:04.220Z] 05:10:04     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-10-24T05:10:04.222Z] 05:10:04     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_clipboard_image.html | no tasks awaiting on messages - got "[\"tested image on clipboard\"]", expected "[]"
[task 2024-10-24T05:10:04.222Z] 05:10:04     INFO -     SimpleTest.is@SimpleTest/SimpleTest.js:509:14
[task 2024-10-24T05:10:04.222Z] 05:10:04     INFO -     ExtensionTestUtils.loadExtension/<@SimpleTest/ExtensionTestUtils.js:51:18
[task 2024-10-24T05:10:04.222Z] 05:10:04     INFO -     executeCleanupFunction@SimpleTest/SimpleTest.js:1562:13
[task 2024-10-24T05:10:04.227Z] 05:10:04     INFO - GECKO(21836) | MEMORY STAT | vsize 2423MB | residentFast 110MB | heapAllocated 9MB
[task 2024-10-24T05:10:04.277Z] 05:10:04    ERROR - TEST-UNEXPECTED-FAIL | /tests/toolkit/components/extensions/test/mochitest/test_ext_clipboard_image.html logged result after SimpleTest.finish(): Extension left running at test shutdown
[task 2024-10-24T05:10:04.277Z] 05:10:04    ERROR - TEST-UNEXPECTED-FAIL | /tests/toolkit/components/extensions/test/mochitest/test_ext_clipboard_image.html logged result after SimpleTest.finish(): no tasks awaiting on messages
[task 2024-10-24T05:10:04.329Z] 05:10:04     INFO - TEST-OK | toolkit/components/extensions/test/mochitest/test_ext_clipboard_image.html | took 313296ms
Regressions: 1926772

:gstoll, since you are the author of the regressor, bug 1915351, could you take a look?

For more information, please visit BugBot documentation.

Flags: needinfo?(gstoll)

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

Flags: needinfo?(gstoll)
Target Milestone: --- → 133 Branch
You need to log in before you can comment on or make changes to this bug.