Closed Bug 1764978 Opened 3 years ago Closed 3 years ago

Intermittent TV dom/url/tests/browser_download_after_revoke.js | A promise chain failed to handle a rejection: Actor 'SpecialPowers' destroyed before query 'Spawn' was resolved - stack: ensureDOMPromiseRejectionsProcessed@resource://

Categories

(Firefox :: Settings UI, defect, P5)

Product:
Firefox
Component:
Settings UI
Type:
defect

Tracking

()

Status:
RESOLVED FIXED
Milestone:
101 Branch
Tracking Flags:
Tracking Status
firefox-esr91 --- unaffected
firefox99 --- unaffected
firefox100 --- unaffected
firefox101 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: aminomancer)

References

(Regression)

Details

(Keywords: intermittent-failure, regression)

Attachments

(1 file)

Bug 1764978 - Refactor browser_download_after_revoke.js. r=kershaw
48 bytes, text/x-phabricator-request
Details | Review

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

[task 2022-04-15T18:42:45.828Z] 18:42:45     INFO - TEST-PASS | dom/url/tests/browser_download_after_revoke.js | Download was started - 
[task 2022-04-15T18:42:45.828Z] 18:42:45     INFO - Buffered messages finished
[task 2022-04-15T18:42:45.830Z] 18:42:45     INFO - TEST-UNEXPECTED-FAIL | dom/url/tests/browser_download_after_revoke.js | A promise chain failed to handle a rejection: Actor 'SpecialPowers' destroyed before query 'Spawn' was resolved - stack: ensureDOMPromiseRejectionsProcessed@resource://testing-common/PromiseTestUtils.jsm:120:17
[task 2022-04-15T18:42:45.830Z] 18:42:45     INFO - nextTest@chrome://mochikit/content/browser-test.js:624:29
[task 2022-04-15T18:42:45.830Z] 18:42:45     INFO - testScope/test_finish/<@chrome://mochikit/content/browser-test.js:1613:25
[task 2022-04-15T18:42:45.830Z] 18:42:45     INFO - run@chrome://mochikit/content/browser-test.js:1528:9
[task 2022-04-15T18:42:45.830Z] 18:42:45     INFO - Rejection date: Fri Apr 15 2022 18:42:45 GMT+0000 (Greenwich Mean Time) - false == true - JS frame :: resource://testing-common/PromiseTestUtils.jsm :: assertNoUncaughtRejections :: line 271
[task 2022-04-15T18:42:45.830Z] 18:42:45     INFO - Stack trace:
[task 2022-04-15T18:42:45.830Z] 18:42:45     INFO - resource://testing-common/PromiseTestUtils.jsm:assertNoUncaughtRejections:271
[task 2022-04-15T18:42:45.830Z] 18:42:45     INFO - chrome://mochikit/content/browser-test.js:nextTest:625
[task 2022-04-15T18:42:45.830Z] 18:42:45     INFO - chrome://mochikit/content/browser-test.js:testScope/test_finish/<:1613
[task 2022-04-15T18:42:45.830Z] 18:42:45     INFO - chrome://mochikit/content/browser-test.js:run:1528
[task 2022-04-15T18:42:45.831Z] 18:42:45     INFO - GECKO(8568) | MEMORY STAT | vsize 897MB | vsizeMaxContiguous 1293MB | residentFast 216MB | heapAllocated 95MB
[task 2022-04-15T18:42:45.831Z] 18:42:45     INFO - TEST-OK | dom/url/tests/browser_download_after_revoke.js | took 144ms
[task 2022-04-15T18:42:45.832Z] 18:42:45     INFO - checking window state
[task 2022-04-15T18:42:45.832Z] 18:42:45     INFO - GECKO(8568) | JavaScript error: resource://testing-common/PromiseTestUtils.jsm, line 120: AbortError: Actor 'SpecialPowers' destroyed before query 'Spawn' was resolved
[task 2022-04-15T18:42:45.832Z] 18:42:45     INFO - GECKO(8568) | Completed ShutdownLeaks collections in process 6224
[task 2022-04-15T18:42:45.833Z] 18:42:45     INFO - TEST-START | Shutdown
[task 2022-04-15T18:42:45.833Z] 18:42:45     INFO - Browser Chrome Test Summary
[task 2022-04-15T18:42:45.833Z] 18:42:45     INFO - Passed:  3
[task 2022-04-15T18:42:45.834Z] 18:42:45     INFO - Failed:  1
[task 2022-04-15T18:42:45.834Z] 18:42:45     INFO - Todo:    0
[task 2022-04-15T18:42:45.834Z] 18:42:45     INFO - Mode:    e10s
[task 2022-04-15T18:42:45.835Z] 18:42:45     INFO - *** End BrowserChrome Test Results ***
[task 2022-04-15T18:42:45.835Z] 18:42:45     INFO - GECKO(8568) | Exiting due to channel error.
[task 2022-04-15T18:42:45.835Z] 18:42:45     INFO - TEST-INFO | Main app process: exit 0
[task 2022-04-15T18:42:45.835Z] 18:42:45     INFO - runtests.py | Application ran for: 0:00:04.473181
[task 2022-04-15T18:42:45.836Z] 18:42:45     INFO - zombiecheck | Reading PID log: C:\Users\task_165004286066054\AppData\Local\Temp\tmp4abo5nmxpidlog
[task 2022-04-15T18:42:45.836Z] 18:42:45     INFO - ==> process 6224 launched child process 7224 ("Z:\task_165004286066054\build\application\firefox\firefox.exe" -contentproc --channel="6224.0.1554842289\1568897765" -parentBuildID 20220415181534 -prefsHandle 2072 -prefMapHandle 2064 -prefsLen 1 -prefMapSize 262561 -appDir "Z:\task_165004286066054\build\application\firefox\browser" - 6224 "\\.\pipe\gecko-crash-server-pipe.6224" 2152 13973310 gpu)
[task 2022-04-15T18:42:45.837Z] 18:42:45     INFO - ==> process 6224 launched child process 8180 ("Z:\task_165004286066054\build\application\firefox\firefox.exe" -contentproc --channel="6224.1.1723399211\1649761074" -childID 1 -isForBrowser -prefsHandle 2736 -prefMapHandle 2732 -prefsLen 1641 -prefMapSize 262561 -jsInitHandle 1352 -jsInitLen 280080 -a11yResourceId 64 -parentBuildID 20220415181534 -appDir "Z:\task_165004286066054\build\application\firefox\browser" - 6224 "\\.\pipe\gecko-crash-server-pipe.6224" 2748 16c75890 tab)
[task 2022-04-15T18:42:45.838Z] 18:42:45     INFO - ==> process 6224 launched child process 3272 ("Z:\task_165004286066054\build\application\firefox\firefox.exe" -contentproc --channel="6224.2.1814824379\1480716153" -childID 2 -isForBrowser -prefsHandle 3096 -prefMapHandle 3092 -prefsLen 1746 -prefMapSize 262561 -jsInitHandle 1352 -jsInitLen 280080 -a11yResourceId 64 -parentBuildID 20220415181534 -appDir "Z:\task_165004286066054\build\application\firefox\browser" - 6224 "\\.\pipe\gecko-crash-server-pipe.6224" 3108 16c74550 tab)
[task 2022-04-15T18:42:45.839Z] 18:42:45     INFO - ==> process 6224 launched child process 6556 ("Z:\task_165004286066054\build\application\firefox\firefox.exe" -contentproc --channel="6224.3.244141450\1883088407" -childID 3 -isForBrowser -prefsHandle 3124 -prefMapHandle 3228 -prefsLen 1821 -prefMapSize 262561 -jsInitHandle 1352 -jsInitLen 280080 -a11yResourceId 64 -parentBuildID 20220415181534 -appDir "Z:\task_165004286066054\build\application\firefox\browser" - 6224 "\\.\pipe\gecko-crash-server-pipe.6224" 3388 16c74ad0 tab)
[task 2022-04-15T18:42:45.839Z] 18:42:45     INFO - ==> process 6224 launched child process 5628 ("Z:\task_165004286066054\build\application\firefox\firefox.exe" -contentproc --channel="6224.4.1786599520\565859903" -childID 4 -isForBrowser -prefsHandle 3892 -prefMapHandle 3888 -prefsLen 9039 -prefMapSize 262561 -jsInitHandle 1352 -jsInitLen 280080 -a11yResourceId 64 -parentBuildID 20220415181534 -appDir "Z:\task_165004286066054\build\application\firefox\browser" - 6224 "\\.\pipe\gecko-crash-server-pipe.6224" 3860 16c74d90 tab)
[task 2022-04-15T18:42:45.840Z] 18:42:45     INFO - ==> process 6224 launched child process 7584 ("Z:\task_165004286066054\build\application\firefox\firefox.exe" -contentproc --channel="6224.5.1162075231\1429717737" -childID 5 -isForBrowser -prefsHandle 4052 -prefMapHandle 4056 -prefsLen 9067 -prefMapSize 262561 -jsInitHandle 1352 -jsInitLen 280080 -a11yResourceId 64 -parentBuildID 20220415181534 -appDir "Z:\task_165004286066054\build\application\firefox\browser" - 6224 "\\.\pipe\gecko-crash-server-pipe.6224" 3932 16c74c30 tab)
[task 2022-04-15T18:42:45.841Z] 18:42:45     INFO - ==> process 6224 launched child process 7460 ("Z:\task_165004286066054\build\application\firefox\firefox.exe" -contentproc --channel="6224.6.249623243\1355814364" -childID 6 -isForBrowser -prefsHandle 4032 -prefMapHandle 4044 -prefsLen 9067 -prefMapSize 262561 -jsInitHandle 1352 -jsInitLen 280080 -a11yResourceId 64 -parentBuildID 20220415181534 -appDir "Z:\task_165004286066054\build\application\firefox\browser" - 6224 "\\.\pipe\gecko-crash-server-pipe.6224" 4168 1fcfdc30 tab)
[task 2022-04-15T18:42:45.842Z] 18:42:45     INFO - ==> process 6224 launched child process 5768 ("Z:\task_165004286066054\build\application\firefox\firefox.exe" -contentproc --channel="6224.7.1735933518\1441123751" -childID 7 -isForBrowser -prefsHandle 4312 -prefMapHandle 4396 -prefsLen 9067 -prefMapSize 262561 -jsInitHandle 1352 -jsInitLen 280080 -a11yResourceId 64 -parentBuildID 20220415181534 -appDir "Z:\task_165004286066054\build\application\firefox\browser" - 6224 "\\.\pipe\gecko-crash-server-pipe.6224" 4332 16c75f70 tab)
[task 2022-04-15T18:42:45.842Z] 18:42:45     INFO - ==> process 6224 launched child process 4812 ("Z:\task_165004286066054\build\application\firefox\firefox.exe" -contentproc --channel="6224.8.1505272388\509367942" -childID 8 -isForBrowser -prefsHandle 4616 -prefMapHandle 4592 -prefsLen 9957 -prefMapSize 262561 -jsInitHandle 1352 -jsInitLen 280080 -a11yResourceId 64 -parentBuildID 20220415181534 -appDir "Z:\task_165004286066054\build\application\firefox\browser" - 6224 "\\.\pipe\gecko-crash-server-pipe.6224" 5048 16c75f70 tab)
[task 2022-04-15T18:42:45.843Z] 18:42:45     INFO - ==> process 6224 launched child process 1612 ("Z:\task_165004286066054\build\application\firefox\firefox.exe" -contentproc --channel="6224.9.299119088\998446033" -childID 9 -isForBrowser -prefsHandle 5692 -prefMapHandle 5680 -prefsLen 10010 -prefMapSize 262561 -jsInitHandle 1352 -jsInitLen 280080 -a11yResourceId 64 -parentBuildID 20220415181534 -appDir "Z:\task_165004286066054\build\application\firefox\browser" - 6224 "\\.\pipe\gecko-crash-server-pipe.6224" 5580 16c75f70 tab)
[task 2022-04-15T18:42:45.844Z] 18:42:45     INFO - zombiecheck | Checking for orphan process with PID: 7584
[task 2022-04-15T18:42:45.844Z] 18:42:45     INFO - zombiecheck | Checking for orphan process with PID: 7460
[task 2022-04-15T18:42:45.844Z] 18:42:45     INFO - zombiecheck | Checking for orphan process with PID: 3272
[task 2022-04-15T18:42:45.844Z] 18:42:45     INFO - zombiecheck | Checking for orphan process with PID: 5768
[task 2022-04-15T18:42:45.845Z] 18:42:45     INFO - zombiecheck | Checking for orphan process with PID: 4812
[task 2022-04-15T18:42:45.845Z] 18:42:45     INFO - zombiecheck | Checking for orphan process with PID: 1612
[task 2022-04-15T18:42:45.845Z] 18:42:45     INFO - zombiecheck | Checking for orphan process with PID: 5628
[task 2022-04-15T18:42:45.846Z] 18:42:45     INFO - zombiecheck | Checking for orphan process with PID: 8180
[task 2022-04-15T18:42:45.846Z] 18:42:45     INFO - zombiecheck | Checking for orphan process with PID: 7224
[task 2022-04-15T18:42:45.847Z] 18:42:45     INFO - zombiecheck | Checking for orphan process with PID: 6556
[task 2022-04-15T18:42:45.847Z] 18:42:45     INFO - Stopping web server
[task 2022-04-15T18:42:45.848Z] 18:42:45     INFO - Server shut down.
[task 2022-04-15T18:42:45.876Z] 18:42:45     INFO - Web server killed.
[task 2022-04-15T18:42:45.876Z] 18:42:45     INFO - Stopping web socket server
[task 2022-04-15T18:42:45.920Z] 18:42:45     INFO - Stopping ssltunnel
[task 2022-04-15T18:42:45.952Z] 18:42:45  WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2022-04-15T18:42:45.952Z] 18:42:45     INFO - runtests.py | Running tests: end.
[task 2022-04-15T18:42:46.003Z] 18:42:46     INFO - Buffered messages finished
[task 2022-04-15T18:42:46.015Z] 18:42:46     INFO -  TEST-INFO | checking window state
[task 2022-04-15T18:42:46.015Z] 18:42:46     INFO -  Browser Chrome Test Summary
[task 2022-04-15T18:42:46.015Z] 18:42:46     INFO -  	Passed: 3
[task 2022-04-15T18:42:46.015Z] 18:42:46     INFO -  	Failed: 1
[task 2022-04-15T18:42:46.015Z] 18:42:46     INFO -  	Todo: 0
[task 2022-04-15T18:42:46.015Z] 18:42:46     INFO -  	Mode: e10s
[task 2022-04-15T18:42:46.015Z] 18:42:46     INFO -  *** End BrowserChrome Test Results ***
[task 2022-04-15T18:42:46.015Z] 18:42:46     INFO - Buffered messages finished
[task 2022-04-15T18:42:46.016Z] 18:42:46     INFO - SUITE-END | took 6s
[task 2022-04-15T18:42:46.016Z] 18:42:46     INFO - SUITE-START | Running 1 tests
[task 2022-04-15T18:42:46.016Z] 18:42:46     INFO - :::
[task 2022-04-15T18:42:46.017Z] 18:42:46     INFO - ::: Test verification summary for:
[task 2022-04-15T18:42:46.017Z] 18:42:46     INFO - :::
[task 2022-04-15T18:42:46.017Z] 18:42:46     INFO - ::: dom/url/tests/browser_download_after_revoke.js
[task 2022-04-15T18:42:46.017Z] 18:42:46     INFO - :::
[task 2022-04-15T18:42:46.018Z] 18:42:46     INFO - ::: 1. Run each test 10 times in one browser. : FAIL
[task 2022-04-15T18:42:46.018Z] 18:42:46     INFO - ::: 2. Run each test 5 times in a new browser each time. : not run / incomplete
[task 2022-04-15T18:42:46.018Z] 18:42:46     INFO - ::: 3. Run each test 10 times in one browser, in chaos mode. : not run / incomplete
[task 2022-04-15T18:42:46.019Z] 18:42:46     INFO - ::: 4. Run each test 5 times in a new browser each time, in chaos mode. : not run / incomplete
[task 2022-04-15T18:42:46.019Z] 18:42:46     INFO - :::
[task 2022-04-15T18:42:46.019Z] 18:42:46     INFO - ::: Test verification FAILED!
[task 2022-04-15T18:42:46.019Z] 18:42:46     INFO - :::
[task 2022-04-15T18:42:46.020Z] 18:42:46     INFO - Buffered messages finished
[task 2022-04-15T18:42:46.020Z] 18:42:46     INFO - SUITE-END | took 0s
[task 2022-04-15T18:42:46.049Z] 18:42:46     INFO - Return code: 0
[task 2022-04-15T18:42:46.049Z] 18:42:46    ERROR - Got 1 unexpected statuses

Hi Shane! I think this might be caused by the recent changes in Bug 1747343. Can you please take a look?
Thank you!

Flags: needinfo?(shmediaproductions)

Thanks for letting me know. This is really mystifying me, I can't figure out what is going on. Is there any way to see history of failures of this test before my patch? Maybe someone more experienced with the test APIs will have some better insights. I would ask Gijs since he helped me with these tests last week, but I think he is away for a while.

Flags: needinfo?(shmediaproductions)
Regressed by: 1747343

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

status-firefox100: --- → unaffected
status-firefox101: --- → affected
status-firefox99: --- → unaffected
status-firefox-esr91: --- → unaffected
Has Regression Range: --- → yes

Hi Shane,

Could you take a look?

Thanks.

Flags: needinfo?(shmediaproductions)

I'm trying, closest connections I can find are bug 1763001 and bug 1677540. The methods used to fix bug 1677540 don't look applicable here because we need to interact with the content. I'll try the other approach of waiting on a progress listener. And if that doesn't work, I'll try converting this into a markup test.

Flags: needinfo?(shmediaproductions)
Assignee: nobody → shmediaproductions
Status: NEW → ASSIGNED
Component: DOM: Networking → Preferences
Product: Core → Firefox
Version: unspecified → Trunk

Trying to fix failures of browser_download_after_revoke.js ostensibly
provoked by patch for Bug 1747343.

I tried just rearranging the test to circumvent the error. But I also found a lot of these with a similar error, though only a few that were resolved (and by different methods). If anyone has some experience with errors of SpecialPowers.spawn, I'd appreciate any feedback 🙏

Pushed by gijskruitbosch@gmail.com: https://hg.mozilla.org/integration/autoland/rev/720e3bfbc3af Refactor browser_download_after_revoke.js. r=kershaw

https://hg.mozilla.org/mozilla-central/rev/720e3bfbc3af

Status: ASSIGNED → RESOLVED
Closed: 3 years ago
status-firefox101: affected → fixed
Resolution: --- → FIXED
Target Milestone: --- → 101 Branch
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: