Closed Bug 1759238 Opened 3 years ago Closed 3 years ago

Intermittent [tier 2] TEST-UNEXPECTED-TIMEOUT | /css/css-multicol/multicol-height-002-print.xht | expected PASS

Categories

(Core :: Layout: Columns, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=370735078&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/JTmKGwNKQUyMfcZl3BBGdw/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/JTmKGwNKQUyMfcZl3BBGdw/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1


[task 2022-03-11T23:10:59.271Z] 23:10:59     INFO - TEST-START | /css/css-multicol/multicol-height-002-print.xht
[task 2022-03-11T23:10:59.338Z] 23:10:59     INFO - PID 2788 | 1647040259337	Marionette	INFO	Testing http://web-platform.test:8000/css/css-multicol/multicol-height-002-print.xht == http://web-platform.test:8000/css/css-multicol/multicol-height-002-print-ref.xht
[task 2022-03-11T23:11:19.297Z] 23:11:19     INFO - TEST-UNEXPECTED-TIMEOUT | /css/css-multicol/multicol-height-002-print.xht | expected PASS
[task 2022-03-11T23:11:19.297Z] 23:11:19     INFO - TEST-INFO took 20027ms
[task 2022-03-11T23:11:19.298Z] 23:11:19     INFO - Restarting browser for new test group
[task 2022-03-11T23:11:19.330Z] 23:11:19     INFO - PID 2788 | 1647040279329	Marionette	INFO	Stopped listening on port 38352
[task 2022-03-11T23:11:19.521Z] 23:11:19     INFO - PID 2788 | ###!!! [Parent][PCompositorManagerParent] Error: RunMessage(msgname=PCompositorBridge::Msg___delete__) Channel closing: too late to send/recv, messages will be lost
[task 2022-03-11T23:11:19.535Z] 23:11:19     INFO - PID 2788 | ###!!! [Parent][PCompositorManagerParent] Error: RunMessage(msgname=PCompositorBridge::Msg___delete__) Channel closing: too late to send/recv, messages will be lost
[task 2022-03-11T23:11:19.538Z] 23:11:19     INFO - PID 2788 | ###!!! [Parent][PImageBridgeParent] Error: RunMessage(msgname=PImageBridge::Msg_WillClose) Channel closing: too late to send/recv, messages will be lost
[task 2022-03-11T23:11:34.517Z] 23:11:34     INFO - PID 2788 | console.error: (new Error("SessionFile is closed", "resource:///modules/sessionstore/SessionFile.jsm", 443))
[task 2022-03-11T23:11:37.410Z] 23:11:37     INFO - PID 2788 | WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"ContentParent: id=7f9dac69ac00","state":{"remoteTypePrefix":"webIsolated"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3563,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7f9dac69b600","state":{"remoteTypePrefix":"web"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3563,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7f9da7545000","state":{"remoteTypePrefix":"webIsolated"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3563,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7f9da7545500","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3563,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7f9dd2ec1200","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3563,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7f9dd2ec1700","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3563,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]}] Barrier: profile-before-change
[task 2022-03-11T23:12:28.410Z] 23:12:28     INFO - PID 2788 | FATAL ERROR: AsyncShutdown timeout in profile-before-change Conditions: [{"name":"ContentParent: id=7f9dac69ac00","state":{"remoteTypePrefix":"webIsolated"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3563,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7f9dac69b600","state":{"remoteTypePrefix":"web"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3563,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7f9da7545000","state":{"remoteTypePrefix":"webIsolated"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3563,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7f9da7545500","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3563,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7f9dd2ec1200","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3563,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7f9dd2ec1700","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3563,"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-11T23:12:28.410Z] 23:12:28     INFO - PID 2788 | [Parent 2788, Main Thread] ###!!! ABORT: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3563
[task 2022-03-11T23:12:28.413Z] 23:12:28     INFO - PID 2788 | ExceptionHandler::GenerateDump cloned child 3250
[task 2022-03-11T23:12:28.414Z] 23:12:28     INFO - PID 2788 | ExceptionHandler::SendContinueSignalToChild sent continue signal to child
[task 2022-03-11T23:12:28.414Z] 23:12:28     INFO - PID 2788 | ExceptionHandler::WaitForContinueSignal waiting for continue signal...
[task 2022-03-11T23:12:30.347Z] 23:12:30     INFO - Browser exited with return code 11
[task 2022-03-11T23:12:30.351Z] 23:12:30     INFO - Closing logging queue
[task 2022-03-11T23:12:30.352Z] 23:12:30     INFO - queue closed
[task 2022-03-11T23:12:30.370Z] 23:12:30     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmp2acjand5
[task 2022-03-11T23:12:30.395Z] 23:12:30     INFO - PID 3090 | [CodeCoverage] Setting handlers for process 3090.
[task 2022-03-11T23:12:30.395Z] 23:12:30     INFO - PID 3090 | 1647040239549	Marionette	INFO	Marionette enabled
[task 2022-03-11T23:12:30.395Z] 23:12:30     INFO - PID 3090 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/tmpo22jdhpw/search.json.mozlz4", (void 0)))
[task 2022-03-11T23:12:30.395Z] 23:12:30     INFO - PID 3090 | 1647040243794	Marionette	INFO	Listening on port 51994
[task 2022-03-11T23:12:30.395Z] 23:12:30     INFO - Starting runner
[task 2022-03-11T23:12:31.405Z] 23:12:31     INFO - PID 3090 | [CodeCoverage] Setting handlers for process 3205.
[task 2022-03-11T23:12:31.512Z] 23:12:31     INFO - PID 3090 | [CodeCoverage] Setting handlers for process 3197.
[task 2022-03-11T23:12:31.751Z] 23:12:31     INFO - TEST-START | /css/css-position/position-fixed-overflow-print.html
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.