Closed
Bug 1806061
Opened 3 years ago
Closed 3 years ago
Intermittent /beacon/beacon-redirect.https.window.html | single tracking bug
Categories
(Core :: DOM: Networking, defect, P5)
Core
DOM: Networking
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=399856685&repo=mozilla-beta
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/CaZ1jyRbRzOeUpOPZrC2Lw/runs/0/artifacts/public/logs/live_backing.log
[task 2022-12-16T05:13:45.983Z] 05:13:45 INFO - TEST-START | /beacon/beacon-redirect.https.window.html
[task 2022-12-16T05:13:45.984Z] 05:13:45 INFO - Closing window e977d735-4b5a-4ba9-aed5-b049239e5ba8
[task 2022-12-16T05:13:49.654Z] 05:13:49 INFO -
[task 2022-12-16T05:13:49.654Z] 05:13:49 INFO - TEST-UNEXPECTED-FAIL | /beacon/beacon-redirect.https.window.html | cross-origin, CORS-safelisted: status = 307, type = string - assert_true: timeout expected true got false
[task 2022-12-16T05:13:49.654Z] 05:13:49 INFO - waitForResult@https://web-platform.test:8443/beacon/beacon-common.sub.js:110:14
[task 2022-12-16T05:13:49.655Z] 05:13:49 INFO -
[task 2022-12-16T05:13:49.655Z] 05:13:49 INFO - TEST-UNEXPECTED-FAIL | /beacon/beacon-redirect.https.window.html | cross-origin, CORS-safelisted: status = 307, type = arraybuffer - assert_true: timeout expected true got false
[task 2022-12-16T05:13:49.655Z] 05:13:49 INFO - waitForResult@https://web-platform.test:8443/beacon/beacon-common.sub.js:110:14
[task 2022-12-16T05:13:49.656Z] 05:13:49 INFO -
[task 2022-12-16T05:13:49.657Z] 05:13:49 INFO - TEST-UNEXPECTED-FAIL | /beacon/beacon-redirect.https.window.html | cross-origin, CORS-safelisted: status = 307, type = form - assert_true: timeout expected true got false
[task 2022-12-16T05:13:49.657Z] 05:13:49 INFO - waitForResult@https://web-platform.test:8443/beacon/beacon-common.sub.js:110:14
[task 2022-12-16T05:13:49.659Z] 05:13:49 INFO - .....
[task 2022-12-16T05:13:49.659Z] 05:13:49 INFO - TEST-OK | /beacon/beacon-redirect.https.window.html | took 3673ms
[task 2022-12-16T05:13:49.662Z] 05:13:49 INFO - PID 5260 | 1671167629654 Marionette INFO Stopped listening on port 49888
[task 2022-12-16T05:13:50.787Z] 05:13:50 INFO - PID 5260 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-12-16T05:13:50.789Z] 05:13:50 INFO - PID 5260 | Blocker: Waiting for ping task
[task 2022-12-16T05:13:50.790Z] 05:13:50 INFO - PID 5260 | Phase: TelemetryController: Waiting for pending ping activity
[task 2022-12-16T05:13:50.790Z] 05:13:50 INFO - PID 5260 | State: (none)
[task 2022-12-16T05:13:50.791Z] 05:13:50 INFO - PID 5260 | 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-16T05:13:50.792Z] 05:13:50 INFO - PID 5260 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.jsm:718:15
[task 2022-12-16T05:13:50.792Z] 05:13:50 INFO - PID 5260 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:523:26
[task 2022-12-16T05:13:50.793Z] 05:13:50 INFO - PID 5260 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:462:15
[task 2022-12-16T05:13:50.793Z] 05:13:50 INFO - PID 5260 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2022-12-16T05:13:50.794Z] 05:13:50 INFO - PID 5260 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2022-12-16T05:13:50.794Z] 05:13:50 INFO - PID 5260 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-12-16T05:13:50.795Z] 05:13:50 INFO - PID 5260 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-12-16T05:13:50.795Z] 05:13:50 INFO - PID 5260 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:103:5
[task 2022-12-16T05:13:50.796Z] 05:13:50 INFO - PID 5260 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:63:33
[task 2022-12-16T05:13:50.797Z] 05:13:50 INFO - PID 5260 | _submitPingLogic@resource://gre/modules/TelemetryControllerParent.jsm:540:48
[task 2022-12-16T05:13:50.797Z] 05:13:50 INFO - PID 5260 | send@resource://gre/modules/TelemetryControllerParent.jsm:628:24
[task 2022-12-16T05:13:50.798Z] 05:13:50 INFO - PID 5260 | submitExternalPing@resource://gre/modules/TelemetryControllerParent.jsm:182:17
[task 2022-12-16T05:13:50.798Z] 05:13:50 INFO - PID 5260 | sendPing@resource://gre/modules/EventPing.jsm:51:30
[task 2022-12-16T05:13:50.799Z] 05:13:50 INFO - PID 5260 | _submitPing@resource://gre/modules/EventPing.jsm:229:12
[task 2022-12-16T05:13:50.799Z] 05:13:50 INFO - PID 5260 | shutdown@resource://gre/modules/EventPing.jsm:112:10
[task 2022-12-16T05:13:50.800Z] 05:13:50 INFO - PID 5260 | _cleanupOnShutdown@resource://gre/modules/TelemetryControllerParent.jsm:940:31
[task 2022-12-16T05:13:50.800Z] 05:13:50 INFO - PID 5260 | shutdown/<@resource://gre/modules/TelemetryControllerParent.jsm:1010:24
[task 2022-12-16T05:13:50.801Z] 05:13:50 INFO - PID 5260 | observe@resource://gre/modules/AsyncShutdown.jsm:572:16
[task 2022-12-16T05:13:50.803Z] 05:13:50 INFO - PID 5260 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-12-16T05:13:50.803Z] 05:13:50 INFO - PID 5260 | Blocker: Waiting for ping task
[task 2022-12-16T05:13:50.804Z] 05:13:50 INFO - PID 5260 | Phase: TelemetryController: Waiting for pending ping activity
[task 2022-12-16T05:13:50.804Z] 05:13:50 INFO - PID 5260 | State: (none)
[task 2022-12-16T05:13:50.805Z] 05:13:50 INFO - PID 5260 | 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-16T05:13:50.805Z] 05:13:50 INFO - PID 5260 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.jsm:718:15
[task 2022-12-16T05:13:50.806Z] 05:13:50 INFO - PID 5260 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:523:26
[task 2022-12-16T05:13:50.806Z] 05:13:50 INFO - PID 5260 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:462:15
[task 2022-12-16T05:13:50.807Z] 05:13:50 INFO - PID 5260 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2022-12-16T05:13:50.807Z] 05:13:50 INFO - PID 5260 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2022-12-16T05:13:50.808Z] 05:13:50 INFO - PID 5260 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-12-16T05:13:50.809Z] 05:13:50 INFO - PID 5260 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-12-16T05:13:50.809Z] 05:13:50 INFO - PID 5260 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:103:5
[task 2022-12-16T05:13:50.810Z] 05:13:50 INFO - PID 5260 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:63:33
[task 2022-12-16T05:13:50.810Z] 05:13:50 INFO - PID 5260 | _submitPingLogic@resource://gre/modules/TelemetryControllerParent.jsm:540:48
[task 2022-12-16T05:13:50.811Z] 05:13:50 INFO - PID 5260 | send@resource://gre/modules/TelemetryControllerParent.jsm:628:24
[task 2022-12-16T05:13:50.811Z] 05:13:50 INFO - PID 5260 | submitExternalPing@resource://gre/modules/TelemetryControllerParent.jsm:182:17
[task 2022-12-16T05:13:50.812Z] 05:13:50 INFO - PID 5260 | saveShutdownPings@resource://gre/modules/TelemetrySession.jsm:982:34
[task 2022-12-16T05:13:50.812Z] 05:13:50 INFO - PID 5260 | shutdownChromeProcess/cleanup/<@resource://gre/modules/TelemetrySession.jsm:1232:20
[task 2022-12-16T05:13:50.813Z] 05:13:50 INFO - PID 5260 | cleanup@resource://gre/modules/TelemetrySession.jsm:1239:9
[task 2022-12-16T05:13:50.814Z] 05:13:50 INFO - PID 5260 | shutdownChromeProcess@resource://gre/modules/TelemetrySession.jsm:1256:14
[task 2022-12-16T05:13:50.814Z] 05:13:50 INFO - PID 5260 | shutdown@resource://gre/modules/TelemetrySession.jsm:247:17
[task 2022-12-16T05:13:50.815Z] 05:13:50 INFO - PID 5260 | _cleanupOnShutdown@resource://gre/modules/TelemetryControllerParent.jsm:959:35
[task 2022-12-16T05:13:50.815Z] 05:13:50 INFO - PID 5260 | observe@resource://gre/modules/AsyncShutdown.jsm:572:16
[task 2022-12-16T05:13:50.816Z] 05:13:50 INFO - PID 5260 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-12-16T05:13:50.816Z] 05:13:50 INFO - PID 5260 | Blocker: TelemetryController: shutting down
[task 2022-12-16T05:13:50.817Z] 05:13:50 INFO - PID 5260 | Phase: profile-before-change-telemetry
[task 2022-12-16T05:13:50.817Z] 05:13:50 INFO - PID 5260 | 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-16T05:13:50.818Z] 05:13:50 INFO - PID 5260 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:523:26
[task 2022-12-16T05:13:50.818Z] 05:13:50 INFO - PID 5260 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:462:15
[task 2022-12-16T05:13:50.818Z] 05:13:50 INFO - PID 5260 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2022-12-16T05:13:50.819Z] 05:13:50 INFO - PID 5260 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2022-12-16T05:13:50.820Z] 05:13:50 INFO - PID 5260 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-12-16T05:13:50.820Z] 05:13:50 INFO - PID 5260 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-12-16T05:13:50.821Z] 05:13:50 INFO - PID 5260 | getShutdownState@resource://gre/modules/TelemetrySend.jsm:1692:7
[task 2022-12-16T05:13:50.821Z] 05:13:50 INFO - PID 5260 | getShutdownState@resource://gre/modules/TelemetrySend.jsm:374:30
[task 2022-12-16T05:13:50.822Z] 05:13:50 INFO - PID 5260 | _getState@resource://gre/modules/TelemetryControllerParent.jsm:1058:38
[task 2022-12-16T05:13:50.822Z] 05:13:50 INFO - PID 5260 | setupTelemetry/<@resource://gre/modules/TelemetryControllerParent.jsm:917:18
[task 2022-12-16T05:13:50.823Z] 05:13:50 INFO - PID 5260 | safeGetState@resource://gre/modules/AsyncShutdown.jsm:254:17
[task 2022-12-16T05:13:50.823Z] 05:13:50 INFO - PID 5260 | Barrier/_internalAddBlocker/promise<@resource://gre/modules/AsyncShutdown.jsm:773:20
[task 2022-12-16T05:13:50.824Z] 05:13:50 INFO - PID 5260 | observe@resource://gre/modules/AsyncShutdown.jsm:572:16
[task 2022-12-16T05:13:50.824Z] 05:13:50 INFO - PID 5260 | 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-16T05:13:50.825Z] 05:13:50 INFO - PID 5260 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.jsm:718:15
[task 2022-12-16T05:13:50.825Z] 05:13:50 INFO - PID 5260 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:523:26
[task 2022-12-16T05:13:50.826Z] 05:13:50 INFO - PID 5260 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:462:15
[task 2022-12-16T05:13:50.826Z] 05:13:50 INFO - PID 5260 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2022-12-16T05:13:50.827Z] 05:13:50 INFO - PID 5260 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2022-12-16T05:13:50.827Z] 05:13:50 INFO - PID 5260 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-12-16T05:13:50.828Z] 05:13:50 INFO - PID 5260 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-12-16T05:13:50.828Z] 05:13:50 INFO - PID 5260 | removeAbortedSessionPing@resource://gre/modules/TelemetryControllerParent.jsm:721:5
[task 2022-12-16T05:13:50.828Z] 05:13:50 INFO - PID 5260 | removeAbortedSessionPing@resource://gre/modules/TelemetryControllerParent.jsm:249:17
[task 2022-12-16T05:13:50.829Z] 05:13:50 INFO - PID 5260 | shutdownChromeProcess/cleanup/<@resource://gre/modules/TelemetrySession.jsm:1235:42
[task 2022-12-16T05:13:50.830Z] 05:13:50 INFO - PID 5260 | observe@resource://gre/modules/AsyncShutdown.jsm:572:16
[task 2022-12-16T05:13:50.903Z] 05:13:50 INFO - PID 5260 | 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-16T05:13:50.908Z] 05:13:50 INFO - PID 5260 | 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-16T05:13:50.908Z] 05:13:50 INFO - PID 5260 | 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-16T05:13:50.909Z] 05:13:50 INFO - PID 5260 | 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-16T05:13:50.910Z] 05:13:50 INFO - PID 5260 | 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-16T05:13:51.004Z] 05:13:51 INFO - Browser exited with return code 0
[task 2022-12-16T05:13:51.007Z] 05:13:51 INFO - Closing logging queue
[task 2022-12-16T05:13:51.008Z] 05:13:51 INFO - queue closed
[task 2022-12-16T05:13:51.158Z] 05:13:51 INFO - Application command: Z:\task_167116550797897\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_167116550797897\AppData\Local\Temp\tmpuoccy5xi
[task 2022-12-16T05:13:51.171Z] 05:13:51 INFO - PID 2004 | 1671167621456 Marionette INFO Marionette enabled
[task 2022-12-16T05:13:51.171Z] 05:13:51 INFO - PID 2004 | 1671167621459 Marionette INFO Listening on port 49921
[task 2022-12-16T05:13:51.173Z] 05:13:51 INFO - PID 2004 | JavaScript error: resource://gre/modules/XULStore.jsm, line 58: Error: Can't find profile directory.
[task 2022-12-16T05:13:51.174Z] 05:13:51 INFO - PID 2004 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_167116550797897\\AppData\\Local\\Temp\\tmp6t9ysocd\\search.json.mozlz4", (void 0)))
[task 2022-12-16T05:13:51.174Z] 05:13:51 INFO - PID 2004 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 230))
[task 2022-12-16T05:13:51.175Z] 05:13:51 INFO - Starting runner
[task 2022-12-16T05:13:52.072Z] 05:13:52 INFO - TEST-START | /beacon/idlharness.any.html
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•3 years ago
|
||
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.
Description
•