Closed Bug 1803842 Opened 2 years ago Closed 2 years ago

Intermittent /css/css-color/deprecated-sameas-018.html | Testing http://web-platform.test:8000/css/css-color/deprecated-sameas-018.html == http://web-platform.test:8000/css/css-color/deprecated-sameas-ButtonBorder-ref.html

Categories

(Core :: CSS Parsing and Computation, defect, P5)

Product:
Core
Component:
CSS Parsing and Computation
Type:
defect

Tracking

()

Status:
RESOLVED FIXED
Milestone:
109 Branch
Tracking Flags:
Tracking Status
firefox-esr102 --- unaffected
firefox107 --- unaffected
firefox108 --- unaffected
firefox109 --- fixed

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: intermittent-failure, regression)

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

[task 2022-12-02T21:05:38.503Z] 21:05:38     INFO - TEST-START | /css/css-color/deprecated-sameas-018.html
[task 2022-12-02T21:05:38.504Z] 21:05:38     INFO - Run 2/10
[task 2022-12-02T21:05:38.515Z] 21:05:38     INFO - Setting pref dom.animations-api.compositing.enabled to true
[task 2022-12-02T21:05:38.525Z] 21:05:38     INFO - Setting pref layout.css.cascade-layers.enabled to true
[task 2022-12-02T21:05:38.533Z] 21:05:38     INFO - Setting pref layout.css.color-mix.enabled to true
[task 2022-12-02T21:05:38.540Z] 21:05:38     INFO - Setting pref layout.css.color-mix.color-spaces.enabled to true
[task 2022-12-02T21:05:38.636Z] 21:05:38     INFO - PID 1135 | 1670015138632	Marionette	INFO	Testing http://web-platform.test:8000/css/css-color/deprecated-sameas-018.html == http://web-platform.test:8000/css/css-color/deprecated-sameas-ButtonBorder-ref.html
[task 2022-12-02T21:05:38.799Z] 21:05:38     INFO - PID 1135 | 1670015138798	Marionette	INFO	No differences allowed
[task 2022-12-02T21:05:38.847Z] 21:05:38     INFO - TEST-UNEXPECTED-FAIL | /css/css-color/deprecated-sameas-018.html | Testing http://web-platform.test:8000/css/css-color/deprecated-sameas-018.html == http://web-platform.test:8000/css/css-color/deprecated-sameas-ButtonBorder-ref.html
[task 2022-12-02T21:05:38.847Z] 21:05:38     INFO - Found 18432 pixels different, maximum difference per channel 28
[task 2022-12-02T21:05:38.847Z] 21:05:38     INFO - TEST-INFO took 305ms
[task 2022-12-02T21:05:38.848Z] 21:05:38     INFO - PID 1135 | 1670015138832	Marionette	INFO	Stopped listening on port 49280
[task 2022-12-02T21:05:38.927Z] 21:05:38     INFO - PID 1135 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-12-02T21:05:38.928Z] 21:05:38     INFO - PID 1135 |           Blocker:  Waiting for ping task
[task 2022-12-02T21:05:38.928Z] 21:05:38     INFO - PID 1135 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2022-12-02T21:05:38.928Z] 21:05:38     INFO - PID 1135 |           State: (none)
[task 2022-12-02T21:05:38.929Z] 21:05:38     INFO - PID 1135 | 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 2022-12-02T21:05:38.929Z] 21:05:38     INFO - PID 1135 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.jsm:718:15
[task 2022-12-02T21:05:38.930Z] 21:05:38     INFO - PID 1135 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:523:26
[task 2022-12-02T21:05:38.930Z] 21:05:38     INFO - PID 1135 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:462:15
[task 2022-12-02T21:05:38.930Z] 21:05:38     INFO - PID 1135 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2022-12-02T21:05:38.931Z] 21:05:38     INFO - PID 1135 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2022-12-02T21:05:38.931Z] 21:05:38     INFO - PID 1135 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-12-02T21:05:38.932Z] 21:05:38     INFO - PID 1135 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-12-02T21:05:38.932Z] 21:05:38     INFO - PID 1135 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:103:5
[task 2022-12-02T21:05:38.932Z] 21:05:38     INFO - PID 1135 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:63:33
[task 2022-12-02T21:05:38.933Z] 21:05:38     INFO - PID 1135 | _submitPingLogic@resource://gre/modules/TelemetryControllerParent.jsm:540:48
[task 2022-12-02T21:05:38.933Z] 21:05:38     INFO - PID 1135 | send@resource://gre/modules/TelemetryControllerParent.jsm:628:24
[task 2022-12-02T21:05:38.934Z] 21:05:38     INFO - PID 1135 | submitExternalPing@resource://gre/modules/TelemetryControllerParent.jsm:182:17
[task 2022-12-02T21:05:38.934Z] 21:05:38     INFO - PID 1135 | sendPing@resource://gre/modules/EventPing.jsm:51:30
[task 2022-12-02T21:05:38.934Z] 21:05:38     INFO - PID 1135 | _submitPing@resource://gre/modules/EventPing.jsm:229:12
[task 2022-12-02T21:05:38.935Z] 21:05:38     INFO - PID 1135 | shutdown@resource://gre/modules/EventPing.jsm:112:10
[task 2022-12-02T21:05:38.935Z] 21:05:38     INFO - PID 1135 | _cleanupOnShutdown@resource://gre/modules/TelemetryControllerParent.jsm:940:31
[task 2022-12-02T21:05:38.935Z] 21:05:38     INFO - PID 1135 | shutdown/<@resource://gre/modules/TelemetryControllerParent.jsm:1010:24
[task 2022-12-02T21:05:38.936Z] 21:05:38     INFO - PID 1135 | observe@resource://gre/modules/AsyncShutdown.jsm:572:16
[task 2022-12-02T21:05:38.937Z] 21:05:38     INFO - PID 1135 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-12-02T21:05:38.937Z] 21:05:38     INFO - PID 1135 |           Blocker:  Waiting for ping task
[task 2022-12-02T21:05:38.938Z] 21:05:38     INFO - PID 1135 |           State: (none)
[task 2022-12-02T21:05:38.939Z] 21:05:38     INFO - PID 1135 | 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 2022-12-02T21:05:38.939Z] 21:05:38     INFO - PID 1135 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.jsm:718:15
[task 2022-12-02T21:05:38.939Z] 21:05:38     INFO - PID 1135 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:523:26
[task 2022-12-02T21:05:38.940Z] 21:05:38     INFO - PID 1135 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:462:15
[task 2022-12-02T21:05:38.940Z] 21:05:38     INFO - PID 1135 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2022-12-02T21:05:38.940Z] 21:05:38     INFO - PID 1135 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2022-12-02T21:05:38.941Z] 21:05:38     INFO - PID 1135 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-12-02T21:05:38.941Z] 21:05:38     INFO - PID 1135 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-12-02T21:05:38.942Z] 21:05:38     INFO - PID 1135 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:103:5
[task 2022-12-02T21:05:38.942Z] 21:05:38     INFO - PID 1135 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:63:33
[task 2022-12-02T21:05:38.942Z] 21:05:38     INFO - PID 1135 | _submitPingLogic@resource://gre/modules/TelemetryControllerParent.jsm:540:48
[task 2022-12-02T21:05:38.943Z] 21:05:38     INFO - PID 1135 | send@resource://gre/modules/TelemetryControllerParent.jsm:628:24
[task 2022-12-02T21:05:38.943Z] 21:05:38     INFO - PID 1135 | submitExternalPing@resource://gre/modules/TelemetryControllerParent.jsm:182:17
[task 2022-12-02T21:05:38.944Z] 21:05:38     INFO - PID 1135 | saveShutdownPings@resource://gre/modules/TelemetrySession.jsm:982:34
[task 2022-12-02T21:05:38.944Z] 21:05:38     INFO - PID 1135 | shutdownChromeProcess/cleanup/<@resource://gre/modules/TelemetrySession.jsm:1232:20
[task 2022-12-02T21:05:38.944Z] 21:05:38     INFO - PID 1135 | cleanup@resource://gre/modules/TelemetrySession.jsm:1239:9
[task 2022-12-02T21:05:38.945Z] 21:05:38     INFO - PID 1135 | shutdownChromeProcess@resource://gre/modules/TelemetrySession.jsm:1256:14
[task 2022-12-02T21:05:38.945Z] 21:05:38     INFO - PID 1135 | shutdown@resource://gre/modules/TelemetrySession.jsm:247:17
[task 2022-12-02T21:05:38.946Z] 21:05:38     INFO - PID 1135 | _cleanupOnShutdown@resource://gre/modules/TelemetryControllerParent.jsm:959:35
[task 2022-12-02T21:05:38.946Z] 21:05:38     INFO - PID 1135 | observe@resource://gre/modules/AsyncShutdown.jsm:572:16
[task 2022-12-02T21:05:38.947Z] 21:05:38     INFO - PID 1135 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-12-02T21:05:38.948Z] 21:05:38     INFO - PID 1135 |           Blocker:  TelemetryController: shutting down
[task 2022-12-02T21:05:38.948Z] 21:05:38     INFO - PID 1135 |           Phase: profile-before-change-telemetry
[task 2022-12-02T21:05:38.949Z] 21:05:38     INFO - PID 1135 |           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.jsm:718:15
[task 2022-12-02T21:05:38.949Z] 21:05:38     INFO - PID 1135 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:523:26
[task 2022-12-02T21:05:38.949Z] 21:05:38     INFO - PID 1135 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:462:15
[task 2022-12-02T21:05:38.950Z] 21:05:38     INFO - PID 1135 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2022-12-02T21:05:38.950Z] 21:05:38     INFO - PID 1135 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2022-12-02T21:05:38.951Z] 21:05:38     INFO - PID 1135 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-12-02T21:05:38.951Z] 21:05:38     INFO - PID 1135 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-12-02T21:05:38.951Z] 21:05:38     INFO - PID 1135 | getShutdownState@resource://gre/modules/TelemetrySend.jsm:1692:7
[task 2022-12-02T21:05:38.952Z] 21:05:38     INFO - PID 1135 | getShutdownState@resource://gre/modules/TelemetrySend.jsm:374:30
[task 2022-12-02T21:05:38.952Z] 21:05:38     INFO - PID 1135 | _getState@resource://gre/modules/TelemetryControllerParent.jsm:1058:38
[task 2022-12-02T21:05:38.952Z] 21:05:38     INFO - PID 1135 | setupTelemetry/<@resource://gre/modules/TelemetryControllerParent.jsm:917:18
[task 2022-12-02T21:05:38.953Z] 21:05:38     INFO - PID 1135 | safeGetState@resource://gre/modules/AsyncShutdown.jsm:254:17
task 2022-12-02T21:05:38.953Z] 21:05:38     INFO - PID 1135 | Barrier/_internalAddBlocker/promise<@resource://gre/modules/AsyncShutdown.jsm:773:20
[task 2022-12-02T21:05:38.954Z] 21:05:38     INFO - PID 1135 | observe@resource://gre/modules/AsyncShutdown.jsm:572:16
[task 2022-12-02T21:05:38.954Z] 21:05:38     INFO - PID 1135 | 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 2022-12-02T21:05:38.954Z] 21:05:38     INFO - PID 1135 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.jsm:718:15
[task 2022-12-02T21:05:38.955Z] 21:05:38     INFO - PID 1135 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:523:26
[task 2022-12-02T21:05:38.955Z] 21:05:38     INFO - PID 1135 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:462:15
[task 2022-12-02T21:05:38.955Z] 21:05:38     INFO - PID 1135 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2022-12-02T21:05:38.956Z] 21:05:38     INFO - PID 1135 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2022-12-02T21:05:38.956Z] 21:05:38     INFO - PID 1135 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-12-02T21:05:38.956Z] 21:05:38     INFO - PID 1135 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-12-02T21:05:38.957Z] 21:05:38     INFO - PID 1135 | removeAbortedSessionPing@resource://gre/modules/TelemetryControllerParent.jsm:721:5
[task 2022-12-02T21:05:38.957Z] 21:05:38     INFO - PID 1135 | removeAbortedSessionPing@resource://gre/modules/TelemetryControllerParent.jsm:249:17
[task 2022-12-02T21:05:38.957Z] 21:05:38     INFO - PID 1135 | shutdownChromeProcess/cleanup/<@resource://gre/modules/TelemetrySession.jsm:1235:42
[task 2022-12-02T21:05:38.958Z] 21:05:38     INFO - PID 1135 | observe@resource://gre/modules/AsyncShutdown.jsm:572:16
[task 2022-12-02T21:05:38.958Z] 21:05:38     INFO - PID 1135 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 718: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2022-12-02T21:05:38.959Z] 21:05:38     INFO - PID 1135 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 718: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2022-12-02T21:05:38.959Z] 21:05:38     INFO - PID 1135 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 718: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2022-12-02T21:05:38.960Z] 21:05:38     INFO - PID 1135 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 718: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2022-12-02T21:05:38.960Z] 21:05:38     INFO - PID 1135 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 718: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2022-12-02T21:05:39.382Z] 21:05:39     INFO - Browser exited with return code 0
[task 2022-12-02T21:05:39.386Z] 21:05:39     INFO - Closing logging queue
[task 2022-12-02T21:05:39.386Z] 21:05:39     INFO - queue closed
[task 2022-12-02T21:05:39.404Z] 21:05:39     INFO - Application command: /opt/worker/tasks/task_167001496398700/build/application/Firefox Nightly.app/Contents/MacOS/firefox --marionette about:blank -foreground -profile /var/folders/cz/6v62kpvs2290ff7blmhjf2mw000014/T/tmp4nhdryfc
[task 2022-12-02T21:05:39.410Z] 21:05:39     INFO - Starting runner
[task 2022-12-02T21:05:39.861Z] 21:05:39     INFO - PID 1146 | 1670015139860	Marionette	INFO	Marionette enabled
[task 2022-12-02T21:05:39.888Z] 21:05:39     INFO - PID 1146 | 1670015139888	Marionette	INFO	Listening on port 49287
[task 2022-12-02T21:05:41.149Z] 21:05:41     INFO - PID 1146 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /var/folders/cz/6v62kpvs2290ff7blmhjf2mw000014/T/tmp4nhdryfc/search.json.mozlz4", (void 0)))
[task 2022-12-02T21:05:41.167Z] 21:05:41     INFO - PID 1146 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 230))
[task 2022-12-02T21:05:42.051Z] 21:05:42     INFO - PID 1146 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 230))
[task 2022-12-02T21:05:42.052Z] 21:05:42     INFO - PID 1146 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 230))
[task 2022-12-02T21:05:42.052Z] 21:05:42     INFO - PID 1146 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 230))
[task 2022-12-02T21:05:42.536Z] 21:05:42     INFO - TEST-START | /css/css-color/deprecated-sameas-018.html

:emilio, since you are the author of the regressor, bug 1638052, could you take a look?

For more information, please visit auto_nag documentation.

Flags: needinfo?(emilio)

tier 1 failure: https://treeherder.mozilla.org/logviewer?job_id=398503245&repo=autoland

Summary: Intermittent TV /css/css-color/deprecated-sameas-018.html | Testing http://web-platform.test:8000/css/css-color/deprecated-sameas-018.html == http://web-platform.test:8000/css/css-color/deprecated-sameas-ButtonBorder-ref.html → Intermittent /css/css-color/deprecated-sameas-018.html | Testing http://web-platform.test:8000/css/css-color/deprecated-sameas-018.html == http://web-platform.test:8000/css/css-color/deprecated-sameas-ButtonBorder-ref.html

Fixed by backing out bug 1638052

Status: NEW → RESOLVED
Closed: 2 years ago
Flags: needinfo?(emilio)
Resolution: --- → FIXED

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

status-firefox107: --- → unaffected
status-firefox108: --- → unaffected
status-firefox109: --- → affected
status-firefox-esr102: --- → unaffected
status-firefox109: affected → fixed
Target Milestone: --- → 109 Branch
You need to log in before you can comment on or make changes to this bug.