1676619 - Intermittent /js/builtins/weakrefs/finalizationregistry-cleanupCallback-gets-a-microtask.optional.any.html | HostCleanupFinalizationRegistry is an implementation-defined abstract operation that is expected to call CleanupFinalizationRegistry

Status: RESOLVED WORKSFORME

(Core :: JavaScript: GC, defect, P3)

Description

[Treeherder Bug Filer]

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

---

task 2020-11-11T10:31:20.190Z] 10:31:20     INFO - TEST-START | /js/builtins/weakrefs/finalizationregistry-cleanupCallback-gets-a-microtask.optional.any.html
[task 2020-11-11T10:31:20.246Z] 10:31:20     INFO - Closing window 18
[task 2020-11-11T10:31:30.037Z] 10:31:30     INFO - 
[task 2020-11-11T10:31:30.037Z] 10:31:30     INFO - TEST-PASS | /dom/ranges/StaticRange-constructor.html | Construct static range with standalone Node container 
[task 2020-11-11T10:31:30.037Z] 10:31:30     INFO - TEST-PASS | /dom/ranges/StaticRange-constructor.html | Construct static range with endpoints in disconnected trees 
[task 2020-11-11T10:31:30.037Z] 10:31:30     INFO - TEST-PASS | /dom/ranges/StaticRange-constructor.html | Construct static range with endpoints in disconnected documents 
[task 2020-11-11T10:31:30.037Z] 10:31:30     INFO - TEST-PASS | /dom/ranges/StaticRange-constructor.html | Throw on DocumentType or Attr container 
[task 2020-11-11T10:31:30.037Z] 10:31:30     INFO - TEST-PASS | /dom/ranges/StaticRange-constructor.html | Throw on missing or invalid arguments 
[task 2020-11-11T10:31:30.038Z] 10:31:30     INFO - TEST-FAIL | /js/builtins/weakrefs/cleanup-prevented-with-reference.optional.any.worker.html | cleanupCallback has only one optional chance to be called for a GC that cleans up a registered target. - assert_implements: FinalizationRegistry.prototype.cleanupSome is not implemented. undefined
[task 2020-11-11T10:31:30.038Z] 10:31:30     INFO - @http://web-platform.test:8000/js/builtins/weakrefs/cleanup-prevented-with-reference.optional.any.js:38:22
[task 2020-11-11T10:31:30.038Z] 10:31:30     INFO - @http://web-platform.test:8000/js/builtins/weakrefs/cleanup-prevented-with-reference.optional.any.js:49:5
[task 2020-11-11T10:31:30.038Z] 10:31:30     INFO - Test.prototype.step@http://web-platform.test:8000/resources/testharness.js:2044:25
[task 2020-11-11T10:31:30.038Z] 10:31:30     INFO - promise_test/tests.promise_tests</<@http://web-platform.test:8000/resources/testharness.js:653:36
[task 2020-11-11T10:31:30.038Z] 10:31:30     INFO - promise_test/tests.promise_tests<@http://web-platform.test:8000/resources/testharness.js:652:20
[task 2020-11-11T10:31:30.038Z] 10:31:30     INFO - TEST-FAIL | /js/builtins/weakrefs/cleanup-prevented-with-reference.optional.any.html | cleanupCallback has only one optional chance to be called for a GC that cleans up a registered target. - assert_implements: FinalizationRegistry.prototype.cleanupSome is not implemented. undefined
[task 2020-11-11T10:31:30.038Z] 10:31:30     INFO - @http://web-platform.test:8000/js/builtins/weakrefs/cleanup-prevented-with-reference.optional.any.js:38:22
[task 2020-11-11T10:31:30.038Z] 10:31:30     INFO - @http://web-platform.test:8000/js/builtins/weakrefs/cleanup-prevented-with-reference.optional.any.js:49:5
[task 2020-11-11T10:31:30.038Z] 10:31:30     INFO - Test.prototype.step@http://web-platform.test:8000/resources/testharness.js:2044:25
[task 2020-11-11T10:31:30.038Z] 10:31:30     INFO - promise_test/tests.promise_tests</<@http://web-platform.test:8000/resources/testharness.js:653:36
[task 2020-11-11T10:31:30.039Z] 10:31:30     INFO - promise_test/tests.promise_tests<@http://web-platform.test:8000/resources/testharness.js:652:20
[task 2020-11-11T10:31:30.039Z] 10:31:30     INFO - TEST-FAIL | /js/builtins/weakrefs/cleanup-prevented-with-unregister.optional.any.html | Cleanup might be prevented with an unregister usage - assert_implements: FinalizationRegistry.prototype.cleanupSome is not implemented. undefined
[task 2020-11-11T10:31:30.039Z] 10:31:30     INFO - @http://web-platform.test:8000/js/builtins/weakrefs/cleanup-prevented-with-unregister.optional.any.js:40:22
[task 2020-11-11T10:31:30.039Z] 10:31:30     INFO - @http://web-platform.test:8000/js/builtins/weakrefs/cleanup-prevented-with-unregister.optional.any.js:56:5
[task 2020-11-11T10:31:30.039Z] 10:31:30     INFO - Test.prototype.step@http://web-platform.test:8000/resources/testharness.js:2044:25
[task 2020-11-11T10:31:30.039Z] 10:31:30     INFO - promise_test/tests.promise_tests</<@http://web-platform.test:8000/resources/testharness.js:653:36
[task 2020-11-11T10:31:30.039Z] 10:31:30     INFO - promise_test/tests.promise_tests<@http://web-platform.test:8000/resources/testharness.js:652:20
[task 2020-11-11T10:31:30.039Z] 10:31:30     INFO - TEST-FAIL | /js/builtins/weakrefs/cleanup-prevented-with-unregister.optional.any.worker.html | Cleanup might be prevented with an unregister usage - assert_implements: FinalizationRegistry.prototype.cleanupSome is not implemented. undefined
[task 2020-11-11T10:31:30.039Z] 10:31:30     INFO - @http://web-platform.test:8000/js/builtins/weakrefs/cleanup-prevented-with-unregister.optional.any.js:40:22
[task 2020-11-11T10:31:30.039Z] 10:31:30     INFO - @http://web-platform.test:8000/js/builtins/weakrefs/cleanup-prevented-with-unregister.optional.any.js:56:5
[task 2020-11-11T10:31:30.039Z] 10:31:30     INFO - Test.prototype.step@http://web-platform.test:8000/resources/testharness.js:2044:25
[task 2020-11-11T10:31:30.039Z] 10:31:30     INFO - promise_test/tests.promise_tests</<@http://web-platform.test:8000/resources/testharness.js:653:36
[task 2020-11-11T10:31:30.039Z] 10:31:30     INFO - promise_test/tests.promise_tests<@http://web-platform.test:8000/resources/testharness.js:652:20
[task 2020-11-11T10:31:30.039Z] 10:31:30     INFO - TEST-FAIL | /js/builtins/weakrefs/finalizationregistry-cleanupCallback-gets-a-microtask.optional.any.worker.html | HostCleanupFinalizationRegistry is an implementation-defined abstract operation that is expected to call CleanupFinalizationRegistry(finalizationRegistry) at some point in the future, if possible. - step_wait_func: Expected 1000 registry cleanups. Timed out waiting on condition
[task 2020-11-11T10:31:30.040Z] 10:31:30     INFO - TEST-UNEXPECTED-FAIL | /js/builtins/weakrefs/finalizationregistry-cleanupCallback-gets-a-microtask.optional.any.html | HostCleanupFinalizationRegistry is an implementation-defined abstract operation that is expected to call CleanupFinalizationRegistry(finalizationRegistry) at some point in the future, if possible. - step_wait_func: Expected 1000 registry cleanups. Timed out waiting on condition
[task 2020-11-11T10:31:30.184Z] 10:31:30     INFO - TEST-OK | /js/builtins/weakrefs/finalizationregistry-cleanupCallback-gets-a-microtask.optional.any.html | took 9993ms
[task 2020-11-11T10:31:30.736Z] 10:31:30     INFO - Closing logging queue
[task 2020-11-11T10:31:30.737Z] 10:31:30     INFO - queue closed
[task 2020-11-11T10:31:30.755Z] 10:31:30     INFO - Setting up ssl
[task 2020-11-11T10:31:30.790Z] 10:31:30     INFO - certutil | 
[task 2020-11-11T10:31:30.831Z] 10:31:30     INFO - certutil | 
[task 2020-11-11T10:31:30.875Z] 10:31:30     INFO - certutil | 
[task 2020-11-11T10:31:30.875Z] 10:31:30     INFO - Certificate Nickname                                         Trust Attributes
[task 2020-11-11T10:31:30.875Z] 10:31:30     INFO -                                                              SSL,S/MIME,JAR/XPI
[task 2020-11-11T10:31:30.875Z] 10:31:30     INFO - 
[task 2020-11-11T10:31:30.875Z] 10:31:30     INFO - web-platform-tests                                           CT,, 
[task 2020-11-11T10:31:30.875Z] 10:31:30     INFO - 
[task 2020-11-11T10:31:32.198Z] 10:31:32     INFO - adb Granting important runtime permissions to org.mozilla.geckoview.test
[task 2020-11-11T10:31:33.677Z] 10:31:33     INFO - adb launch_application: am start -W -n org.mozilla.geckoview.test/org.mozilla.geckoview.test.TestRunnerActivity -a android.intent.action.MAIN --es env9 MOZ_DISABLE_NONLOCAL_CONNECTIONS=1 --es env8 R_LOG_DESTINATION=stderr --es args '-no-remote -profile /data/local/tmp/test_root/profile --marionette about:blank' --es env3 MOZ_HIDE_RESULTS_TABLE=1 --es env2 R_LOG_VERBOSE=1 --es env1 MOZ_WEBRENDER=0 --es env0 MOZ_CRASHREPORTER=1 --es env7 MOZ_CRASHREPORTER_SHUTDOWN=1 --es env6 MOZ_IN_AUTOMATION=1 --es env5 MOZ_LOG=signaling:3,mtransport:4,DataChannel:4,jsep:4 --es env4 STYLO_THREADS=1 --ez use_multiprocess True --es env12 R_LOG_LEVEL=6 --es env11 MOZ_PROCESS_LOG=/tmp/tmpdZ1Qxzpidlog --es env10 MOZ_CRASHREPORTER_NO_REPORT=1
[task 2020-11-11T10:31:34.802Z] 10:31:34     INFO - Starting runner```

Comment 1

[Natalia Csoregi [:nataliaCs]]

Retriggers/backfills range: https://treeherder.mozilla.org/jobs?repo=autoland&searchStr=android%2C7.0%2Cx86-64%2Cdebug%2Cweb%2Cplatform%2Ctests%2Ctest-android-em-7.0-x86_64%2Fdebug-geckoview-web-platform-tests-e10s&tochange=84780a4376d6791bf1ea75f8e47078a979fefd79&fromchange=123972486e74f2d2704bfec490bc3f1d114cd066&group_state=expanded

Most probably caused by Bug 1675517 - https://hg.mozilla.org/integration/autoland/rev/a70a87540e2742bee8c8b7383ba05d9cae2f63be

Comment 2

[Intermittent Failures Robot]

23 failures in 4040 pushes (0.006 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 18
• mozilla-central: 5

Platform breakdown:

• android-em-7-0-x86_64: 15
• windows10-64: 7
• android-em-7-0-x86_64-shippable-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1676619&startday=2020-11-09&endday=2020-11-15&tree=all

Comment 3

[Andreea Pavel [:apavel]]

Attachment: Bug 1676619 - adjust finalizationregistry-cleanupCallback-gets-a-microtask.optional.any.html expectation r?#intermittent-reviewers

Comment 4

[Intermittent Failures Robot]

49 failures in 4810 pushes (0.01 failures/push) were associated with this bug in the last 7 days.

This is the #20 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 39
• try: 2
• mozilla-central: 8

Platform breakdown:

• windows10-64: 27
• android-em-7-0-x86_64: 22

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1676619&startday=2020-11-16&endday=2020-11-22&tree=all

Comment 5

[Intermittent Failures Robot]

5 failures in 1479 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 3
• autoland: 1
• try: 1

Platform breakdown:

• windows10-64: 4
• android-em-7-0-x86_64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1676619&startday=2020-11-23&endday=2020-11-29&tree=all

Comment 6

[Intermittent Failures Robot]

1 failures in 3712 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• windows10-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1676619&startday=2020-11-30&endday=2020-12-06&tree=all

Comment 7

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/e78f61d3ee73
adjust finalizationregistry-cleanupCallback-gets-a-microtask.optional.any.html expectation r=intermittent-reviewers,jmaher DONTBUILD

Comment 8

[Cristina Coroiu [:ccoroiu]]

https://hg.mozilla.org/mozilla-central/rev/e78f61d3ee73

Comment 9

[Intermittent Failures Robot]

12 failures in 3421 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 5
• try: 1
• autoland: 6

Platform breakdown:

• windows7-32: 12

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1676619&startday=2020-12-14&endday=2020-12-20&tree=all

Comment 10

[Intermittent Failures Robot]

6 failures in 1450 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 4
• mozilla-central: 2

Platform breakdown:

• windows7-32: 6

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1676619&startday=2020-12-21&endday=2020-12-27&tree=all

Comment 11

[Intermittent Failures Robot]

5 failures in 525 pushes (0.01 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 4
• mozilla-central: 1

Platform breakdown:

• windows7-32: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1676619&startday=2020-12-28&endday=2021-01-03&tree=all

Comment 12

[Intermittent Failures Robot]

10 failures in 2956 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 7
• mozilla-central: 3

Platform breakdown:

• windows7-32: 9
• windows10-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1676619&startday=2021-01-04&endday=2021-01-10&tree=all

Comment 13

[Intermittent Failures Robot]

15 failures in 3810 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 7
• mozilla-central: 8

Platform breakdown:

• windows7-32: 14
• windows10-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1676619&startday=2021-01-11&endday=2021-01-17&tree=all

Comment 14

[Intermittent Failures Robot]

9 failures in 3488 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 7
• mozilla-central: 2

Platform and build breakdown:

• windows7-32: 8
  • debug: 8
• windows10-64: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1676619&startday=2021-01-18&endday=2021-01-24&tree=all

Comment 15

[Andreea Pavel [:apavel]]

There are 29 total failures in the last 7 days on

• windows7-32 debug
• windows10-64-qr debug
  Recent failure log:

[task 2021-02-13T22:18:51.657Z] 22:18:51 INFO - TEST-START | /infrastructure/testdriver/actions/multiTouchPoints.html
[task 2021-02-13T22:18:51.659Z] 22:18:51 INFO - Closing window 55
[task 2021-02-13T22:18:51.892Z] 22:18:51 INFO - {'actions': [{'type': 'none', 'actions': [{'type': 'pause', 'duration': 16}, {'type': 'pause', 'duration': 16}, {'type': 'pause', 'duration': 16}, {'type': 'pause', 'duration': 16}], 'id': '0'}, {'type': 'pointer', 'actions': [{'type': 'pointerMove', 'x': 0, 'y': 0, 'origin': {'element-6066-11e4-a52e-4f735466cecf': '7fe03c01-290f-411c-8789-b7b824ee16a1', 'chromeelement-9fc5-4b51-a3c8-01716eedeb04': '7fe03c01-290f-411c-8789-b7b824ee16a1'}}, {'type': 'pointerDown', 'button': 0}, {'type': 'pointerMove', 'x': 0, 'y': 10, 'origin': {'element-6066-11e4-a52e-4f735466cecf': '7fe03c01-290f-411c-8789-b7b824ee16a1', 'chromeelement-9fc5-4b51-a3c8-01716eedeb04': '7fe03c01-290f-411c-8789-b7b824ee16a1'}}, {'type': 'pointerUp', 'button': 0}], 'parameters': {'pointerType': 'touch'}, 'id': 'touchPointer1'}, {'type': 'pointer', 'actions': [{'type': 'pointerMove', 'x': 10, 'y': 0, 'origin': {'element-6066-11e4-a52e-4f735466cecf': '7fe03c01-290f-411c-8789-b7b824ee16a1', 'chromeelement-9fc5-4b51-a3c8-01716eedeb04': '7fe03c01-290f-411c-8789-b7b824ee16a1'}}, {'type': 'pointerDown', 'button': 0}, {'type': 'pause'}, {'type': 'pointerUp', 'button': 0}], 'parameters': {'pointerType': 'touch'}, 'id': 'touchPointer2'}]}
[task 2021-02-13T22:18:51.898Z] 22:18:51 WARNING - Action action_sequence failed
[task 2021-02-13T22:18:51.902Z] 22:18:51 WARNING - Traceback (most recent call last):
[task 2021-02-13T22:18:51.902Z] 22:18:51 WARNING - File "C:\Users\task_1613247246\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\base.py", line 804, in process_action
[task 2021-02-13T22:18:51.902Z] 22:18:51 WARNING - result = action_handler(payload)
[task 2021-02-13T22:18:51.902Z] 22:18:51 WARNING - File "C:\Users\task_1613247246\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\actions.py", line 59, in call
[task 2021-02-13T22:18:51.902Z] 22:18:51 WARNING - self.protocol.action_sequence.send_actions({"actions": actions})
[task 2021-02-13T22:18:51.902Z] 22:18:51 WARNING - File "C:\Users\task_1613247246\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 466, in send_actions
[task 2021-02-13T22:18:51.902Z] 22:18:51 WARNING - self.marionette._send_message("WebDriver:PerformActions", actions)
[task 2021-02-13T22:18:51.902Z] 22:18:51 WARNING - File "C:\Users\task_1613247246\build\venv\lib\site-packages\marionette_driver\decorators.py", line 27, in _
[task 2021-02-13T22:18:51.902Z] 22:18:51 WARNING - return func(*args, **kwargs)
[task 2021-02-13T22:18:51.902Z] 22:18:51 WARNING - File "C:\Users\task_1613247246\build\venv\lib\site-packages\marionette_driver\marionette.py", line 629, in _send_message
[task 2021-02-13T22:18:51.902Z] 22:18:51 WARNING - self._handle_error(err)
[task 2021-02-13T22:18:51.902Z] 22:18:51 WARNING - File "C:\Users\task_1613247246\build\venv\lib\site-packages\marionette_driver\marionette.py", line 651, in _handle_error
[task 2021-02-13T22:18:51.902Z] 22:18:51 WARNING - raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2021-02-13T22:18:51.902Z] 22:18:51 WARNING - marionette_driver.errors.InvalidArgumentException: Unknown pointerType: [object String] "touch"
[task 2021-02-13T22:18:51.902Z] 22:18:51 WARNING - stacktrace:
[task 2021-02-13T22:18:51.903Z] 22:18:51 WARNING - WebDriverError@chrome://marionette/content/error.js:181:5
[task 2021-02-13T22:18:51.903Z] 22:18:51 WARNING - InvalidArgumentError@chrome://marionette/content/error.js:310:5
[task 2021-02-13T22:18:51.903Z] 22:18:51 WARNING - assert.that/<@chrome://marionette/content/assert.js:460:13
[task 2021-02-13T22:18:51.903Z] 22:18:51 WARNING - assert.in@chrome://marionette/content/assert.js:413:47
[task 2021-02-13T22:18:51.903Z] 22:18:51 WARNING - action.PointerType.get@chrome://marionette/content/action.js:402:10
[task 2021-02-13T22:18:51.903Z] 22:18:51 WARNING - Pointer@chrome://marionette/content/action.js:592:39
[task 2021-02-13T22:18:51.903Z] 22:18:51 WARNING - fromJSON@chrome://marionette/content/action.js:480:14
[task 2021-02-13T22:18:51.903Z] 22:18:51 WARNING - fromJSON@chrome://marionette/content/action.js:846:39
[task 2021-02-13T22:18:51.903Z] 22:18:51 WARNING - fromJSON@chrome://marionette/content/action.js:809:48
[task 2021-02-13T22:18:51.903Z] 22:18:51 WARNING - performActions@chrome://marionette/content/actors/MarionetteCommandsChild.jsm:439:20
[task 2021-02-13T22:18:51.903Z] 22:18:51 WARNING - receiveMessage@chrome://marionette/content/actors/MarionetteCommandsChild.jsm:136:31
[task 2021-02-13T22:18:51.903Z] 22:18:51 WARNING -
[task 2021-02-13T22:18:51.903Z] 22:18:51 WARNING -
[task 2021-02-13T22:18:51.909Z] 22:18:51 WARNING - Traceback (most recent call last):
[task 2021-02-13T22:18:51.909Z] 22:18:51 WARNING - File "C:\Users\task_1613247246\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 769, in run_func
[task 2021-02-13T22:18:51.909Z] 22:18:51 WARNING - self.result = True, self.func(self.protocol, self.url, self.timeout)
[task 2021-02-13T22:18:51.909Z] 22:18:51 WARNING - File "C:\Users\task_1613247246\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 897, in do_testharness
[task 2021-02-13T22:18:51.909Z] 22:18:51 WARNING - done, rv = handler(result)
[task 2021-02-13T22:18:51.909Z] 22:18:51 WARNING - File "C:\Users\task_1613247246\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\base.py", line 788, in call
[task 2021-02-13T22:18:51.909Z] 22:18:51 WARNING - return callback(url, payload)
[task 2021-02-13T22:18:51.909Z] 22:18:51 WARNING - File "C:\Users\task_1613247246\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\base.py", line 804, in process_action
[task 2021-02-13T22:18:51.910Z] 22:18:51 WARNING - result = action_handler(payload)
[task 2021-02-13T22:18:51.910Z] 22:18:51 WARNING - File "C:\Users\task_1613247246\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\actions.py", line 59, in call
[task 2021-02-13T22:18:51.910Z] 22:18:51 WARNING - self.protocol.action_sequence.send_actions({"actions": actions})
[task 2021-02-13T22:18:51.910Z] 22:18:51 WARNING - File "C:\Users\task_1613247246\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 466, in send_actions
[task 2021-02-13T22:18:51.910Z] 22:18:51 WARNING - self.marionette._send_message("WebDriver:PerformActions", actions)
[task 2021-02-13T22:18:51.910Z] 22:18:51 WARNING - File "C:\Users\task_1613247246\build\venv\lib\site-packages\marionette_driver\decorators.py", line 27, in _
[task 2021-02-13T22:18:51.910Z] 22:18:51 WARNING - return func(*args, **kwargs)
[task 2021-02-13T22:18:51.910Z] 22:18:51 WARNING - File "C:\Users\task_1613247246\build\venv\lib\site-packages\marionette_driver\marionette.py", line 629, in _send_message
[task 2021-02-13T22:18:51.910Z] 22:18:51 WARNING - self._handle_error(err)

[task 2021-02-13T22:18:53.268Z] 22:18:53 INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2021-02-13T22:18:53.270Z] 22:18:53 INFO - Closing logging queue
[task 2021-02-13T22:18:53.271Z] 22:18:53 INFO - queue closed
[task 2021-02-13T22:18:53.331Z] 22:18:53 INFO - Application command: C:\Users\task_1613247246\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_1613247246\AppData\Local\Temp\tmphugtx7oe
[task 2021-02-13T22:18:53.333Z] 22:18:53 INFO - Starting runner
[task 2021-02-13T22:18:53.384Z] 22:18:53 INFO - PID 6308 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_1613247246\AppData\Local\Temp\tmphugtx7oe\runtests_leaks_5640.log
[task 2021-02-13T22:18:53.385Z] 22:18:53 INFO - PID 6308 | [3276, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:251
[task 2021-02-13T22:18:53.955Z] 22:18:53 INFO - PID 6308 | [2021-02-13T22:18:53Z WARN rkv::backend::impl_safe::environment] load_ratio() is irrelevant for this storage backend.
[task 2021-02-13T22:18:54.154Z] 22:18:54 INFO - PID 6308 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_1613247246\AppData\Local\Temp\tmphugtx7oe\runtests_leaks_5640_gpu_pid3280.log
[task 2021-02-13T22:18:54.155Z] 22:18:54 INFO - PID 6308 | [3280, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:251
[task 2021-02-13T22:18:54.328Z] 22:18:54 INFO - PID 6308 | [Parent 3276, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4254
[task 2021-02-13T22:18:54.331Z] 22:18:54 INFO - PID 6308 | [Parent 3276, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4254
[task 2021-02-13T22:18:54.361Z] 22:18:54 INFO - PID 6308 | [GPU 3280, Renderer] WARNING: Failed to bind API to GL!: file /builds/worker/checkouts/gecko/gfx/gl/GLContextProviderEGL.cpp:654
[task 2021-02-13T22:18:54.363Z] 22:18:54 INFO - PID 6308 | [GPU 3280, Renderer] WARNING: Failed to create GLContext from PBuffer: file /builds/worker/checkouts/gecko/gfx/gl/GLContextProviderEGL.cpp:1197
[task 2021-02-13T22:18:54.363Z] 22:18:54 INFO - PID 6308 | [GPU 3280, Renderer] WARNING: Failed to create EGLContext with khr_rbab_attribs: file /builds/worker/checkouts/gecko/gfx/gl/GLContextProviderEGL.cpp:752
[task 2021-02-13T22:18:54.365Z] 22:18:54 INFO - PID 6308 | Initializing context 00000118B1E66000 surface 00000118B0380190 on display 00000118B03B1000
[task 2021-02-13T22:18:54.471Z] 22:18:54 INFO - PID 6308 | [Parent 3276, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1298
[task 2021-02-13T22:18:54.678Z] 22:18:54 INFO -
[task 2021-02-13T22:18:54.678Z] 22:18:54 INFO - TEST-UNEXPECTED-FAIL | /js/builtins/weakrefs/finalizationregistry-cleanupCallback-gets-a-microtask.optional.any.html | HostCleanupFinalizationRegistry is an implementation-defined abstract operation that is expected to call CleanupFinalizationRegistry(finalizationRegistry) at some point in the future, if possible. - step_wait_func: Expected 1000 registry cleanups. Timed out waiting on condition
[task 2021-02-13T22:18:54.679Z] 22:18:54 INFO - TEST-OK | /js/builtins/weakrefs/finalizationregistry-cleanupCallback-gets-a-microtask.optional.any.html | took 9544ms
[task 2021-02-13T22:18:54.694Z] 22:18:54 INFO - PID 4900 | 1613254734692 Marionette INFO Stopped listening on port 58493
[task 2021-02-13T22:18:54.727Z] 22:18:54 INFO - PID 4900 | [Child 8472, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-02-13T22:18:54.728Z] 22:18:54 INFO - PID 4900 | [Child 8472, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-02-13T22:18:54.734Z] 22:18:54 INFO - PID 4900 | [Child 8472, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-02-13T22:18:54.737Z] 22:18:54 INFO - PID 4900 | [Child 8472, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-02-13T22:18:54.739Z] 22:18:54 INFO - PID 4900 | [Child 8472, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-02-13T22:18:54.763Z] 22:18:54 INFO - PID 6308 | [2021-02-13T22:18:54Z WARN webrender::device::gl] Missing optimized shader source for gpu_cache_update
[task 2021-02-13T22:18:54.820Z] 22:18:54 INFO - PID 4900 | [GPU 2208, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:370
[task 2021-02-13T22:18:54.865Z] 22:18:54 INFO - PID 6308 | [Parent 3276, GMPThread] WARNING: Failed to delete GMP storage directory: file /builds/worker/checkouts/gecko/dom/media/gmp/GMPServiceParent.cpp:1562
[task 2021-02-13T22:18:54.892Z] 22:18:54 INFO - PID 6308 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_1613247246\AppData\Local\Temp\tmphugtx7oe\runtests_leaks_5640_tab_pid7804.log
[task 2021-02-13T22:18:54.893Z] 22:18:54 INFO - PID 6308 | [7804, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:251
[task 2021-02-13T22:18:54.912Z] 22:18:54 INFO - PID 6308 | [2021-02-13T22:18:54Z WARN webrender::device::gl] Attribute VertexAttribute { name: "aUvRect1", count: 4, kind: F32 } is not found in the shader composite. Expected at 6, found at -1
[task 2021-02-13T22:18:54.913Z] 22:18:54 INFO - PID 6308 | [2021-02-13T22:18:54Z WARN webrender::device::gl] Attribute VertexAttribute { name: "aUvRect2", count: 4, kind: F32 } is not found in the shader composite. Expected at 7, found at -1
[task 2021-02-13T22:18:54.959Z] 22:18:54 INFO - PID 4900 | [Child 7380, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3365
[task 2021-02-13T22:18:54.960Z] 22:18:54 INFO - PID 6308 | [Parent 3276, Main Thread] WARNING: NS_ENSURE_TRUE(presShell) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4252
[task 2021-02-13T22:18:54.999Z] 22:18:54 INFO - PID 4900 | [Child 7380, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4381
[task 2021-02-13T22:18:55.007Z] 22:18:55 INFO - PID 4900 | [Child 8472, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3365
[task 2021-02-13T22:18:55.009Z] 22:18:55 INFO - PID 4900 | [2021-02-13T22:18:55Z WARN xulstore::persist] tried to remove key that isn't in the store
[task 2021-02-13T22:18:55.010Z] 22:18:55 INFO - PID 4900 | [2021-02-13T22:18:55Z WARN xulstore::persist] tried to remove key that isn't in the store

Aryx should we update the expectations here?

Comment 16

[Intermittent Failures Robot]

30 failures in 4322 pushes (0.007 failures/push) were associated with this bug in the last 7 days.

This is the #38 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 11
• mozilla-central: 19

Platform and build breakdown:

• windows10-64: 2
  • debug: 2
• windows7-32: 15
  • debug: 15
• windows10-64-qr: 13
  • debug: 13

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1676619&startday=2021-02-08&endday=2021-02-14&tree=all

Comment 17

[Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)]

Attachment: Bug 1676619 - set finalizationregistry-cleanupCallback-gets-a-microtask.optional.any.html as intermittent on Windows debug. DONTBUILD

Annotation as intermittent got removed with the web-platform-test
synchronization with upstream in bug 1690825.

Comment 18

[Jason Orendorff [:jorendorff]]

Jon, would you mind taking a look? If it's Windows debug only, maybe the test should just be disabled...

Comment 19

[Jon Coppeard (:jonco)]

Sure.

It looks like we don't implement the hook to force a GC from the tests that can be implemented here:

https://searchfox.org/mozilla-central/source/testing/web-platform/tests/js/builtins/weakrefs/resources/maybe-garbage-collect.js#13

Running this test in my browser shows the "Tests are running without the ability to do manual garbage collection" console warning.

(Per WPT dashboard, this test fails on all browsers).

Comment 20

[Intermittent Failures Robot]

1 failures in 3737 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1676619&startday=2021-02-15&endday=2021-02-21&tree=all

Comment 21

[Intermittent Failures Robot]

1 failures in 4622 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• macosx1014-64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1676619&startday=2021-03-08&endday=2021-03-14&tree=all

Comment 22

[Jon Coppeard (:jonco)]

No failures for two years, closing this bug.