Closed Bug 1770280 Opened 2 years ago Closed 2 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /websockets/constructor/022.html?wpt_flags=h2 | WebSockets: protocol array - Test timed out

Categories

(Core :: Networking: WebSockets, defect, P5)

Product:
Core
Component:
Networking: WebSockets
Type:
defect

Tracking

()

Status:
RESOLVED DUPLICATE of bug 1766683
Tracking Flags:
Tracking Status
firefox-esr91 --- wontfix
firefox100 --- wontfix
firefox101 --- fixed
firefox102 --- fixed

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: intermittent-failure, regression)

Filed by: mlaza [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=378697110&repo=mozilla-esr91
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/NoqpkM-vSR-8nmvIDqyjjQ/runs/0/artifacts/public/logs/live_backing.log

[task 2022-05-20T00:13:58.989Z] 00:13:58     INFO - TEST-START | /workers/interfaces/WorkerGlobalScope/self.any.worker.html
[task 2022-05-20T00:13:58.989Z] 00:13:58     INFO - Closing window 9a927168-3c86-7145-9790-468d7f2d7711
[task 2022-05-20T00:13:59.081Z] 00:13:59     INFO - ....
[task 2022-05-20T00:13:59.081Z] 00:13:59     INFO - TEST-OK | /workers/interfaces/WorkerGlobalScope/self.any.worker.html | took 93ms
[task 2022-05-20T00:13:59.081Z] 00:13:59     INFO - Restarting browser for new test group
[task 2022-05-20T00:13:59.083Z] 00:13:59     INFO - PID 2275 | 1653005639081	Marionette	INFO	Stopped listening on port 51348
[task 2022-05-20T00:13:59.125Z] 00:13:59     INFO - PID 2275 | JavaScript error: resource:///modules/Interactions.jsm, line 209: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIUserIdleService.removeIdleObserver]
[task 2022-05-20T00:13:59.414Z] 00:13:59     INFO - PID 2275 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2022-05-20T00:13:59.616Z] 00:13:59     INFO - Browser exited with return code 0
[task 2022-05-20T00:13:59.619Z] 00:13:59     INFO - Closing logging queue
[task 2022-05-20T00:13:59.619Z] 00:13:59     INFO - queue closed
[task 2022-05-20T00:13:59.654Z] 00:13:59     INFO - Application command: /opt/worker/tasks/task_165300492446229/build/application/Firefox.app/Contents/MacOS/firefox --marionette about:blank -foreground -profile /var/folders/40/r3s4v7650l5_ctg0b7p2x2_r000014/T/tmpjif79kmc
[task 2022-05-20T00:13:59.663Z] 00:13:59     INFO - Starting runner
[task 2022-05-20T00:14:00.004Z] 00:14:00     INFO - PID 2292 | 1653005640003	Marionette	INFO	Marionette enabled
[task 2022-05-20T00:14:00.146Z] 00:14:00     INFO - 
[task 2022-05-20T00:14:00.146Z] 00:14:00     INFO - TEST-UNEXPECTED-TIMEOUT | /websockets/constructor/022.html?wpt_flags=h2 | WebSockets: protocol array - Test timed out
[task 2022-05-20T00:14:00.147Z] 00:14:00     INFO - TEST-UNEXPECTED-TIMEOUT | /websockets/constructor/022.html?wpt_flags=h2 | expected OK
[task 2022-05-20T00:14:00.147Z] 00:14:00     INFO - TEST-INFO took 10298ms
[task 2022-05-20T00:14:00.148Z] 00:14:00     INFO - PID 2207 | 1653005640147	Marionette	INFO	Stopped listening on port 50248
[task 2022-05-20T00:14:00.191Z] 00:14:00     INFO - PID 2207 | JavaScript error: resource:///modules/Interactions.jsm, line 209: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIUserIdleService.removeIdleObserver]
[task 2022-05-20T00:14:00.463Z] 00:14:00     INFO - PID 2207 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2022-05-20T00:14:00.650Z] 00:14:00     INFO - Browser exited with return code 0
[task 2022-05-20T00:14:00.653Z] 00:14:00     INFO - Closing logging queue
[task 2022-05-20T00:14:00.653Z] 00:14:00     INFO - queue closed
[task 2022-05-20T00:14:00.689Z] 00:14:00     INFO - Application command: /opt/worker/tasks/task_165300492446229/build/application/Firefox.app/Contents/MacOS/firefox --marionette about:blank -foreground -profile /var/folders/40/r3s4v7650l5_ctg0b7p2x2_r000014/T/tmprqcuw9ny
[task 2022-05-20T00:14:00.699Z] 00:14:00     INFO - Starting runner
[task 2022-05-20T00:14:00.976Z] 00:14:00     INFO - PID 2292 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /var/folders/40/r3s4v7650l5_ctg0b7p2x2_r000014/T/tmpjif79kmc/search.json.mozlz4", (void 0)))
[task 2022-05-20T00:14:01.084Z] 00:14:01     INFO - PID 2292 | 2022-05-20 00:14:01.083 firefox[2292:76510] Persistent UI failed to open file file:///Users/cltbld/Library/Saved%20Application%20State/org.mozilla.firefox.savedState/window_1.data: No such file or directory (2)
[task 2022-05-20T00:14:01.185Z] 00:14:01     INFO - PID 2304 | 1653005641183	Marionette	INFO	Marionette enabled
[task 2022-05-20T00:14:01.668Z] 00:14:01     INFO - PID 2292 | 1653005641667	Marionette	INFO	Listening on port 51447
[task 2022-05-20T00:14:01.863Z] 00:14:01     INFO - TEST-START | /workers/semantics/structured-clone/dedicated.html

Most likely regressed by bug 1727995 which landed a couple pushes before this bug was filed.

Regressed by: 1727995

Set release status flags based on info from the regressing bug 1727995

status-firefox100: --- → affected
status-firefox101: --- → affected
status-firefox102: --- → affected
status-firefox-esr91: --- → affected

:kershaw, since you are the author of the regressor, bug 1727995, could you take a look?
For more information, please visit auto_nag documentation.

Flags: needinfo?(kershaw)
Has Regression Range: --- → yes
Status: NEW → RESOLVED
Closed: 2 years ago
Flags: needinfo?(kershaw)
Resolution: --- → DUPLICATE
You need to log in before you can comment on or make changes to this bug.