Closed Bug 1814545 Opened 3 years ago Closed 3 years ago

Intermittent /css/css-masking/mask-svg-content/mask-empty-container-with-filter.svg | single tracking bug

Categories

(Core :: Web Painting, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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


[task 2023-02-01T23:59:01.092Z] 23:59:01     INFO - TEST-START | /css/css-masking/mask-svg-content/mask-empty-container-with-filter.svg
[task 2023-02-01T23:59:01.107Z] 23:59:01     INFO - Setting pref dom.animations-api.compositing.enabled to true
[task 2023-02-01T23:59:01.120Z] 23:59:01     INFO - Setting pref layout.css.cascade-layers.enabled to true
[task 2023-02-01T23:59:01.132Z] 23:59:01     INFO - Setting pref layout.css.more_color_4.enabled to true
[task 2023-02-01T23:59:01.211Z] 23:59:01     INFO - PID 2564 | 1675295941209	Marionette	INFO	Testing http://web-platform.test:8000/css/css-masking/mask-svg-content/mask-empty-container-with-filter.svg == http://web-platform.test:8000/css/css-masking/mask-svg-content/reference/mask-green-square-001-ref.svg
[task 2023-02-01T23:59:01.336Z] 23:59:01     INFO - TEST-UNEXPECTED-ERROR | /css/css-masking/mask-svg-content/mask-empty-container-with-filter.svg | Testing http://web-platform.test:8000/css/css-masking/mask-svg-content/mask-empty-container-with-filter.svg == http://web-platform.test:8000/css/css-masking/mask-svg-content/reference/mask-green-square-001-ref.svg
[task 2023-02-01T23:59:01.336Z] 23:59:01     INFO - TypeError: can't access property "getActor", webProgress.browsingContext.currentWindowGlobal is null
[task 2023-02-01T23:59:01.337Z] 23:59:01     INFO - loadTestUrl@chrome://remote/content/marionette/reftest.sys.mjs:673:21
[task 2023-02-01T23:59:01.337Z] 23:59:01     INFO - screenshot@chrome://remote/content/marionette/reftest.sys.mjs:738:18
[task 2023-02-01T23:59:01.337Z] 23:59:01     INFO - compareUrls@chrome://remote/content/marionette/reftest.sys.mjs:489:29
[task 2023-02-01T23:59:01.337Z] 23:59:01     INFO - REFTEST   IMAGE 1 (TEST): data:image/png;base64,
[task 2023-02-01T23:59:01.337Z] 23:59:01     INFO - REFTEST   IMAGE 2 (REFERENCE): data:image/png;base64,
[task 2023-02-01T23:59:01.338Z] 23:59:01     INFO - TEST-INFO took 242ms
[task 2023-02-01T23:59:01.388Z] 23:59:01     INFO - PID 2564 | 1675295941387	Marionette	INFO	Stopped listening on port 54006
[task 2023-02-01T23:59:01.939Z] 23:59:01     INFO - PID 2564 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-02-01T23:59:01.939Z] 23:59:01     INFO - PID 2564 |           Blocker:  Waiting for ping task
[task 2023-02-01T23:59:01.939Z] 23:59:01     INFO - PID 2564 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2023-02-01T23:59:01.939Z] 23:59:01     INFO - PID 2564 |           State: (none)
[task 2023-02-01T23:59:01.940Z] 23:59:01     INFO - PID 2564 | WARNING: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-02-01T23:59:01.940Z] 23:59:01     INFO - PID 2564 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-02-01T23:59:01.941Z] 23:59:01     INFO - PID 2564 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-02-01T23:59:01.941Z] 23:59:01     INFO - PID 2564 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-02-01T23:59:01.942Z] 23:59:01     INFO - PID 2564 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-02-01T23:59:01.942Z] 23:59:01     INFO - PID 2564 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-02-01T23:59:01.943Z] 23:59:01     INFO - PID 2564 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-02-01T23:59:01.946Z] 23:59:01     INFO - PID 2564 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-02-01T23:59:01.947Z] 23:59:01     INFO - PID 2564 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-02-01T23:59:01.950Z] 23:59:01     INFO - PID 2564 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-02-01T23:59:01.951Z] 23:59:01     INFO - PID 2564 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-02-01T23:59:01.951Z] 23:59:01     INFO - PID 2564 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-02-01T23:59:01.951Z] 23:59:01     INFO - PID 2564 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-02-01T23:59:01.951Z] 23:59:01     INFO - PID 2564 | console.error: "TelemetryScheduler.shutdown - Already shut down"
[task 2023-02-01T23:59:01.951Z] 23:59:01     INFO - PID 2564 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-02-01T23:59:01.952Z] 23:59:01     INFO - PID 2564 |           Blocker:  Waiting for ping task
[task 2023-02-01T23:59:01.952Z] 23:59:01     INFO - PID 2564 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2023-02-01T23:59:01.953Z] 23:59:01     INFO - PID 2564 |           State: (none)
[task 2023-02-01T23:59:01.953Z] 23:59:01     INFO - PID 2564 | WARNING: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-02-01T23:59:01.954Z] 23:59:01     INFO - PID 2564 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-02-01T23:59:01.955Z] 23:59:01     INFO - PID 2564 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-02-01T23:59:01.955Z] 23:59:01     INFO - PID 2564 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-02-01T23:59:01.956Z] 23:59:01     INFO - PID 2564 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-02-01T23:59:01.956Z] 23:59:01     INFO - PID 2564 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-02-01T23:59:01.957Z] 23:59:01     INFO - PID 2564 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-02-01T23:59:01.958Z] 23:59:01     INFO - PID 2564 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-02-01T23:59:01.958Z] 23:59:01     INFO - PID 2564 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-02-01T23:59:01.959Z] 23:59:01     INFO - PID 2564 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-02-01T23:59:01.959Z] 23:59:01     INFO - PID 2564 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-02-01T23:59:01.960Z] 23:59:01     INFO - PID 2564 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-02-01T23:59:01.961Z] 23:59:01     INFO - PID 2564 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-02-01T23:59:01.961Z] 23:59:01     INFO - PID 2564 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-02-01T23:59:01.962Z] 23:59:01     INFO - PID 2564 |           Blocker:  TelemetryController: shutting down
[task 2023-02-01T23:59:01.962Z] 23:59:01     INFO - PID 2564 |           Phase: profile-before-change-telemetry
[task 2023-02-01T23:59:01.963Z] 23:59:01     INFO - PID 2564 |           State: Error getting state: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange" at addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-02-01T23:59:01.964Z] 23:59:01     INFO - PID 2564 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-02-01T23:59:01.964Z] 23:59:01     INFO - PID 2564 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-02-01T23:59:01.965Z] 23:59:01     INFO - PID 2564 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-02-01T23:59:01.965Z] 23:59:01     INFO - PID 2564 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-02-01T23:59:01.966Z] 23:59:01     INFO - PID 2564 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-02-01T23:59:01.966Z] 23:59:01     INFO - PID 2564 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-02-01T23:59:01.967Z] 23:59:01     INFO - PID 2564 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-02-01T23:59:01.968Z] 23:59:01     INFO - PID 2564 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-02-01T23:59:01.968Z] 23:59:01     INFO - PID 2564 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-02-01T23:59:01.969Z] 23:59:01     INFO - PID 2564 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-02-01T23:59:01.970Z] 23:59:01     INFO - PID 2564 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-02-01T23:59:01.970Z] 23:59:01     INFO - PID 2564 | WARNING: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-02-01T23:59:01.971Z] 23:59:01     INFO - PID 2564 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-02-01T23:59:01.971Z] 23:59:01     INFO - PID 2564 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-02-01T23:59:01.972Z] 23:59:01     INFO - PID 2564 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-02-01T23:59:01.972Z] 23:59:01     INFO - PID 2564 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-02-01T23:59:01.973Z] 23:59:01     INFO - PID 2564 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-02-01T23:59:01.973Z] 23:59:01     INFO - PID 2564 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-02-01T23:59:01.974Z] 23:59:01     INFO - PID 2564 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-02-01T23:59:01.974Z] 23:59:01     INFO - PID 2564 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-02-01T23:59:01.975Z] 23:59:01     INFO - PID 2564 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-02-01T23:59:01.975Z] 23:59:01     INFO - PID 2564 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-02-01T23:59:01.976Z] 23:59:01     INFO - PID 2564 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-02-01T23:59:01.977Z] 23:59:01     INFO - PID 2564 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-02-01T23:59:01.978Z] 23:59:01     INFO - PID 2564 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-02-01T23:59:01.979Z] 23:59:01     INFO - PID 2564 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-02-01T23:59:01.980Z] 23:59:01     INFO - PID 2564 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-02-01T23:59:01.981Z] 23:59:01     INFO - PID 2564 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-02-01T23:59:01.981Z] 23:59:01     INFO - PID 2564 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-02-01T23:59:02.063Z] 23:59:02     INFO - Browser exited with return code 0
[task 2023-02-01T23:59:02.066Z] 23:59:02     INFO - Closing logging queue
[task 2023-02-01T23:59:02.067Z] 23:59:02     INFO - queue closed
[task 2023-02-01T23:59:02.144Z] 23:59:02     INFO - Application command: Z:\task_167529134999076\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_167529134999076\AppData\Local\Temp\tmpiyvy1efq
[task 2023-02-01T23:59:02.151Z] 23:59:02     INFO - PID 2976 | 1675295940095	Marionette	INFO	Marionette enabled
[task 2023-02-01T23:59:02.152Z] 23:59:02     INFO - PID 2976 | 1675295940099	Marionette	INFO	Listening on port 54257
[task 2023-02-01T23:59:02.152Z] 23:59:02     INFO - PID 2976 | JavaScript error: resource://gre/modules/XULStore.jsm, line 58: Error: Can't find profile directory.
[task 2023-02-01T23:59:02.153Z] 23:59:02     INFO - PID 2976 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-02-01T23:59:02.154Z] 23:59:02     INFO - PID 2976 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_167529134999076\\AppData\\Local\\Temp\\tmpar3l173i\\search.json.mozlz4", (void 0)))
[task 2023-02-01T23:59:02.154Z] 23:59:02     INFO - Starting runner
[task 2023-02-01T23:59:02.238Z] 23:59:02     INFO - PID 2976 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-02-01T23:59:02.238Z] 23:59:02     INFO - PID 2976 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-02-01T23:59:02.238Z] 23:59:02     INFO - PID 2976 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 3 years ago → 3 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.