Closed Bug 1805659 Opened 3 years ago Closed 3 years ago

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

Categories

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

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

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, intermittent-testcase)

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

[task 2022-12-14T13:18:39.580Z] 13:18:39     INFO - TEST-START | /css/CSS2/pagination/block-page-break-inside-avoid-6-print.html
[task 2022-12-14T13:18:39.581Z] 13:18:39     INFO - PID 9024 | 1671023919580	Marionette	INFO	Testing http://web-platform.test:8000/css/CSS2/pagination/block-page-break-inside-avoid-6-print.html == http://web-platform.test:8000/css/CSS2/pagination/block-page-break-inside-avoid-print-ref.html
[task 2022-12-14T13:18:39.708Z] 13:18:39     INFO - PID 9024 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 230))
[task 2022-12-14T13:18:39.715Z] 13:18:39     INFO - PID 9024 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 230))
[task 2022-12-14T13:18:39.715Z] 13:18:39     INFO - PID 9024 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 230))
[task 2022-12-14T13:18:49.608Z] 13:18:49     INFO - TEST-UNEXPECTED-TIMEOUT | /css/CSS2/pagination/block-page-break-inside-avoid-6-print.html | expected PASS
[task 2022-12-14T13:18:49.608Z] 13:18:49     INFO - TEST-INFO took 10030ms
[task 2022-12-14T13:18:49.618Z] 13:18:49     INFO - PID 9024 | 1671023929632	Marionette	INFO	Stopped listening on port 49876
[task 2022-12-14T13:18:57.201Z] 13:18:57     INFO - PID 9024 | console.error: "Failed to import bookmarks from chrome://browser/content/default-bookmarks.html: Error: places.sqlite#1: cannot execute operation Bookmarks.jsm: insertBookmarkTree, the connection is already closing"
[task 2022-12-14T13:19:00.704Z] 13:19:00     INFO - PID 9024 | WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"Places Connection shutdown","state":{"PlacesShutdownProgress":5},"filename":"/builds/worker/checkouts/gecko/toolkit/components/places/Database.cpp","lineNumber":470,"stack":""}] Barrier: profile-before-change
[task 2022-12-14T13:19:02.621Z] 13:19:02     INFO - PID 9024 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-12-14T13:19:02.629Z] 13:19:02     INFO - PID 9024 |           Blocker:  Waiting for ping task
[task 2022-12-14T13:19:02.630Z] 13:19:02     INFO - PID 9024 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2022-12-14T13:19:02.630Z] 13:19:02     INFO - PID 9024 |           State: (none)
[task 2022-12-14T13:19:02.631Z] 13:19:02     INFO - PID 9024 | 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-14T13:19:02.632Z] 13:19:02     INFO - PID 9024 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.jsm:718:15
[task 2022-12-14T13:19:02.633Z] 13:19:02     INFO - PID 9024 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:523:26
[task 2022-12-14T13:19:02.633Z] 13:19:02     INFO - PID 9024 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:462:15
[task 2022-12-14T13:19:02.634Z] 13:19:02     INFO - PID 9024 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2022-12-14T13:19:02.634Z] 13:19:02     INFO - PID 9024 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2022-12-14T13:19:02.635Z] 13:19:02     INFO - PID 9024 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-12-14T13:19:02.635Z] 13:19:02     INFO - PID 9024 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-12-14T13:19:02.636Z] 13:19:02     INFO - PID 9024 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:103:5
[task 2022-12-14T13:19:02.637Z] 13:19:02     INFO - PID 9024 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:63:33
[task 2022-12-14T13:19:02.637Z] 13:19:02     INFO - PID 9024 | _submitPingLogic@resource://gre/modules/TelemetryControllerParent.jsm:540:48
[task 2022-12-14T13:19:02.638Z] 13:19:02     INFO - PID 9024 | send@resource://gre/modules/TelemetryControllerParent.jsm:628:24
[task 2022-12-14T13:19:02.639Z] 13:19:02     INFO - PID 9024 | submitExternalPing@resource://gre/modules/TelemetryControllerParent.jsm:182:17
[task 2022-12-14T13:19:02.639Z] 13:19:02     INFO - PID 9024 | sendPing@resource://gre/modules/EventPing.jsm:51:30
[task 2022-12-14T13:19:02.640Z] 13:19:02     INFO - PID 9024 | _submitPing@resource://gre/modules/EventPing.jsm:229:12
[task 2022-12-14T13:19:02.641Z] 13:19:02     INFO - PID 9024 | shutdown@resource://gre/modules/EventPing.jsm:112:10
[task 2022-12-14T13:19:02.641Z] 13:19:02     INFO - PID 9024 | _cleanupOnShutdown@resource://gre/modules/TelemetryControllerParent.jsm:940:31
[task 2022-12-14T13:19:02.642Z] 13:19:02     INFO - PID 9024 | shutdown/<@resource://gre/modules/TelemetryControllerParent.jsm:1010:24
[task 2022-12-14T13:19:02.642Z] 13:19:02     INFO - PID 9024 | observe@resource://gre/modules/AsyncShutdown.jsm:572:16
[task 2022-12-14T13:19:02.643Z] 13:19:02     INFO - PID 9024 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-12-14T13:19:02.643Z] 13:19:02     INFO - PID 9024 |           Blocker:  Waiting for ping task
[task 2022-12-14T13:19:02.644Z] 13:19:02     INFO - PID 9024 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2022-12-14T13:19:02.645Z] 13:19:02     INFO - PID 9024 |           State: (none)
[task 2022-12-14T13:19:02.646Z] 13:19:02     INFO - PID 9024 | 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-14T13:19:02.646Z] 13:19:02     INFO - PID 9024 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.jsm:718:15
[task 2022-12-14T13:19:02.647Z] 13:19:02     INFO - PID 9024 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:523:26
[task 2022-12-14T13:19:02.648Z] 13:19:02     INFO - PID 9024 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:462:15
[task 2022-12-14T13:19:02.648Z] 13:19:02     INFO - PID 9024 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2022-12-14T13:19:02.649Z] 13:19:02     INFO - PID 9024 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2022-12-14T13:19:02.649Z] 13:19:02     INFO - PID 9024 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-12-14T13:19:02.650Z] 13:19:02     INFO - PID 9024 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-12-14T13:19:02.650Z] 13:19:02     INFO - PID 9024 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:103:5
[task 2022-12-14T13:19:02.651Z] 13:19:02     INFO - PID 9024 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:63:33
[task 2022-12-14T13:19:02.651Z] 13:19:02     INFO - PID 9024 | _submitPingLogic@resource://gre/modules/TelemetryControllerParent.jsm:540:48
[task 2022-12-14T13:19:02.653Z] 13:19:02     INFO - PID 9024 | send@resource://gre/modules/TelemetryControllerParent.jsm:628:24
[task 2022-12-14T13:19:02.653Z] 13:19:02     INFO - PID 9024 | submitExternalPing@resource://gre/modules/TelemetryControllerParent.jsm:182:17
[task 2022-12-14T13:19:02.654Z] 13:19:02     INFO - PID 9024 | saveShutdownPings@resource://gre/modules/TelemetrySession.jsm:982:34
[task 2022-12-14T13:19:02.654Z] 13:19:02     INFO - PID 9024 | shutdownChromeProcess/cleanup/<@resource://gre/modules/TelemetrySession.jsm:1232:20
[task 2022-12-14T13:19:02.655Z] 13:19:02     INFO - PID 9024 | cleanup@resource://gre/modules/TelemetrySession.jsm:1239:9
[task 2022-12-14T13:19:02.655Z] 13:19:02     INFO - PID 9024 | shutdownChromeProcess@resource://gre/modules/TelemetrySession.jsm:1256:14
[task 2022-12-14T13:19:02.656Z] 13:19:02     INFO - PID 9024 | shutdown@resource://gre/modules/TelemetrySession.jsm:247:17
[task 2022-12-14T13:19:02.656Z] 13:19:02     INFO - PID 9024 | _cleanupOnShutdown@resource://gre/modules/TelemetryControllerParent.jsm:959:35
[task 2022-12-14T13:19:02.657Z] 13:19:02     INFO - PID 9024 | observe@resource://gre/modules/AsyncShutdown.jsm:572:16
[task 2022-12-14T13:19:02.657Z] 13:19:02     INFO - PID 9024 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-12-14T13:19:02.657Z] 13:19:02     INFO - PID 9024 |           Blocker:  TelemetryController: shutting down
[task 2022-12-14T13:19:02.658Z] 13:19:02     INFO - PID 9024 |           Phase: profile-before-change-telemetry
[task 2022-12-14T13:19:02.659Z] 13:19:02     INFO - PID 9024 |           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-14T13:19:02.660Z] 13:19:02     INFO - PID 9024 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:523:26
[task 2022-12-14T13:19:02.660Z] 13:19:02     INFO - PID 9024 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:462:15
[task 2022-12-14T13:19:02.661Z] 13:19:02     INFO - PID 9024 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2022-12-14T13:19:02.661Z] 13:19:02     INFO - PID 9024 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2022-12-14T13:19:02.662Z] 13:19:02     INFO - PID 9024 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-12-14T13:19:02.662Z] 13:19:02     INFO - PID 9024 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-12-14T13:19:02.663Z] 13:19:02     INFO - PID 9024 | getShutdownState@resource://gre/modules/TelemetrySend.jsm:1692:7
[task 2022-12-14T13:19:02.663Z] 13:19:02     INFO - PID 9024 | getShutdownState@resource://gre/modules/TelemetrySend.jsm:374:30
[task 2022-12-14T13:19:02.664Z] 13:19:02     INFO - PID 9024 | _getState@resource://gre/modules/TelemetryControllerParent.jsm:1058:38
[task 2022-12-14T13:19:02.664Z] 13:19:02     INFO - PID 9024 | setupTelemetry/<@resource://gre/modules/TelemetryControllerParent.jsm:917:18
[task 2022-12-14T13:19:02.665Z] 13:19:02     INFO - PID 9024 | safeGetState@resource://gre/modules/AsyncShutdown.jsm:254:17
[task 2022-12-14T13:19:02.665Z] 13:19:02     INFO - PID 9024 | Barrier/_internalAddBlocker/promise<@resource://gre/modules/AsyncShutdown.jsm:773:20
[task 2022-12-14T13:19:02.666Z] 13:19:02     INFO - PID 9024 | observe@resource://gre/modules/AsyncShutdown.jsm:572:16
[task 2022-12-14T13:19:02.666Z] 13:19:02     INFO - PID 9024 | 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-14T13:19:02.667Z] 13:19:02     INFO - PID 9024 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.jsm:718:15
[task 2022-12-14T13:19:02.667Z] 13:19:02     INFO - PID 9024 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:523:26
[task 2022-12-14T13:19:02.668Z] 13:19:02     INFO - PID 9024 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:462:15
[task 2022-12-14T13:19:02.669Z] 13:19:02     INFO - PID 9024 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2022-12-14T13:19:02.669Z] 13:19:02     INFO - PID 9024 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2022-12-14T13:19:02.670Z] 13:19:02     INFO - PID 9024 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-12-14T13:19:02.670Z] 13:19:02     INFO - PID 9024 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-12-14T13:19:02.671Z] 13:19:02     INFO - PID 9024 | removeAbortedSessionPing@resource://gre/modules/TelemetryControllerParent.jsm:721:5
[task 2022-12-14T13:19:02.671Z] 13:19:02     INFO - PID 9024 | removeAbortedSessionPing@resource://gre/modules/TelemetryControllerParent.jsm:249:17
[task 2022-12-14T13:19:02.672Z] 13:19:02     INFO - PID 9024 | shutdownChromeProcess/cleanup/<@resource://gre/modules/TelemetrySession.jsm:1235:42
[task 2022-12-14T13:19:02.672Z] 13:19:02     INFO - PID 9024 | observe@resource://gre/modules/AsyncShutdown.jsm:572:16
[task 2022-12-14T13:19:03.274Z] 13:19:03     INFO - PID 9024 | 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-14T13:19:03.276Z] 13:19:03     INFO - PID 9024 | 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-14T13:19:03.277Z] 13:19:03     INFO - PID 9024 | 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-14T13:19:03.278Z] 13:19:03     INFO - PID 9024 | 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-14T13:19:03.279Z] 13:19:03     INFO - PID 9024 | 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-14T13:19:03.341Z] 13:19:03     INFO - Browser exited with return code 0
[task 2022-12-14T13:19:03.344Z] 13:19:03     INFO - Closing logging queue
[task 2022-12-14T13:19:03.345Z] 13:19:03     INFO - queue closed
[task 2022-12-14T13:19:03.372Z] 13:19:03     INFO - Application command: Z:\task_167101713638646\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_167101713638646\AppData\Local\Temp\tmpc1s6wal7
[task 2022-12-14T13:19:03.384Z] 13:19:03     INFO - PID 7596 | 1671023867622	Marionette	INFO	Marionette enabled
[task 2022-12-14T13:19:03.384Z] 13:19:03     INFO - PID 7596 | 1671023868072	Marionette	INFO	Listening on port 49877
[task 2022-12-14T13:19:03.385Z] 13:19:03     INFO - PID 7596 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_167101713638646\\AppData\\Local\\Temp\\tmpl6ekk7gf\\search.json.mozlz4", (void 0)))
[task 2022-12-14T13:19:03.386Z] 13:19:03     INFO - PID 7596 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 230))
[task 2022-12-14T13:19:03.387Z] 13:19:03     INFO - PID 7596 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 230))
[task 2022-12-14T13:19:03.388Z] 13:19:03     INFO - PID 7596 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 230))
[task 2022-12-14T13:19:03.388Z] 13:19:03     INFO - PID 7596 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 230))
[task 2022-12-14T13:19:03.389Z] 13:19:03     INFO - Starting runner
[task 2022-12-14T13:19:04.219Z] 13:19:04     INFO - TEST-START | /css/CSS2/pagination/block-page-break-inside-avoid-7-print.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
You need to log in before you can comment on or make changes to this bug.