Closed Bug 1807532 Opened 3 years ago Closed 1 year ago

Intermittent /css/CSS2/pagination/block-page-break-inside-avoid-10-print.html | single tracking bug

Categories

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

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, intermittent-testcase)

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


[task 2022-12-26T05:41:38.669Z] 05:41:38     INFO - TEST-START | /css/CSS2/pagination/block-page-break-inside-avoid-10-print.html
[task 2022-12-26T05:41:38.671Z] 05:41:38     INFO - PID 8972 | 1672033298671	Marionette	INFO	Testing http://web-platform.test:8000/css/CSS2/pagination/block-page-break-inside-avoid-10-print.html == http://web-platform.test:8000/css/CSS2/pagination/block-page-break-inside-avoid-print-ref.html
[task 2022-12-26T05:42:00.275Z] 05:42:00     INFO - TEST-UNEXPECTED-TIMEOUT | /css/CSS2/pagination/block-page-break-inside-avoid-10-print.html | expected PASS
[task 2022-12-26T05:42:00.275Z] 05:42:00     INFO - TEST-INFO took 21608ms
[task 2022-12-26T05:42:00.316Z] 05:42:00     INFO - PID 8972 | 1672033320329	Marionette	INFO	Stopped listening on port 49886
[task 2022-12-26T05:42:00.447Z] 05:42:00     INFO - PID 8972 | console.log: "Warning: Setting up fake worker."
[task 2022-12-26T05:42:00.547Z] 05:42:00     INFO - PID 8972 | console.error: "Failed to import bookmarks from chrome://browser/content/default-bookmarks.html: Error: places.sqlite#1: cannot execute operation GuidHelper.getItemId, the connection is already closing"
[task 2022-12-26T05:42:00.688Z] 05:42:00     INFO - PID 8972 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-12-26T05:42:00.688Z] 05:42:00     INFO - PID 8972 |           Blocker:  Waiting for ping task
[task 2022-12-26T05:42:00.688Z] 05:42:00     INFO - PID 8972 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2022-12-26T05:42:00.688Z] 05:42:00     INFO - PID 8972 |           State: (none)
[task 2022-12-26T05:42:00.688Z] 05:42:00     INFO - PID 8972 | 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-26T05:42:00.688Z] 05:42:00     INFO - PID 8972 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:712:15
[task 2022-12-26T05:42:00.688Z] 05:42:00     INFO - PID 8972 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:517:26
[task 2022-12-26T05:42:00.688Z] 05:42:00     INFO - PID 8972 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:456:15
[task 2022-12-26T05:42:00.688Z] 05:42:00     INFO - PID 8972 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2022-12-26T05:42:00.696Z] 05:42:00     INFO - PID 8972 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2022-12-26T05:42:00.696Z] 05:42:00     INFO - PID 8972 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-12-26T05:42:00.696Z] 05:42:00     INFO - PID 8972 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2022-12-26T05:42:00.698Z] 05:42:00     INFO - PID 8972 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2022-12-26T05:42:00.698Z] 05:42:00     INFO - PID 8972 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2022-12-26T05:42:00.699Z] 05:42:00     INFO - PID 8972 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2022-12-26T05:42:00.699Z] 05:42:00     INFO - PID 8972 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2022-12-26T05:42:00.699Z] 05:42:00     INFO - PID 8972 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:566:16
[task 2022-12-26T05:42:00.714Z] 05:42:00     INFO - PID 8972 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-12-26T05:42:00.714Z] 05:42:00     INFO - PID 8972 |           Blocker:  Waiting for ping task
[task 2022-12-26T05:42:00.714Z] 05:42:00     INFO - PID 8972 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2022-12-26T05:42:00.716Z] 05:42:00     INFO - PID 8972 |           State: (none)
[task 2022-12-26T05:42:00.717Z] 05:42:00     INFO - PID 8972 | 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-26T05:42:00.718Z] 05:42:00     INFO - PID 8972 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:712:15
[task 2022-12-26T05:42:00.718Z] 05:42:00     INFO - PID 8972 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:517:26
[task 2022-12-26T05:42:00.718Z] 05:42:00     INFO - PID 8972 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:456:15
[task 2022-12-26T05:42:00.718Z] 05:42:00     INFO - PID 8972 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2022-12-26T05:42:00.718Z] 05:42:00     INFO - PID 8972 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2022-12-26T05:42:00.722Z] 05:42:00     INFO - PID 8972 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-12-26T05:42:00.723Z] 05:42:00     INFO - PID 8972 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2022-12-26T05:42:00.724Z] 05:42:00     INFO - PID 8972 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2022-12-26T05:42:00.724Z] 05:42:00     INFO - PID 8972 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2022-12-26T05:42:00.725Z] 05:42:00     INFO - PID 8972 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2022-12-26T05:42:00.726Z] 05:42:00     INFO - PID 8972 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2022-12-26T05:42:00.727Z] 05:42:00     INFO - PID 8972 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:566:16
[task 2022-12-26T05:42:00.727Z] 05:42:00     INFO - PID 8972 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-12-26T05:42:00.727Z] 05:42:00     INFO - PID 8972 |           Blocker:  TelemetryController: shutting down
[task 2022-12-26T05:42:00.729Z] 05:42:00     INFO - PID 8972 |           Phase: profile-before-change-telemetry
[task 2022-12-26T05:42:00.730Z] 05:42:00     INFO - PID 8972 |           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:712:15
[task 2022-12-26T05:42:00.731Z] 05:42:00     INFO - PID 8972 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:517:26
[task 2022-12-26T05:42:00.732Z] 05:42:00     INFO - PID 8972 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:456:15
[task 2022-12-26T05:42:00.733Z] 05:42:00     INFO - PID 8972 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2022-12-26T05:42:00.733Z] 05:42:00     INFO - PID 8972 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2022-12-26T05:42:00.733Z] 05:42:00     INFO - PID 8972 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-12-26T05:42:00.735Z] 05:42:00     INFO - PID 8972 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2022-12-26T05:42:00.735Z] 05:42:00     INFO - PID 8972 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2022-12-26T05:42:00.735Z] 05:42:00     INFO - PID 8972 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2022-12-26T05:42:00.737Z] 05:42:00     INFO - PID 8972 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2022-12-26T05:42:00.738Z] 05:42:00     INFO - PID 8972 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2022-12-26T05:42:00.738Z] 05:42:00     INFO - PID 8972 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:566:16
[task 2022-12-26T05:42:00.740Z] 05:42:00     INFO - PID 8972 | 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-26T05:42:00.740Z] 05:42:00     INFO - PID 8972 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:712:15
[task 2022-12-26T05:42:00.743Z] 05:42:00     INFO - PID 8972 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:517:26
[task 2022-12-26T05:42:00.743Z] 05:42:00     INFO - PID 8972 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:456:15
[task 2022-12-26T05:42:00.743Z] 05:42:00     INFO - PID 8972 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2022-12-26T05:42:00.743Z] 05:42:00     INFO - PID 8972 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2022-12-26T05:42:00.743Z] 05:42:00     INFO - PID 8972 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-12-26T05:42:00.743Z] 05:42:00     INFO - PID 8972 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2022-12-26T05:42:00.743Z] 05:42:00     INFO - PID 8972 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2022-12-26T05:42:00.746Z] 05:42:00     INFO - PID 8972 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2022-12-26T05:42:00.747Z] 05:42:00     INFO - PID 8972 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2022-12-26T05:42:00.748Z] 05:42:00     INFO - PID 8972 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2022-12-26T05:42:00.749Z] 05:42:00     INFO - PID 8972 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:566:16
[task 2022-12-26T05:42:00.749Z] 05:42:00     INFO - PID 8972 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 712: 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-26T05:42:00.749Z] 05:42:00     INFO - PID 8972 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 712: 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-26T05:42:00.755Z] 05:42:00     INFO - PID 8972 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 712: 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-26T05:42:00.755Z] 05:42:00     INFO - PID 8972 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 712: 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-26T05:42:00.755Z] 05:42:00     INFO - PID 8972 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 712: 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-26T05:42:02.042Z] 05:42:02     INFO - PID 8972 | [Parent 3572, IPC I/O Parent] WARNING: Process 1328 may be hanging at shutdown; will wait for up to 80000ms: file Z:/task_167202787227167/build/src/ipc/chromium/src/chrome/common/process_watcher_win.cc:129
[task 2022-12-26T05:42:03.308Z] 05:42:03     INFO - Browser exited with return code 0
[task 2022-12-26T05:42:03.311Z] 05:42:03     INFO - Closing logging queue
[task 2022-12-26T05:42:03.312Z] 05:42:03     INFO - queue closed
[task 2022-12-26T05:42:03.341Z] 05:42:03     INFO - Application command: Z:\task_167203291918369\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_167203291918369\AppData\Local\Temp\tmpnkvxljem
[task 2022-12-26T05:42:03.348Z] 05:42:03     INFO - PID 7100 | 1672033281091	Marionette	INFO	Marionette enabled
[task 2022-12-26T05:42:03.349Z] 05:42:03     INFO - PID 7100 | 1672033281098	Marionette	INFO	Listening on port 49887
[task 2022-12-26T05:42:03.349Z] 05:42:03     INFO - PID 7100 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 237))
[task 2022-12-26T05:42:03.351Z] 05:42:03     INFO - PID 7100 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_167203291918369\\AppData\\Local\\Temp\\tmps3g6ahb3\\search.json.mozlz4", (void 0)))
[task 2022-12-26T05:42:03.352Z] 05:42:03     INFO - PID 7100 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 237))
[task 2022-12-26T05:42:03.352Z] 05:42:03     INFO - PID 7100 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 237))
[task 2022-12-26T05:42:03.353Z] 05:42:03     INFO - PID 7100 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 237))
[task 2022-12-26T05:42:03.353Z] 05:42:03     INFO - Starting runner
[task 2022-12-26T05:42:04.282Z] 05:42:04     INFO - TEST-START | /css/CSS2/pagination/block-page-break-inside-avoid-11-print.html
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
Summary: Intermittent [tier 2] /css/CSS2/pagination/block-page-break-inside-avoid-10-print.html | single tracking bug → Intermittent /css/CSS2/pagination/block-page-break-inside-avoid-10-print.html | single tracking bug
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 3 years ago3 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 3 years ago2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 2 years ago2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 2 years ago2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 2 years ago2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 2 years ago1 year ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.