Open
Bug 1825353
Opened 1 year ago
Updated 7 months ago
Intermittent /xhr/abort-after-timeout.any.worker.html | single tracking bug
Categories
(Core :: DOM: Networking, defect, P2)
Core
DOM: Networking
Tracking
()
NEW
People
(Reporter: intermittent-bug-filer, Unassigned)
References
(Blocks 2 open bugs)
Details
(Keywords: intermittent-failure, intermittent-testcase, Whiteboard: [necko-triaged])
Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=410667300&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/WkDzeTc1QVOf7tiw6tAItw/runs/0/artifacts/public/logs/live_backing.log
[task 2023-03-29T14:56:08.871Z] 14:56:08 INFO - TEST-START | /xhr/abort-after-timeout.any.worker.html
[task 2023-03-29T14:56:08.883Z] 14:56:08 INFO - Closing window 7f468439-9351-41b1-91e9-69297ef27fce
[task 2023-03-29T14:56:10.345Z] 14:56:10 INFO -
[task 2023-03-29T14:56:10.345Z] 14:56:10 INFO - TEST-UNEXPECTED-FAIL | /xhr/abort-after-timeout.any.worker.html | XMLHttpRequest: abort() after a timeout should not fire "abort" event - assert_true: expected true got false
[task 2023-03-29T14:56:10.345Z] 14:56:10 INFO - @http://web-platform.test:8000/xhr/abort-after-timeout.any.js:19:24
[task 2023-03-29T14:56:10.345Z] 14:56:10 INFO - Test.prototype.step_timeout/<@http://web-platform.test:8000/resources/testharness.js:2715:25
[task 2023-03-29T14:56:10.345Z] 14:56:10 INFO - Test.prototype.step@http://web-platform.test:8000/resources/testharness.js:2595:25
[task 2023-03-29T14:56:10.345Z] 14:56:10 INFO - Test.prototype.step_func/<@http://web-platform.test:8000/resources/testharness.js:2642:35
[task 2023-03-29T14:56:10.346Z] 14:56:10 INFO - TEST-OK | /xhr/abort-after-timeout.any.worker.html | took 1475ms
[task 2023-03-29T14:56:10.350Z] 14:56:10 INFO - PID 15020 | 1680101770349 Marionette INFO Stopped listening on port 51178
[task 2023-03-29T14:56:10.512Z] 14:56:10 INFO - PID 15020 | [Child 15133, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-03-29T14:56:10.517Z] 14:56:10 INFO - PID 15020 | [Child 15133, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-03-29T14:56:10.539Z] 14:56:10 INFO - PID 15020 | [Child 15133, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-03-29T14:56:10.545Z] 14:56:10 INFO - PID 15020 | [Child 15133, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-03-29T14:56:10.555Z] 14:56:10 INFO - PID 15020 | [Child 15133, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-03-29T14:56:10.558Z] 14:56:10 INFO - PID 15020 | [Child 15133, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-03-29T14:56:10.572Z] 14:56:10 INFO - PID 15020 | [Child 15133, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-03-29T14:56:10.902Z] 14:56:10 INFO - PID 15020 | ALSA lib seq_hw.c:466:(snd_seq_hw_open) open /dev/snd/seq failed: No such file or directory
[task 2023-03-29T14:56:12.171Z] 14:56:12 INFO - PID 15020 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-03-29T14:56:12.171Z] 14:56:12 INFO - PID 15020 | Blocker: Waiting for ping task
[task 2023-03-29T14:56:12.171Z] 14:56:12 INFO - PID 15020 | Phase: TelemetryController: Waiting for pending ping activity
[task 2023-03-29T14:56:12.172Z] 14:56:12 INFO - PID 15020 | State: (none)
[task 2023-03-29T14:56:12.172Z] 14:56:12 INFO - PID 15020 | 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-29T14:56:12.172Z] 14:56:12 INFO - PID 15020 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-03-29T14:56:12.173Z] 14:56:12 INFO - PID 15020 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-03-29T14:56:12.173Z] 14:56:12 INFO - PID 15020 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-03-29T14:56:12.173Z] 14:56:12 INFO - PID 15020 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-03-29T14:56:12.174Z] 14:56:12 INFO - PID 15020 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-03-29T14:56:12.174Z] 14:56:12 INFO - PID 15020 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-03-29T14:56:12.174Z] 14:56:12 INFO - PID 15020 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-03-29T14:56:12.174Z] 14:56:12 INFO - PID 15020 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-03-29T14:56:12.175Z] 14:56:12 INFO - PID 15020 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-03-29T14:56:12.175Z] 14:56:12 INFO - PID 15020 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-03-29T14:56:12.175Z] 14:56:12 INFO - PID 15020 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:824:36
[task 2023-03-29T14:56:12.175Z] 14:56:12 INFO - PID 15020 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-03-29T14:56:12.195Z] 14:56:12 INFO - PID 15020 | console.error: "TelemetryScheduler.shutdown - Already shut down"
[task 2023-03-29T14:56:12.343Z] 14:56:12 INFO - PID 15020 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-03-29T14:56:12.343Z] 14:56:12 INFO - PID 15020 | Blocker: Waiting for ping task
[task 2023-03-29T14:56:12.343Z] 14:56:12 INFO - PID 15020 | Phase: TelemetryController: Waiting for pending ping activity
[task 2023-03-29T14:56:12.343Z] 14:56:12 INFO - PID 15020 | State: (none)
[task 2023-03-29T14:56:12.345Z] 14:56:12 INFO - PID 15020 | 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-29T14:56:12.349Z] 14:56:12 INFO - PID 15020 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-03-29T14:56:12.350Z] 14:56:12 INFO - PID 15020 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-03-29T14:56:12.353Z] 14:56:12 INFO - PID 15020 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-03-29T14:56:12.353Z] 14:56:12 INFO - PID 15020 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-03-29T14:56:12.353Z] 14:56:12 INFO - PID 15020 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-03-29T14:56:12.353Z] 14:56:12 INFO - PID 15020 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-03-29T14:56:12.353Z] 14:56:12 INFO - PID 15020 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-03-29T14:56:12.353Z] 14:56:12 INFO - PID 15020 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-03-29T14:56:12.354Z] 14:56:12 INFO - PID 15020 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-03-29T14:56:12.355Z] 14:56:12 INFO - PID 15020 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-03-29T14:56:12.356Z] 14:56:12 INFO - PID 15020 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:824:36
[task 2023-03-29T14:56:12.357Z] 14:56:12 INFO - PID 15020 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-03-29T14:56:12.368Z] 14:56:12 INFO - PID 15020 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-03-29T14:56:12.368Z] 14:56:12 INFO - PID 15020 | Blocker: TelemetryController: shutting down
[task 2023-03-29T14:56:12.368Z] 14:56:12 INFO - PID 15020 | Phase: profile-before-change-telemetry
[task 2023-03-29T14:56:12.374Z] 14:56:12 INFO - PID 15020 | 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-29T14:56:12.374Z] 14:56:12 INFO - PID 15020 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-03-29T14:56:12.374Z] 14:56:12 INFO - PID 15020 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-03-29T14:56:12.374Z] 14:56:12 INFO - PID 15020 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-03-29T14:56:12.374Z] 14:56:12 INFO - PID 15020 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-03-29T14:56:12.374Z] 14:56:12 INFO - PID 15020 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-03-29T14:56:12.374Z] 14:56:12 INFO - PID 15020 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-03-29T14:56:12.374Z] 14:56:12 INFO - PID 15020 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-03-29T14:56:12.375Z] 14:56:12 INFO - PID 15020 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-03-29T14:56:12.375Z] 14:56:12 INFO - PID 15020 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-03-29T14:56:12.376Z] 14:56:12 INFO - PID 15020 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:824:36
[task 2023-03-29T14:56:12.376Z] 14:56:12 INFO - PID 15020 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-03-29T14:56:12.378Z] 14:56:12 INFO - PID 15020 | 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-29T14:56:12.380Z] 14:56:12 INFO - PID 15020 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-03-29T14:56:12.381Z] 14:56:12 INFO - PID 15020 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-03-29T14:56:12.382Z] 14:56:12 INFO - PID 15020 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-03-29T14:56:12.383Z] 14:56:12 INFO - PID 15020 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-03-29T14:56:12.384Z] 14:56:12 INFO - PID 15020 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-03-29T14:56:12.385Z] 14:56:12 INFO - PID 15020 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-03-29T14:56:12.386Z] 14:56:12 INFO - PID 15020 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-03-29T14:56:12.386Z] 14:56:12 INFO - PID 15020 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-03-29T14:56:12.387Z] 14:56:12 INFO - PID 15020 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-03-29T14:56:12.388Z] 14:56:12 INFO - PID 15020 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-03-29T14:56:12.389Z] 14:56:12 INFO - PID 15020 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:824:36
[task 2023-03-29T14:56:12.395Z] 14:56:12 INFO - PID 15020 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-03-29T14:56:12.411Z] 14:56:12 INFO - PID 15020 | 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-29T14:56:12.413Z] 14:56:12 INFO - PID 15020 | 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-29T14:56:12.415Z] 14:56:12 INFO - PID 15020 | 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-29T14:56:12.417Z] 14:56:12 INFO - PID 15020 | 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-29T14:56:12.419Z] 14:56:12 INFO - PID 15020 | 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-29T14:56:17.256Z] 14:56:17 INFO - Browser exited with return code 0
[task 2023-03-29T14:56:17.261Z] 14:56:17 INFO - Closing logging queue
[task 2023-03-29T14:56:17.261Z] 14:56:17 INFO - queue closed
[task 2023-03-29T14:56:17.271Z] 14:56:17 INFO - INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2023-03-29T14:56:17.271Z] 14:56:17 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmpwmjwny7r
[task 2023-03-29T14:56:17.313Z] 14:56:17 INFO - PID 15582 | ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2023-03-29T14:56:17.313Z] 14:56:17 INFO - PID 15582 | ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2023-03-29T14:56:17.313Z] 14:56:17 INFO - PID 15582 | 1680101759468 Marionette INFO Marionette enabled
[task 2023-03-29T14:56:17.313Z] 14:56:17 INFO - PID 15582 | 1680101759486 Marionette INFO Listening on port 49701
[task 2023-03-29T14:56:17.314Z] 14:56:17 INFO - PID 15582 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-03-29T14:56:17.314Z] 14:56:17 INFO - PID 15582 | console.error: ({})
[task 2023-03-29T14:56:17.314Z] 14:56:17 INFO - PID 15582 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/tmp8py2s889/search.json.mozlz4", (void 0)))
[task 2023-03-29T14:56:17.314Z] 14:56:17 INFO - PID 15582 | [Child 15718, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-03-29T14:56:17.314Z] 14:56:17 INFO - PID 15582 | [Child 15718, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-03-29T14:56:17.314Z] 14:56:17 INFO - PID 15582 | [ERROR glean_core] Error setting metrics feature config: Json(Error("EOF while parsing a value", line: 1, column: 0))
[task 2023-03-29T14:56:17.314Z] 14:56:17 INFO - PID 15582 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-03-29T14:56:17.314Z] 14:56:17 INFO - PID 15582 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-03-29T14:56:17.314Z] 14:56:17 INFO - PID 15582 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-03-29T14:56:17.314Z] 14:56:17 INFO - Starting runner
[task 2023-03-29T14:56:19.400Z] 14:56:19 INFO - TEST-START | /xhr/abort-after-timeout.any.html
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 6•1 year ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
Status: NEW → RESOLVED
Closed: 1 year ago
Resolution: --- → INCOMPLETE
|
Reporter | |
Comment 7•1 year ago
|
||
| treeherder | ||
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=419947020&repo=mozilla-central
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Comment 9•1 year ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
Status: REOPENED → RESOLVED
Closed: 1 year ago → 1 year ago
Resolution: --- → INCOMPLETE
|
|
Reporter | |
Comment 10•1 year ago
|
||
| treeherder | ||
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=423405183&repo=autoland
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Comment 13•10 months ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
Status: REOPENED → RESOLVED
Closed: 1 year ago → 10 months ago
Resolution: --- → INCOMPLETE
|
|
Reporter | |
Comment 14•8 months ago
|
||
| treeherder | ||
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=433504885&repo=mozilla-central
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
This is a bug. The abort method should check if the method is closed first
link
You need to log in
before you can comment on or make changes to this bug.
Description
•