Closed
Bug 1805431
Opened 3 years ago
Closed 1 year ago
Intermittent /wasm/webapi/instantiateStreaming.any.serviceworker.html | single tracking bug
Categories
(Core :: JavaScript: WebAssembly, defect, P5)
Core
JavaScript: WebAssembly
Tracking
()
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=399477889&repo=try
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/T0Z7ovRLSgSl86UN0N0Ciw/runs/0/artifacts/public/logs/live_backing.log
[task 2022-12-13T13:54:00.740Z] 13:54:00 INFO - TEST-OK | /wasm/webapi/instantiateStreaming.any.sharedworker.html | took 143ms
[task 2022-12-13T13:54:00.741Z] 13:54:00 INFO - TEST-START | /wasm/webapi/instantiateStreaming.any.serviceworker.html
[task 2022-12-13T13:54:00.742Z] 13:54:00 INFO - Closing window 5a0984ed-107a-4e08-8773-4e74bd762d79
[task 2022-12-13T13:54:10.867Z] 13:54:10 INFO - TEST-UNEXPECTED-TIMEOUT | /wasm/webapi/instantiateStreaming.any.serviceworker.html | expected OK
[task 2022-12-13T13:54:10.867Z] 13:54:10 INFO - TEST-INFO took 10132ms
[task 2022-12-13T13:54:10.872Z] 13:54:10 INFO - PID 1572 | 1670939650871 Marionette INFO Stopped listening on port 61916
[task 2022-12-13T13:54:16.892Z] 13:54:16 INFO - PID 1572 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-12-13T13:54:16.898Z] 13:54:16 INFO - PID 1572 | Blocker: Waiting for ping task
[task 2022-12-13T13:54:16.899Z] 13:54:16 INFO - PID 1572 | Phase: TelemetryController: Waiting for pending ping activity
[task 2022-12-13T13:54:16.900Z] 13:54:16 INFO - PID 1572 | State: (none)
[task 2022-12-13T13:54:16.900Z] 13:54:16 INFO - PID 1572 | 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-13T13:54:16.901Z] 13:54:16 INFO - PID 1572 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.jsm:718:15
[task 2022-12-13T13:54:16.901Z] 13:54:16 INFO - PID 1572 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:523:26
[task 2022-12-13T13:54:16.902Z] 13:54:16 INFO - PID 1572 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:462:15
[task 2022-12-13T13:54:16.903Z] 13:54:16 INFO - PID 1572 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2022-12-13T13:54:16.904Z] 13:54:16 INFO - PID 1572 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2022-12-13T13:54:16.904Z] 13:54:16 INFO - PID 1572 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-12-13T13:54:16.905Z] 13:54:16 INFO - PID 1572 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-12-13T13:54:16.905Z] 13:54:16 INFO - PID 1572 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:103:5
[task 2022-12-13T13:54:16.906Z] 13:54:16 INFO - PID 1572 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:63:33
[task 2022-12-13T13:54:16.906Z] 13:54:16 INFO - PID 1572 | _submitPingLogic@resource://gre/modules/TelemetryControllerParent.jsm:540:48
[task 2022-12-13T13:54:16.907Z] 13:54:16 INFO - PID 1572 | send@resource://gre/modules/TelemetryControllerParent.jsm:628:24
[task 2022-12-13T13:54:16.907Z] 13:54:16 INFO - PID 1572 | submitExternalPing@resource://gre/modules/TelemetryControllerParent.jsm:182:17
[task 2022-12-13T13:54:16.908Z] 13:54:16 INFO - PID 1572 | sendPing@resource://gre/modules/EventPing.jsm:51:30
[task 2022-12-13T13:54:16.909Z] 13:54:16 INFO - PID 1572 | _submitPing@resource://gre/modules/EventPing.jsm:229:12
[task 2022-12-13T13:54:16.910Z] 13:54:16 INFO - PID 1572 | shutdown@resource://gre/modules/EventPing.jsm:112:10
[task 2022-12-13T13:54:16.910Z] 13:54:16 INFO - PID 1572 | _cleanupOnShutdown@resource://gre/modules/TelemetryControllerParent.jsm:940:31
[task 2022-12-13T13:54:16.911Z] 13:54:16 INFO - PID 1572 | shutdown/<@resource://gre/modules/TelemetryControllerParent.jsm:1010:24
[task 2022-12-13T13:54:16.911Z] 13:54:16 INFO - PID 1572 | observe@resource://gre/modules/AsyncShutdown.jsm:572:16
[task 2022-12-13T13:54:16.912Z] 13:54:16 INFO - PID 1572 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-12-13T13:54:16.913Z] 13:54:16 INFO - PID 1572 | Blocker: Waiting for ping task
[task 2022-12-13T13:54:16.913Z] 13:54:16 INFO - PID 1572 | Phase: TelemetryController: Waiting for pending ping activity
[task 2022-12-13T13:54:16.914Z] 13:54:16 INFO - PID 1572 | State: (none)
[task 2022-12-13T13:54:16.914Z] 13:54:16 INFO - PID 1572 | 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-13T13:54:16.915Z] 13:54:16 INFO - PID 1572 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.jsm:718:15
[task 2022-12-13T13:54:16.916Z] 13:54:16 INFO - PID 1572 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:523:26
[task 2022-12-13T13:54:16.917Z] 13:54:16 INFO - PID 1572 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:462:15
[task 2022-12-13T13:54:16.917Z] 13:54:16 INFO - PID 1572 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2022-12-13T13:54:16.918Z] 13:54:16 INFO - PID 1572 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2022-12-13T13:54:16.919Z] 13:54:16 INFO - PID 1572 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-12-13T13:54:16.919Z] 13:54:16 INFO - PID 1572 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-12-13T13:54:16.920Z] 13:54:16 INFO - PID 1572 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:103:5
[task 2022-12-13T13:54:16.920Z] 13:54:16 INFO - PID 1572 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:63:33
[task 2022-12-13T13:54:16.921Z] 13:54:16 INFO - PID 1572 | _submitPingLogic@resource://gre/modules/TelemetryControllerParent.jsm:540:48
[task 2022-12-13T13:54:16.921Z] 13:54:16 INFO - PID 1572 | send@resource://gre/modules/TelemetryControllerParent.jsm:628:24
[task 2022-12-13T13:54:16.922Z] 13:54:16 INFO - PID 1572 | submitExternalPing@resource://gre/modules/TelemetryControllerParent.jsm:182:17
[task 2022-12-13T13:54:16.922Z] 13:54:16 INFO - PID 1572 | saveShutdownPings@resource://gre/modules/TelemetrySession.jsm:982:34
[task 2022-12-13T13:54:16.924Z] 13:54:16 INFO - PID 1572 | shutdownChromeProcess/cleanup/<@resource://gre/modules/TelemetrySession.jsm:1232:20
[task 2022-12-13T13:54:16.924Z] 13:54:16 INFO - PID 1572 | cleanup@resource://gre/modules/TelemetrySession.jsm:1239:9
[task 2022-12-13T13:54:16.925Z] 13:54:16 INFO - PID 1572 | shutdownChromeProcess@resource://gre/modules/TelemetrySession.jsm:1256:14
[task 2022-12-13T13:54:16.925Z] 13:54:16 INFO - PID 1572 | shutdown@resource://gre/modules/TelemetrySession.jsm:247:17
[task 2022-12-13T13:54:16.926Z] 13:54:16 INFO - PID 1572 | _cleanupOnShutdown@resource://gre/modules/TelemetryControllerParent.jsm:959:35
[task 2022-12-13T13:54:16.926Z] 13:54:16 INFO - PID 1572 | observe@resource://gre/modules/AsyncShutdown.jsm:572:16
[task 2022-12-13T13:54:16.927Z] 13:54:16 INFO - PID 1572 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-12-13T13:54:16.927Z] 13:54:16 INFO - PID 1572 | Blocker: TelemetryController: shutting down
[task 2022-12-13T13:54:16.927Z] 13:54:16 INFO - PID 1572 | Phase: profile-before-change-telemetry
[task 2022-12-13T13:54:16.928Z] 13:54:16 INFO - PID 1572 | 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-13T13:54:16.929Z] 13:54:16 INFO - PID 1572 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:523:26
[task 2022-12-13T13:54:16.929Z] 13:54:16 INFO - PID 1572 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:462:15
[task 2022-12-13T13:54:16.929Z] 13:54:16 INFO - PID 1572 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2022-12-13T13:54:16.930Z] 13:54:16 INFO - PID 1572 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2022-12-13T13:54:16.930Z] 13:54:16 INFO - PID 1572 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-12-13T13:54:16.930Z] 13:54:16 INFO - PID 1572 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-12-13T13:54:16.932Z] 13:54:16 INFO - PID 1572 | getShutdownState@resource://gre/modules/TelemetrySend.jsm:1692:7
[task 2022-12-13T13:54:16.932Z] 13:54:16 INFO - PID 1572 | getShutdownState@resource://gre/modules/TelemetrySend.jsm:374:30
[task 2022-12-13T13:54:16.932Z] 13:54:16 INFO - PID 1572 | _getState@resource://gre/modules/TelemetryControllerParent.jsm:1058:38
[task 2022-12-13T13:54:16.932Z] 13:54:16 INFO - PID 1572 | setupTelemetry/<@resource://gre/modules/TelemetryControllerParent.jsm:917:18
[task 2022-12-13T13:54:16.934Z] 13:54:16 INFO - PID 1572 | safeGetState@resource://gre/modules/AsyncShutdown.jsm:254:17
[task 2022-12-13T13:54:16.934Z] 13:54:16 INFO - PID 1572 | Barrier/_internalAddBlocker/promise<@resource://gre/modules/AsyncShutdown.jsm:773:20
[task 2022-12-13T13:54:16.935Z] 13:54:16 INFO - PID 1572 | observe@resource://gre/modules/AsyncShutdown.jsm:572:16
[task 2022-12-13T13:54:16.935Z] 13:54:16 INFO - PID 1572 | 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-13T13:54:16.936Z] 13:54:16 INFO - PID 1572 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.jsm:718:15
[task 2022-12-13T13:54:16.936Z] 13:54:16 INFO - PID 1572 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:523:26
[task 2022-12-13T13:54:16.937Z] 13:54:16 INFO - PID 1572 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:462:15
[task 2022-12-13T13:54:16.937Z] 13:54:16 INFO - PID 1572 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2022-12-13T13:54:16.938Z] 13:54:16 INFO - PID 1572 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2022-12-13T13:54:16.938Z] 13:54:16 INFO - PID 1572 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-12-13T13:54:16.939Z] 13:54:16 INFO - PID 1572 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-12-13T13:54:16.939Z] 13:54:16 INFO - PID 1572 | removeAbortedSessionPing@resource://gre/modules/TelemetryControllerParent.jsm:721:5
[task 2022-12-13T13:54:16.940Z] 13:54:16 INFO - PID 1572 | removeAbortedSessionPing@resource://gre/modules/TelemetryControllerParent.jsm:249:17
[task 2022-12-13T13:54:16.940Z] 13:54:16 INFO - PID 1572 | shutdownChromeProcess/cleanup/<@resource://gre/modules/TelemetrySession.jsm:1235:42
[task 2022-12-13T13:54:16.941Z] 13:54:16 INFO - PID 1572 | observe@resource://gre/modules/AsyncShutdown.jsm:572:16
[task 2022-12-13T13:54:17.075Z] 13:54:17 INFO - PID 1572 | 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-13T13:54:17.076Z] 13:54:17 INFO - PID 1572 | 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-13T13:54:17.077Z] 13:54:17 INFO - PID 1572 | 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-13T13:54:17.078Z] 13:54:17 INFO - PID 1572 | 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-13T13:54:17.078Z] 13:54:17 INFO - PID 1572 | 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-13T13:54:17.140Z] 13:54:17 INFO - Browser exited with return code 0
[task 2022-12-13T13:54:17.143Z] 13:54:17 INFO - Closing logging queue
[task 2022-12-13T13:54:17.144Z] 13:54:17 INFO - queue closed
[task 2022-12-13T13:54:17.255Z] 13:54:17 INFO - Application command: Z:\task_167093719924324\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_167093719924324\AppData\Local\Temp\tmp3v0eo2xl
[task 2022-12-13T13:54:17.271Z] 13:54:17 INFO - PID 5316 | 1670939632889 Marionette INFO Marionette enabled
[task 2022-12-13T13:54:17.272Z] 13:54:17 INFO - PID 5316 | 1670939632892 Marionette INFO Listening on port 61940
[task 2022-12-13T13:54:17.273Z] 13:54:17 INFO - PID 5316 | JavaScript error: resource://gre/modules/XULStore.jsm, line 58: Error: Can't find profile directory.
[task 2022-12-13T13:54:17.274Z] 13:54:17 INFO - PID 5316 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_167093719924324\\AppData\\Local\\Temp\\tmp2wvxybv7\\search.json.mozlz4", (void 0)))
[task 2022-12-13T13:54:17.275Z] 13:54:17 INFO - PID 5316 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 230))
[task 2022-12-13T13:54:17.275Z] 13:54:17 INFO - PID 5316 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 230))
[task 2022-12-13T13:54:17.276Z] 13:54:17 INFO - PID 5316 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 230))
[task 2022-12-13T13:54:17.276Z] 13:54:17 INFO - PID 5316 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 230))
[task 2022-12-13T13:54:17.277Z] 13:54:17 INFO - Starting runner
[task 2022-12-13T13:54:18.181Z] 13:54:18 INFO - TEST-START | /wasm/webapi/invalid-args.any.sharedworker.html
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•2 years ago
|
||
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
|
Reporter | |
Comment 3•2 years ago
|
||
| treeherder | ||
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=424608568&repo=mozilla-central
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Comment 5•2 years ago
|
||
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
|
|
Reporter | |
Comment 6•1 year ago
|
||
| treeherder | ||
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=451422668&repo=mozilla-central
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Comment 8•1 year ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
Status: REOPENED → RESOLVED
Closed: 2 years ago → 1 year ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•