Closed Bug 1820457 Opened 2 years ago Closed 2 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /encoding/legacy-mb-tchinese/big5/big5-encode-form-big5-hkscs.html?X-X | expected OK

Categories

(Core :: Internationalization, defect, P5)

Product:
Core
Component:
Internationalization
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: sstanca [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=407920571&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/HLfBqlUUQ_e0R4kRJYsCSg/runs/0/artifacts/public/logs/live_backing.log

[task 2023-03-06T06:38:07.794Z] 06:38:07     INFO - TEST-START | /encoding/legacy-mb-tchinese/big5/big5-encode-form-big5-hkscs.html?2001-3000
[task 2023-03-06T06:38:07.795Z] 06:38:07     INFO - Closing window fd036361-e43c-48c7-9ca6-875f93eb9017
[task 2023-03-06T06:38:08.074Z] 06:38:08     INFO - PID 6208 | JavaScript error: http://web-platform.test:8000/resources/testharness.js, line 3724: InternalError: too much recursion
[task 2023-03-06T06:39:12.971Z] 06:39:12     INFO - TEST-UNEXPECTED-TIMEOUT | /encoding/legacy-mb-tchinese/big5/big5-encode-form-big5-hkscs.html?2001-3000 | expected OK
[task 2023-03-06T06:39:12.971Z] 06:39:12     INFO - TEST-INFO took 65188ms
[task 2023-03-06T06:39:12.975Z] 06:39:12     INFO - PID 6208 | 1678084752974	Marionette	INFO	Stopped listening on port 51766
[task 2023-03-06T06:39:13.312Z] 06:39:13     INFO - PID 6208 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-03-06T06:39:13.313Z] 06:39:13     INFO - PID 6208 |           Blocker:  Waiting for ping task
[task 2023-03-06T06:39:13.314Z] 06:39:13     INFO - PID 6208 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2023-03-06T06:39:13.314Z] 06:39:13     INFO - PID 6208 |           State: (none)
[task 2023-03-06T06:39:13.316Z] 06:39:13     INFO - PID 6208 | 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 2023-03-06T06:39:13.316Z] 06:39:13     INFO - PID 6208 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-03-06T06:39:13.317Z] 06:39:13     INFO - PID 6208 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-03-06T06:39:13.318Z] 06:39:13     INFO - PID 6208 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-03-06T06:39:13.318Z] 06:39:13     INFO - PID 6208 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-03-06T06:39:13.319Z] 06:39:13     INFO - PID 6208 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-03-06T06:39:13.319Z] 06:39:13     INFO - PID 6208 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-03-06T06:39:13.320Z] 06:39:13     INFO - PID 6208 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-03-06T06:39:13.321Z] 06:39:13     INFO - PID 6208 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-03-06T06:39:13.321Z] 06:39:13     INFO - PID 6208 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-03-06T06:39:13.323Z] 06:39:13     INFO - PID 6208 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-03-06T06:39:13.323Z] 06:39:13     INFO - PID 6208 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:823:36
[task 2023-03-06T06:39:13.324Z] 06:39:13     INFO - PID 6208 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-03-06T06:39:13.325Z] 06:39:13     INFO - PID 6208 | console.error: "TelemetryScheduler.shutdown - Already shut down"
[task 2023-03-06T06:39:13.326Z] 06:39:13     INFO - PID 6208 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-03-06T06:39:13.326Z] 06:39:13     INFO - PID 6208 |           Blocker:  Waiting for ping task
[task 2023-03-06T06:39:13.327Z] 06:39:13     INFO - PID 6208 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2023-03-06T06:39:13.327Z] 06:39:13     INFO - PID 6208 |           State: (none)
[task 2023-03-06T06:39:13.328Z] 06:39:13     INFO - PID 6208 | 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 2023-03-06T06:39:13.329Z] 06:39:13     INFO - PID 6208 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-03-06T06:39:13.330Z] 06:39:13     INFO - PID 6208 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-03-06T06:39:13.330Z] 06:39:13     INFO - PID 6208 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-03-06T06:39:13.331Z] 06:39:13     INFO - PID 6208 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-03-06T06:39:13.332Z] 06:39:13     INFO - PID 6208 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-03-06T06:39:13.332Z] 06:39:13     INFO - PID 6208 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-03-06T06:39:13.333Z] 06:39:13     INFO - PID 6208 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-03-06T06:39:13.334Z] 06:39:13     INFO - PID 6208 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-03-06T06:39:13.334Z] 06:39:13     INFO - PID 6208 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-03-06T06:39:13.335Z] 06:39:13     INFO - PID 6208 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-03-06T06:39:13.335Z] 06:39:13     INFO - PID 6208 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:823:36
[task 2023-03-06T06:39:13.336Z] 06:39:13     INFO - PID 6208 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-03-06T06:39:13.337Z] 06:39:13     INFO - PID 6208 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-03-06T06:39:13.337Z] 06:39:13     INFO - PID 6208 |           Blocker:  TelemetryController: shutting down
[task 2023-03-06T06:39:13.338Z] 06:39:13     INFO - PID 6208 |           Phase: profile-before-change-telemetry
[task 2023-03-06T06:39:13.339Z] 06:39:13     INFO - PID 6208 |           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:727:15
[task 2023-03-06T06:39:13.339Z] 06:39:13     INFO - PID 6208 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-03-06T06:39:13.340Z] 06:39:13     INFO - PID 6208 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-03-06T06:39:13.340Z] 06:39:13     INFO - PID 6208 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-03-06T06:39:13.341Z] 06:39:13     INFO - PID 6208 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-03-06T06:39:13.341Z] 06:39:13     INFO - PID 6208 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-03-06T06:39:13.342Z] 06:39:13     INFO - PID 6208 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-03-06T06:39:13.342Z] 06:39:13     INFO - PID 6208 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-03-06T06:39:13.343Z] 06:39:13     INFO - PID 6208 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-03-06T06:39:13.343Z] 06:39:13     INFO - PID 6208 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-03-06T06:39:13.344Z] 06:39:13     INFO - PID 6208 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:823:36
[task 2023-03-06T06:39:13.344Z] 06:39:13     INFO - PID 6208 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-03-06T06:39:13.345Z] 06:39:13     INFO - PID 6208 | 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 2023-03-06T06:39:13.346Z] 06:39:13     INFO - PID 6208 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-03-06T06:39:13.346Z] 06:39:13     INFO - PID 6208 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-03-06T06:39:13.346Z] 06:39:13     INFO - PID 6208 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-03-06T06:39:13.347Z] 06:39:13     INFO - PID 6208 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-03-06T06:39:13.348Z] 06:39:13     INFO - PID 6208 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-03-06T06:39:13.348Z] 06:39:13     INFO - PID 6208 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-03-06T06:39:13.349Z] 06:39:13     INFO - PID 6208 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-03-06T06:39:13.350Z] 06:39:13     INFO - PID 6208 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-03-06T06:39:13.350Z] 06:39:13     INFO - PID 6208 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-03-06T06:39:13.351Z] 06:39:13     INFO - PID 6208 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-03-06T06:39:13.352Z] 06:39:13     INFO - PID 6208 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:823:36
[task 2023-03-06T06:39:13.352Z] 06:39:13     INFO - PID 6208 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-03-06T06:39:13.353Z] 06:39:13     INFO - PID 6208 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-03-06T06:39:13.354Z] 06:39:13     INFO - PID 6208 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-03-06T06:39:13.355Z] 06:39:13     INFO - PID 6208 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-03-06T06:39:13.355Z] 06:39:13     INFO - PID 6208 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-03-06T06:39:13.356Z] 06:39:13     INFO - PID 6208 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-03-06T06:39:13.420Z] 06:39:13     INFO - Browser exited with return code 0
[task 2023-03-06T06:39:13.425Z] 06:39:13     INFO - Closing logging queue
[task 2023-03-06T06:39:13.425Z] 06:39:13     INFO - queue closed
[task 2023-03-06T06:39:13.615Z] 06:39:13     INFO - Application command: Z:\task_167808288142657\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_167808288142657\AppData\Local\Temp\tmp54ri99vn
[task 2023-03-06T06:39:13.621Z] 06:39:13     INFO - PID 2388 | 1678084622659	Marionette	INFO	Marionette enabled
[task 2023-03-06T06:39:13.621Z] 06:39:13     INFO - PID 2388 | 1678084622662	Marionette	INFO	Listening on port 52423
[task 2023-03-06T06:39:13.622Z] 06:39:13     INFO - PID 2388 | JavaScript error: resource://gre/modules/XULStore.jsm, line 58: Error: Can't find profile directory.
[task 2023-03-06T06:39:13.623Z] 06:39:13     INFO - PID 2388 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-03-06T06:39:13.624Z] 06:39:13     INFO - PID 2388 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_167808288142657\\AppData\\Local\\Temp\\tmpxdjomkrx\\search.json.mozlz4", (void 0)))
[task 2023-03-06T06:39:13.625Z] 06:39:13     INFO - PID 2388 | [ERROR glean_core] Error setting metrics feature config: Json(Error("EOF while parsing a value", line: 1, column: 0))
[task 2023-03-06T06:39:13.626Z] 06:39:13     INFO - PID 2388 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-03-06T06:39:13.626Z] 06:39:13     INFO - PID 2388 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-03-06T06:39:13.627Z] 06:39:13     INFO - PID 2388 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-03-06T06:39:13.627Z] 06:39:13     INFO - Starting runner
[task 2023-03-06T06:39:14.737Z] 06:39:14     INFO - TEST-START | /encoding/legacy-mb-tchinese/big5/big5-encode-form-big5-hkscs.html?1-1000

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

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

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=428517474&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: 2 years ago → 2 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.