Closed Bug 1762377 Opened 4 years ago Closed 3 years ago

Intermittent [tier 2] TEST-UNEXPECTED-TIMEOUT | /webmessaging/Channel_postMessage_transfer_xsite_incoming_messages.window.html | Tasks enqueued on the port-message-queue of an enabled port,

Categories

(Core :: DOM: Core & HTML, defect, P5)

Product:
Core
Component:
DOM: Core & HTML
Type:
defect

Tracking

()

Status:
RESOLVED DUPLICATE of bug 1787054

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

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

[task 2022-03-31T11:07:25.404Z] 11:07:25     INFO - TEST-START | /webmessaging/Channel_postMessage_transfer_xsite_incoming_messages.window.html
[task 2022-03-31T11:07:25.415Z] 11:07:25     INFO - Closing window 68144659-0027-4d78-9912-073eaf8b90a4
[task 2022-03-31T11:07:45.782Z] 11:07:45     INFO - 
[task 2022-03-31T11:07:45.782Z] 11:07:45     INFO - TEST-UNEXPECTED-TIMEOUT | /webmessaging/Channel_postMessage_transfer_xsite_incoming_messages.window.html | Tasks enqueued on the port-message-queue of an enabled port,
[task 2022-03-31T11:07:45.782Z] 11:07:45     INFO -   are transferred along with the port, when the transfer happens in the same task
[task 2022-03-31T11:07:45.782Z] 11:07:45     INFO -   during which postMessage is called - Test timed out
[task 2022-03-31T11:07:45.783Z] 11:07:45     INFO - TEST-UNEXPECTED-TIMEOUT | /webmessaging/Channel_postMessage_transfer_xsite_incoming_messages.window.html | expected OK
[task 2022-03-31T11:07:45.783Z] 11:07:45     INFO - TEST-INFO took 20379ms
[task 2022-03-31T11:07:45.785Z] 11:07:45     INFO - PID 20958 | 1648724865784	Marionette	INFO	Stopped listening on port 36048
[task 2022-03-31T11:07:53.985Z] 11:07:53     INFO - PID 20958 | ###!!! [Parent][PBackgroundParent] Error: RunMessage(msgname=PRemoteWorkerService::Msg___delete__) Channel closing: too late to send/recv, messages will be lost
[task 2022-03-31T11:07:53.987Z] 11:07:53     INFO - PID 20958 | ###!!! [Parent][PCompositorManagerParent] Error: RunMessage(msgname=PCompositorBridge::Msg___delete__) Channel closing: too late to send/recv, messages will be lost
[task 2022-03-31T11:07:55.796Z] 11:07:55     INFO - PID 20958 | WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"SessionStore: flushing all windows","state":{"total":1,"current":0},"filename":"resource:///modules/sessionstore/SessionStore.jsm","lineNumber":2107,"stack":["resource:///modules/sessionstore/SessionStore.jsm:ssi_onQuitApplicationGranted:2107","resource:///modules/sessionstore/SessionStore.jsm:ssi_observe:1023","chrome://remote/content/marionette/driver.js:GeckoDriver.prototype.quit:2661","chrome://remote/content/marionette/server.js:despatch:306","chrome://remote/content/marionette/server.js:execute:279","chrome://remote/content/marionette/server.js:onPacket/<:252","chrome://remote/content/marionette/server.js:onPacket:253","chrome://remote/content/marionette/transport.js:_onJSONObjectReady/<:500"]}] Barrier: quit-application-granted
[task 2022-03-31T11:08:36.859Z] 11:08:36     INFO - PID 20958 | ###!!! [Parent][PCompositorManagerParent] Error: RunMessage(msgname=PCompositorBridge::Msg___delete__) Channel closing: too late to send/recv, messages will be lost
[task 2022-03-31T11:08:36.861Z] 11:08:36     INFO - PID 20958 | ###!!! [Parent][PImageBridgeParent] Error: RunMessage(msgname=PImageBridge::Msg_WillClose) Channel closing: too late to send/recv, messages will be lost
[task 2022-03-31T11:08:46.888Z] 11:08:46     INFO - PID 20958 | WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"ContentParent: id=7f0ec32f1300","state":{"remoteTypePrefix":"webIsolated"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3568,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7f0ebd4f8800","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3568,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7f0ebc728300","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3568,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7f0ec76f2900","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3568,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]}] Barrier: profile-before-change
[task 2022-03-31T11:08:51.850Z] 11:08:51     INFO - PID 20958 | console.error: (new Error("SessionFile is closed", "resource:///modules/sessionstore/SessionFile.jsm", 373))
[task 2022-03-31T11:08:56.848Z] 11:08:56     INFO - Browser exited with return code -15
[task 2022-03-31T11:08:56.850Z] 11:08:56     INFO - Closing logging queue
[task 2022-03-31T11:08:56.850Z] 11:08:56     INFO - queue closed
[task 2022-03-31T11:08:56.877Z] 11:08:56     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmp6sl46s1r
[task 2022-03-31T11:08:56.907Z] 11:08:56     INFO - PID 21318 | [CodeCoverage] Setting handlers for process 21318.
[task 2022-03-31T11:08:56.908Z] 11:08:56     INFO - PID 21318 | 1648724839267	Marionette	INFO	Marionette enabled
[task 2022-03-31T11:08:56.908Z] 11:08:56     INFO - PID 21318 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/tmpduvtc7wg/search.json.mozlz4", (void 0)))
[task 2022-03-31T11:08:56.908Z] 11:08:56     INFO - PID 21318 | 1648724846196	Marionette	INFO	Listening on port 58700
[task 2022-03-31T11:08:56.908Z] 11:08:56     INFO - Starting runner
[task 2022-03-31T11:08:58.008Z] 11:08:58     INFO - PID 21318 | [CodeCoverage] Setting handlers for process 21436.
[task 2022-03-31T11:08:58.203Z] 11:08:58     INFO - PID 21318 | [CodeCoverage] Setting handlers for process 21426.
[task 2022-03-31T11:08:59.289Z] 11:08:59     INFO - PID 21318 | [CodeCoverage] Setting handlers for process 21607.
[task 2022-03-31T11:08:59.677Z] 11:08:59     INFO - TEST-START | /webmessaging/Channel_postMessage_with_transfer_entangled.any.html

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

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

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

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