Closed Bug 1799783 Opened 3 years ago Closed 2 years ago

Intermittent /css/css-layout-api/baseline/orthogonal-baseline.https.html | single tracking bug

Categories

(Core :: Layout, defect, P5)

Product:
Core
Component:
Layout
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, intermittent-testcase)

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

[task 2022-11-08T23:26:25.744Z] 23:26:25     INFO - TEST-START | /css/css-layout-api/baseline/orthogonal-baseline.https.html
[task 2022-11-08T23:26:25.745Z] 23:26:25     INFO - PID 7856 | 1667949985745	Marionette	INFO	Testing https://web-platform.test:8443/css/css-layout-api/baseline/orthogonal-baseline.https.html == http://web-platform.test:8000/css/css-layout-api/green-square-ref.html
[task 2022-11-08T23:26:28.268Z] 23:26:28     INFO - TEST-UNEXPECTED-TIMEOUT | /css/css-layout-api/baseline/orthogonal-baseline.https.html | expected FAIL
[task 2022-11-08T23:26:28.268Z] 23:26:28     INFO - TEST-INFO expected FAIL | took 2525ms
[task 2022-11-08T23:26:28.428Z] 23:26:28     INFO - Restarting browser for new test group
[task 2022-11-08T23:26:28.432Z] 23:26:28     INFO - PID 7856 | 1667949988437	Marionette	INFO	Stopped listening on port 50000
[task 2022-11-08T23:26:30.220Z] 23:26:30     INFO - PID 7856 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-11-08T23:26:30.221Z] 23:26:30     INFO - PID 7856 |           Blocker:  Waiting for ping task
[task 2022-11-08T23:26:30.222Z] 23:26:30     INFO - PID 7856 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2022-11-08T23:26:30.223Z] 23:26:30     INFO - PID 7856 |           State: (none)
[task 2022-11-08T23:26:30.224Z] 23:26:30     INFO - PID 7856 | 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-11-08T23:26:30.224Z] 23:26:30     INFO - PID 7856 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.jsm:703:15
[task 2022-11-08T23:26:30.224Z] 23:26:30     INFO - PID 7856 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:511:26
[task 2022-11-08T23:26:30.225Z] 23:26:30     INFO - PID 7856 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:450:15
[task 2022-11-08T23:26:30.225Z] 23:26:30     INFO - PID 7856 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1563:28
[task 2022-11-08T23:26:30.226Z] 23:26:30     INFO - PID 7856 | @resource://gre/modules/osfile/osfile_async_front.jsm:1583:16
[task 2022-11-08T23:26:30.227Z] 23:26:30     INFO - PID 7856 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-11-08T23:26:30.227Z] 23:26:30     INFO - PID 7856 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-11-08T23:26:30.228Z] 23:26:30     INFO - PID 7856 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:103:5
[task 2022-11-08T23:26:30.228Z] 23:26:30     INFO - PID 7856 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:63:33
[task 2022-11-08T23:26:30.229Z] 23:26:30     INFO - PID 7856 | _submitPingLogic@resource://gre/modules/TelemetryControllerParent.jsm:554:48
[task 2022-11-08T23:26:30.229Z] 23:26:30     INFO - PID 7856 | send@resource://gre/modules/TelemetryControllerParent.jsm:642:24
[task 2022-11-08T23:26:30.230Z] 23:26:30     INFO - PID 7856 | submitExternalPing@resource://gre/modules/TelemetryControllerParent.jsm:182:17
[task 2022-11-08T23:26:30.230Z] 23:26:30     INFO - PID 7856 | sendPing@resource://gre/modules/EventPing.jsm:51:30
[task 2022-11-08T23:26:30.231Z] 23:26:30     INFO - PID 7856 | _submitPing@resource://gre/modules/EventPing.jsm:229:12
[task 2022-11-08T23:26:30.231Z] 23:26:30     INFO - PID 7856 | shutdown@resource://gre/modules/EventPing.jsm:112:10
[task 2022-11-08T23:26:30.231Z] 23:26:30     INFO - PID 7856 | _cleanupOnShutdown@resource://gre/modules/TelemetryControllerParent.jsm:954:31
[task 2022-11-08T23:26:30.232Z] 23:26:30     INFO - PID 7856 | shutdown/<@resource://gre/modules/TelemetryControllerParent.jsm:1024:24
[task 2022-11-08T23:26:30.233Z] 23:26:30     INFO - PID 7856 | observe@resource://gre/modules/AsyncShutdown.jsm:560:16
[task 2022-11-08T23:26:30.238Z] 23:26:30     INFO - PID 7856 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-11-08T23:26:30.239Z] 23:26:30     INFO - PID 7856 |           Blocker:  Waiting for ping task
[task 2022-11-08T23:26:30.240Z] 23:26:30     INFO - PID 7856 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2022-11-08T23:26:30.240Z] 23:26:30     INFO - PID 7856 |           State: (none)
[task 2022-11-08T23:26:30.241Z] 23:26:30     INFO - PID 7856 | 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-11-08T23:26:30.241Z] 23:26:30     INFO - PID 7856 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.jsm:703:15
[task 2022-11-08T23:26:30.242Z] 23:26:30     INFO - PID 7856 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:511:26
[task 2022-11-08T23:26:30.242Z] 23:26:30     INFO - PID 7856 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:450:15
[task 2022-11-08T23:26:30.243Z] 23:26:30     INFO - PID 7856 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1563:28
[task 2022-11-08T23:26:30.243Z] 23:26:30     INFO - PID 7856 | @resource://gre/modules/osfile/osfile_async_front.jsm:1583:16
[task 2022-11-08T23:26:30.244Z] 23:26:30     INFO - PID 7856 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-11-08T23:26:30.244Z] 23:26:30     INFO - PID 7856 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-11-08T23:26:30.245Z] 23:26:30     INFO - PID 7856 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:103:5
[task 2022-11-08T23:26:30.246Z] 23:26:30     INFO - PID 7856 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:63:33
[task 2022-11-08T23:26:30.247Z] 23:26:30     INFO - PID 7856 | _submitPingLogic@resource://gre/modules/TelemetryControllerParent.jsm:554:48
[task 2022-11-08T23:26:30.247Z] 23:26:30     INFO - PID 7856 | send@resource://gre/modules/TelemetryControllerParent.jsm:642:24
[task 2022-11-08T23:26:30.248Z] 23:26:30     INFO - PID 7856 | submitExternalPing@resource://gre/modules/TelemetryControllerParent.jsm:182:17
[task 2022-11-08T23:26:30.248Z] 23:26:30     INFO - PID 7856 | saveShutdownPings@resource://gre/modules/TelemetrySession.jsm:982:34
[task 2022-11-08T23:26:30.249Z] 23:26:30     INFO - PID 7856 | shutdownChromeProcess/cleanup/<@resource://gre/modules/TelemetrySession.jsm:1232:20
[task 2022-11-08T23:26:30.249Z] 23:26:30     INFO - PID 7856 | cleanup@resource://gre/modules/TelemetrySession.jsm:1239:9
[task 2022-11-08T23:26:30.250Z] 23:26:30     INFO - PID 7856 | shutdownChromeProcess@resource://gre/modules/TelemetrySession.jsm:1256:14
[task 2022-11-08T23:26:30.250Z] 23:26:30     INFO - PID 7856 | shutdown@resource://gre/modules/TelemetrySession.jsm:247:17
[task 2022-11-08T23:26:30.251Z] 23:26:30     INFO - PID 7856 | _cleanupOnShutdown@resource://gre/modules/TelemetryControllerParent.jsm:973:35
[task 2022-11-08T23:26:30.251Z] 23:26:30     INFO - PID 7856 | observe@resource://gre/modules/AsyncShutdown.jsm:560:16
[task 2022-11-08T23:26:30.252Z] 23:26:30     INFO - PID 7856 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-11-08T23:26:30.252Z] 23:26:30     INFO - PID 7856 |           Blocker:  TelemetryController: shutting down
[task 2022-11-08T23:26:30.253Z] 23:26:30     INFO - PID 7856 |           Phase: profile-before-change-telemetry
[task 2022-11-08T23:26:30.254Z] 23:26:30     INFO - PID 7856 |           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:703:15
[task 2022-11-08T23:26:30.254Z] 23:26:30     INFO - PID 7856 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:511:26
[task 2022-11-08T23:26:30.255Z] 23:26:30     INFO - PID 7856 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:450:15
[task 2022-11-08T23:26:30.256Z] 23:26:30     INFO - PID 7856 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1563:28
[task 2022-11-08T23:26:30.256Z] 23:26:30     INFO - PID 7856 | @resource://gre/modules/osfile/osfile_async_front.jsm:1583:16
[task 2022-11-08T23:26:30.257Z] 23:26:30     INFO - PID 7856 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-11-08T23:26:30.257Z] 23:26:30     INFO - PID 7856 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-11-08T23:26:30.257Z] 23:26:30     INFO - PID 7856 | getShutdownState@resource://gre/modules/TelemetrySend.jsm:1687:7
[task 2022-11-08T23:26:30.259Z] 23:26:30     INFO - PID 7856 | getShutdownState@resource://gre/modules/TelemetrySend.jsm:369:30
[task 2022-11-08T23:26:30.260Z] 23:26:30     INFO - PID 7856 | _getState@resource://gre/modules/TelemetryControllerParent.jsm:1072:38
[task 2022-11-08T23:26:30.260Z] 23:26:30     INFO - PID 7856 | setupTelemetry/<@resource://gre/modules/TelemetryControllerParent.jsm:931:18
[task 2022-11-08T23:26:30.261Z] 23:26:30     INFO - PID 7856 | safeGetState@resource://gre/modules/AsyncShutdown.jsm:242:17
[task 2022-11-08T23:26:30.261Z] 23:26:30     INFO - PID 7856 | Barrier/addBlocker/promise<@resource://gre/modules/AsyncShutdown.jsm:751:20
[task 2022-11-08T23:26:30.262Z] 23:26:30     INFO - PID 7856 | observe@resource://gre/modules/AsyncShutdown.jsm:560:16
[task 2022-11-08T23:26:30.262Z] 23:26:30     INFO - PID 7856 | 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-11-08T23:26:30.264Z] 23:26:30     INFO - PID 7856 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.jsm:703:15
[task 2022-11-08T23:26:30.264Z] 23:26:30     INFO - PID 7856 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:511:26
[task 2022-11-08T23:26:30.264Z] 23:26:30     INFO - PID 7856 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:450:15
[task 2022-11-08T23:26:30.265Z] 23:26:30     INFO - PID 7856 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1563:28
[task 2022-11-08T23:26:30.266Z] 23:26:30     INFO - PID 7856 | @resource://gre/modules/osfile/osfile_async_front.jsm:1583:16
[task 2022-11-08T23:26:30.266Z] 23:26:30     INFO - PID 7856 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-11-08T23:26:30.267Z] 23:26:30     INFO - PID 7856 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-11-08T23:26:30.268Z] 23:26:30     INFO - PID 7856 | removeAbortedSessionPing@resource://gre/modules/TelemetryControllerParent.jsm:735:5
[task 2022-11-08T23:26:30.268Z] 23:26:30     INFO - PID 7856 | removeAbortedSessionPing@resource://gre/modules/TelemetryControllerParent.jsm:249:17
[task 2022-11-08T23:26:30.269Z] 23:26:30     INFO - PID 7856 | shutdownChromeProcess/cleanup/<@resource://gre/modules/TelemetrySession.jsm:1235:42
[task 2022-11-08T23:26:30.269Z] 23:26:30     INFO - PID 7856 | observe@resource://gre/modules/AsyncShutdown.jsm:560:16
[task 2022-11-08T23:26:30.305Z] 23:26:30     INFO - PID 7856 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 703: 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-11-08T23:26:30.306Z] 23:26:30     INFO - PID 7856 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 703: 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-11-08T23:26:30.307Z] 23:26:30     INFO - PID 7856 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 703: 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-11-08T23:26:30.307Z] 23:26:30     INFO - PID 7856 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 703: 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-11-08T23:26:30.308Z] 23:26:30     INFO - PID 7856 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 703: 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-11-08T23:26:30.380Z] 23:26:30     INFO - Browser exited with return code 0
[task 2022-11-08T23:26:30.382Z] 23:26:30     INFO - Closing logging queue
[task 2022-11-08T23:26:30.385Z] 23:26:30     INFO - queue closed
[task 2022-11-08T23:26:30.424Z] 23:26:30     INFO - Application command: Z:\task_166794278185789\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_166794278185789\AppData\Local\Temp\tmptcle80gu
[task 2022-11-08T23:26:30.429Z] 23:26:30     INFO - PID 8144 | 1667949984282	Marionette	INFO	Marionette enabled
[task 2022-11-08T23:26:30.430Z] 23:26:30     INFO - PID 8144 | 1667949984284	Marionette	INFO	Listening on port 50029
[task 2022-11-08T23:26:30.431Z] 23:26:30     INFO - PID 8144 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_166794278185789\\AppData\\Local\\Temp\\tmp3jpn1ytj\\search.json.mozlz4", (void 0)))
[task 2022-11-08T23:26:30.431Z] 23:26:30     INFO - PID 8144 | *** You are running in background task mode. ***
[task 2022-11-08T23:26:30.431Z] 23:26:30     INFO - PID 8144 | *** You are running in headless mode.
[task 2022-11-08T23:26:30.432Z] 23:26:30     INFO - PID 8144 | console.error: "C:\\Users\\task_166794278185789\\AppData\\Local\\Temp\\tmp3jpn1ytj" "" 0 ".purge.bg_rm"
[task 2022-11-08T23:26:30.433Z] 23:26:30     INFO - Starting runner
[task 2022-11-08T23:26:31.445Z] 23:26:31     INFO - TEST-START | /css/css-layout-api/box-tree-registered.https.html

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=433446242&repo=mozilla-central

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.

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