Closed Bug 1761189 Opened 3 years ago Closed 3 years ago

Intermittent [tier 2] TEST-UNEXPECTED-TIMEOUT | /content-security-policy/worker-src/service-list.https.sub.html | Same-origin service worker allowed by host-source expression. - Test timed out

Categories

(Core :: DOM: Security, defect, P5)

Product:
Core
Component:
DOM: Security
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, Whiteboard: [domsecurity-intermittent])

Filed by: nfay [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=372151472&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/QS4WWe08QaW80qE-1B0pRw/runs/0/artifacts/public/logs/live_backing.log

[task 2022-03-24T05:49:56.295Z] 05:49:56     INFO - TEST-START | /content-security-policy/worker-src/service-list.https.sub.html
[task 2022-03-24T05:49:56.302Z] 05:49:56     INFO - PID 4927 | ###!!! [Parent][PCompositorManagerParent] Error: RunMessage(msgname=PCompositorBridge::Msg___delete__) Channel closing: too late to send/recv, messages will be lost
[task 2022-03-24T05:49:56.303Z] 05:49:56     INFO - PID 4927 | ###!!! [Parent][PImageBridgeParent] Error: RunMessage(msgname=PImageBridge::Msg_WillClose) Channel closing: too late to send/recv, messages will be lost
[task 2022-03-24T05:49:56.310Z] 05:49:56     INFO - Closing window 772d9f9d-ca73-4bb2-b69a-01270198d2df
[task 2022-03-24T05:50:16.737Z] 05:50:16     INFO - 
[task 2022-03-24T05:50:16.737Z] 05:50:16     INFO - TEST-UNEXPECTED-TIMEOUT | /content-security-policy/worker-src/service-list.https.sub.html | Same-origin service worker allowed by host-source expression. - Test timed out
[task 2022-03-24T05:50:16.738Z] 05:50:16     INFO - TEST-UNEXPECTED-TIMEOUT | /content-security-policy/worker-src/service-list.https.sub.html | expected OK
[task 2022-03-24T05:50:16.738Z] 05:50:16     INFO - TEST-INFO took 20445ms
[task 2022-03-24T05:50:16.740Z] 05:50:16     INFO - PID 4927 | 1648101016739	Marionette	INFO	Stopped listening on port 58209
[task 2022-03-24T05:50:16.911Z] 05:50:16     INFO - PID 4927 | ###!!! [Parent][PCompositorManagerParent] Error: RunMessage(msgname=PCompositorBridge::Msg___delete__) Channel closing: too late to send/recv, messages will be lost
[task 2022-03-24T05:50:16.930Z] 05:50:16     INFO - PID 4927 | ###!!! [Parent][PCompositorManagerParent] Error: RunMessage(msgname=PCompositorBridge::Msg___delete__) Channel closing: too late to send/recv, messages will be lost
[task 2022-03-24T05:50:16.933Z] 05:50:16     INFO - PID 4927 | ###!!! [Parent][PBackgroundParent] Error: RunMessage(msgname=PRemoteWorkerService::Msg___delete__) Channel closing: too late to send/recv, messages will be lost
[task 2022-03-24T05:50:16.936Z] 05:50:16     INFO - PID 4927 | ###!!! [Parent][PCompositorManagerParent] Error: RunMessage(msgname=PCompositorBridge::Msg___delete__) Channel closing: too late to send/recv, messages will be lost
[task 2022-03-24T05:50:16.942Z] 05:50:16     INFO - PID 4927 | ###!!! [Parent][PImageBridgeParent] Error: RunMessage(msgname=PImageBridge::Msg_WillClose) Channel closing: too late to send/recv, messages will be lost
[task 2022-03-24T05:50:16.947Z] 05:50:16     INFO - PID 4927 | ###!!! [Parent][PCompositorManagerParent] Error: RunMessage(msgname=PCompositorBridge::Msg___delete__) Channel closing: too late to send/recv, messages will be lost
[task 2022-03-24T05:50:16.947Z] 05:50:16     INFO - PID 4927 | ###!!! [Parent][PImageBridgeParent] Error: RunMessage(msgname=PImageBridge::Msg_WillClose) Channel closing: too late to send/recv, messages will be lost
[task 2022-03-24T05:50:31.903Z] 05:50:31     INFO - PID 4927 | console.error: (new Error("SessionFile is closed", "resource:///modules/sessionstore/SessionFile.jsm", 443))
[task 2022-03-24T05:50:34.788Z] 05:50:34     INFO - PID 4927 | WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"ContentParent: id=7eff90566600","state":{"remoteTypePrefix":"webServiceWorker"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3564,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7eff889e2600","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3564,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7eff9056a700","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3564,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7eff84439600","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3564,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]}] Barrier: profile-before-change
[task 2022-03-24T05:51:25.788Z] 05:51:25     INFO - PID 4927 | FATAL ERROR: AsyncShutdown timeout in profile-before-change Conditions: [{"name":"ContentParent: id=7eff90566600","state":{"remoteTypePrefix":"webServiceWorker"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3564,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7eff889e2600","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3564,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7eff9056a700","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3564,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7eff84439600","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3564,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]}] At least one completion condition failed to complete within a reasonable amount of time. Causing a crash to ensure that we do not leave the user with an unresponsive process draining resources.
[task 2022-03-24T05:51:25.789Z] 05:51:25     INFO - PID 4927 | [Parent 4927, Main Thread] ###!!! ABORT: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3564
[task 2022-03-24T05:51:25.792Z] 05:51:25     INFO - PID 4927 | ExceptionHandler::GenerateDump cloned child 5566
[task 2022-03-24T05:51:25.792Z] 05:51:25     INFO - PID 4927 | ExceptionHandler::SendContinueSignalToChild sent continue signal to child
[task 2022-03-24T05:51:25.793Z] 05:51:25     INFO - PID 4927 | ExceptionHandler::WaitForContinueSignal waiting for continue signal...
[task 2022-03-24T05:51:27.784Z] 05:51:27     INFO - Browser exited with return code 11
[task 2022-03-24T05:51:27.785Z] 05:51:27     INFO - Closing logging queue
[task 2022-03-24T05:51:27.786Z] 05:51:27     INFO - queue closed
[task 2022-03-24T05:51:27.802Z] 05:51:27     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmprtftmt8k
[task 2022-03-24T05:51:27.813Z] 05:51:27     INFO - PID 5263 | [CodeCoverage] Setting handlers for process 5263.
[task 2022-03-24T05:51:27.813Z] 05:51:27     INFO - PID 5263 | 1648100989812	Marionette	INFO	Marionette enabled
[task 2022-03-24T05:51:27.814Z] 05:51:27     INFO - PID 5263 | [CodeCoverage] Setting handlers for process 5374.
[task 2022-03-24T05:51:27.814Z] 05:51:27     INFO - PID 5263 | [CodeCoverage] Setting handlers for process 5383.
[task 2022-03-24T05:51:27.815Z] 05:51:27     INFO - PID 5263 | [CodeCoverage] Setting handlers for process 5459.
[task 2022-03-24T05:51:27.816Z] 05:51:27     INFO - PID 5263 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/tmp_8gx4xul/search.json.mozlz4", (void 0)))
[task 2022-03-24T05:51:27.816Z] 05:51:27     INFO - PID 5263 | 1648100997836	Marionette	INFO	Listening on port 37316
[task 2022-03-24T05:51:27.817Z] 05:51:27     INFO - Starting runner
[task 2022-03-24T05:51:28.577Z] 05:51:28     INFO - TEST-START | /content-security-policy/worker-src/service-none.https.sub.html

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

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=382113804&repo=mozilla-central

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

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

Status: REOPENED → RESOLVED
Closed: 3 years ago → 3 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.