Closed Bug 1763772 Opened 3 years ago Closed 3 years ago

Intermittent toolkit/components/extensions/test/mochitest/test_ext_storage_manager_capabilities.html | AbortError: Actor 'SpecialPowers' destroyed before query 'PopPrefEnv' was resolved - Should not throw any errors

Categories

(WebExtensions :: General, defect, P5)

Product:
WebExtensions
Component:
General
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED FIXED

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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

[task 2022-04-08T07:02:35.707Z] 07:02:35     INFO -  TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_storage_manager_capabilities.html | test result correct
[task 2022-04-08T07:02:35.707Z] 07:02:35     INFO -  add_task | Leaving test test_contentScriptSecure
[task 2022-04-08T07:02:35.707Z] 07:02:35     INFO -  add_task | Entering test cleanup
[task 2022-04-08T07:02:35.708Z] 07:02:35     INFO -  Buffered messages finished
[task 2022-04-08T07:02:35.708Z] 07:02:35  WARNING -  TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_storage_manager_capabilities.html | AbortError: Actor 'SpecialPowers' destroyed before query 'PopPrefEnv' was resolved - Should not throw any errors
[task 2022-04-08T07:02:35.708Z] 07:02:35     INFO -  nextTick/<@https://example.com/tests/SimpleTest/SimpleTest.js:2103:26
[task 2022-04-08T07:02:35.708Z] 07:02:35     INFO -  async*nextTick@https://example.com/tests/SimpleTest/SimpleTest.js:2120:11
[task 2022-04-08T07:02:35.709Z] 07:02:35     INFO -  setTimeout handler*SimpleTest_setTimeoutShim@https://example.com/tests/SimpleTest/SimpleTest.js:907:41
[task 2022-04-08T07:02:35.709Z] 07:02:35     INFO -  add_task@https://example.com/tests/SimpleTest/SimpleTest.js:2050:17
[task 2022-04-08T07:02:35.709Z] 07:02:35     INFO -  @https://example.com/tests/toolkit/components/extensions/test/mochitest/test_ext_storage_manager_capabilities.html:15:9
[task 2022-04-08T07:02:35.709Z] 07:02:35  WARNING -  TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_storage_manager_capabilities.html | Cleanup promise rejected: InvalidStateError: JSWindowActorChild.sendAsyncMessage: JSWindowActorChild cannot send at the moment
[task 2022-04-08T07:02:35.709Z] 07:02:35     INFO -  SimpleTest.ok@https://example.com/tests/SimpleTest/SimpleTest.js:417:16
[task 2022-04-08T07:02:35.710Z] 07:02:35     INFO -  executeCleanupFunction/<@https://example.com/tests/SimpleTest/SimpleTest.js:1494:20
[task 2022-04-08T07:02:35.710Z] 07:02:35     INFO -  setTimeout handler*SimpleTest_setTimeoutShim@https://example.com/tests/SimpleTest/SimpleTest.js:907:41
[task 2022-04-08T07:02:35.710Z] 07:02:35     INFO -  @https://example.com/tests/toolkit/components/extensions/test/mochitest/head.js:25:44
[task 2022-04-08T07:02:35.710Z] 07:02:35     INFO -  @https://example.com/tests/toolkit/components/extensions/test/mochitest/head.js:25:11
[task 2022-04-08T07:02:35.710Z] 07:02:35     INFO -  executeCleanupFunction@https://example.com/tests/SimpleTest/SimpleTest.js:1487:13
[task 2022-04-08T07:02:35.710Z] 07:02:35     INFO -  executeCleanupFunction@https://example.com/tests/SimpleTest/SimpleTest.js:1497:7
[task 2022-04-08T07:02:55.913Z] 07:02:55     INFO -  wait for org.mozilla.geckoview.test_runner complete; top activity=com.android.launcher3
[task 2022-04-08T07:02:55.914Z] 07:02:55     INFO -  runtestsremote.py | Application ran for: 0:00:33.687045
[task 2022-04-08T07:02:56.020Z] 07:02:56     INFO -  Stopping web server
[task 2022-04-08T07:02:56.025Z] 07:02:56     INFO -  Server shut down.
[task 2022-04-08T07:02:56.046Z] 07:02:56     INFO -  Web server killed.
[task 2022-04-08T07:02:56.046Z] 07:02:56     INFO -  Stopping web socket server
[task 2022-04-08T07:02:56.067Z] 07:02:56     INFO -  Stopping ssltunnel
[task 2022-04-08T07:02:56.088Z] 07:02:56     INFO -  leakcheck | refcount logging is off, so leaks can't be detected!
[task 2022-04-08T07:02:56.088Z] 07:02:56     INFO -  runtests.py | Running tests: end.
[task 2022-04-08T07:02:56.412Z] 07:02:56     INFO -  Buffered messages finished
[task 2022-04-08T07:02:56.755Z] 07:02:56     INFO -  0 INFO TEST-START | Shutdown
[task 2022-04-08T07:02:56.755Z] 07:02:56     INFO -  1 INFO Passed:  13589
[task 2022-04-08T07:02:56.755Z] 07:02:56     INFO -  2 INFO Failed:  0
[task 2022-04-08T07:02:56.756Z] 07:02:56     INFO -  3 INFO Todo:    28
[task 2022-04-08T07:02:56.756Z] 07:02:56     INFO -  4 INFO Mode:    e10s
[task 2022-04-08T07:02:56.756Z] 07:02:56     INFO -  5 INFO SimpleTest FINISHED
[task 2022-04-08T07:02:56.756Z] 07:02:56     INFO -  Buffered messages finished
[task 2022-04-08T07:02:56.756Z] 07:02:56     INFO -  SUITE-END | took 841s
[task 2022-04-08T07:02:56.813Z] 07:02:56     INFO - Return code: 0
[task 2022-04-08T07:02:56.814Z] 07:02:56     INFO - TinderboxPrint: mochitest-plain<br/>13589/0/28
[task 2022-04-08T07:02:56.814Z] 07:02:56     INFO - ##### mochitest-plain log ends
[task 2022-04-08T07:02:56.814Z] 07:02:56  WARNING - # TBPL WARNING #
[task 2022-04-08T07:02:56.814Z] 07:02:56  WARNING - setting return code to 1
[task 2022-04-08T07:02:56.814Z] 07:02:56  WARNING - The mochitest-plain suite: mochitest-plain ran with return status: WARNING
[task 2022-04-08T07:02:56.814Z] 07:02:56     INFO - Running post-action listener: _package_coverage_data
[task 2022-04-08T07:02:56.814Z] 07:02:56     INFO - Running post-action listener: _resource_record_post_action
[task 2022-04-08T07:02:56.815Z] 07:02:56     INFO - Running post-action listener: process_java_coverage_data
[task 2022-04-08T07:02:56.815Z] 07:02:56     INFO - Running post-action listener: stop_device
[task 2022-04-08T07:02:57.285Z] 07:02:57     INFO - Killing logcat pid 1948.
[task 2022-04-08T07:02:57.285Z] 07:02:57     INFO - Killing every process called qemu-system-x86_64
[task 2022-04-08T07:02:57.300Z] 07:02:57     INFO - [mozharness: 2022-04-08 07:02:57.299668Z] Finished run-tests step (success)
[task 2022-04-08T07:02:57.300Z] 07:02:57     INFO - Running post-run listener: _resource_record_post_run
[task 2022-04-08T07:02:57.639Z] 07:02:57     INFO - Total resource usage - Wall time: 877s; CPU: 11%; Read bytes: 0; Write bytes: 133021696; Read time: 0; Write time: 22674
[task 2022-04-08T07:02:57.639Z] 07:02:57     INFO - TinderboxPrint: CPU usage<br/>11.1%
[task 2022-04-08T07:02:57.639Z] 07:02:57     INFO - TinderboxPrint: I/O read bytes / time<br/>0 / 0
[task 2022-04-08T07:02:57.639Z] 07:02:57     INFO - TinderboxPrint: I/O write bytes / time<br/>133,021,696 / 22,674
[task 2022-04-08T07:02:57.640Z] 07:02:57     INFO - TinderboxPrint: CPU guest<br/>2,719.9 (3.1%)
[task 2022-04-08T07:02:57.640Z] 07:02:57     INFO - TinderboxPrint: CPU idle<br/>74,585.8 (86.1%)
[task 2022-04-08T07:02:57.640Z] 07:02:57     INFO - TinderboxPrint: CPU system<br/>1,494.3 (1.7%)
[task 2022-04-08T07:02:57.640Z] 07:02:57     INFO - TinderboxPrint: CPU user<br/>7,700.8 (8.9%)
[task 2022-04-08T07:02:57.640Z] 07:02:57     INFO - TinderboxPrint: Swap in / out<br/>0 / 0
[task 2022-04-08T07:02:57.641Z] 07:02:57     INFO - start-emulator - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2022-04-08T07:02:57.649Z] 07:02:57     INFO - verify-device - Wall time: 29s; CPU: 4%; Read bytes: 0; Write bytes: 21880832; Read time: 0; Write time: 3510
[task 2022-04-08T07:02:57.650Z] 07:02:57     INFO - install - Wall time: 3s; CPU: 4%; Read bytes: 0; Write bytes: 49152; Read time: 0; Write time: 2
[task 2022-04-08T07:02:57.754Z] 07:02:57     INFO - run-tests - Wall time: 846s; CPU: 11%; Read bytes: 0; Write bytes: 111091712; Read time: 0; Write time: 19162
[task 2022-04-08T07:02:59.522Z] 07:02:59  WARNING - returning nonzero exit status 1
[task 2022-04-08T07:02:59.688Z] cleanup
[task 2022-04-08T07:02:59.688Z] + cleanup
[task 2022-04-08T07:02:59.688Z] + local rv=1
[task 2022-04-08T07:02:59.688Z] + [[ -s /builds/worker/.xsession-errors ]]
[task 2022-04-08T07:02:59.688Z] + cp /builds/worker/.xsession-errors /builds/worker/artifacts/public/xsession-errors.log
[task 2022-04-08T07:02:59.690Z] + '[' ']'
[task 2022-04-08T07:02:59.690Z] + true
[task 2022-04-08T07:02:59.690Z] + cleanup_xvfb
[task 2022-04-08T07:02:59.690Z] ++ pidof Xvfb
[task 2022-04-08T07:02:59.697Z] + local xvfb_pid=54
[task 2022-04-08T07:02:59.697Z] + local vnc=false
[task 2022-04-08T07:02:59.697Z] + local interactive=false
[task 2022-04-08T07:02:59.697Z] + '[' -n 54 ']'
[task 2022-04-08T07:02:59.697Z] + [[ false == false ]]
[task 2022-04-08T07:02:59.697Z] + [[ false == false ]]
[task 2022-04-08T07:02:59.697Z] + kill 54
[task 2022-04-08T07:02:59.697Z] + screen -XS xvfb quit
[task 2022-04-08T07:02:59.702Z] + exit 1
[taskcluster 2022-04-08 07:03:00.360Z] === Task Finished ===
[taskcluster 2022-04-08 07:03:07.609Z] Unsuccessful task run with exit code: 1 completed in 973.388 seconds

This is fixed by backout: https://hg.mozilla.org/integration/autoland/rev/778d89a3d0f04691dd19616ab3288eda7b4f41be

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