Closed Bug 1753354 Opened 3 years ago Closed 3 years ago

Intermittent [tier 2] TEST-UNEXPECTED-TIMEOUT | /css/css-logical/cascading-001.html | expected PASS

Categories

(Core :: Layout, defect, P5)

Product:
Core
Component:
Layout
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: imoraru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=366552354&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/VBA44vWMSe2bQIIFOI0QPw/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/VBA44vWMSe2bQIIFOI0QPw/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

[task 2022-02-03T02:12:34.405Z] 02:12:34     INFO - TEST-START | /css/css-logical/cascading-001.html
[task 2022-02-03T02:12:34.421Z] 02:12:34     INFO - Setting pref dom.animations-api.compositing.enabled (true)
[task 2022-02-03T02:12:34.445Z] 02:12:34     INFO - Setting pref layout.css.cascade-layers.enabled (true)
[task 2022-02-03T02:12:34.468Z] 02:12:34     INFO - Setting pref dom.animations-api.core.enabled (true)
[task 2022-02-03T02:12:34.489Z] 02:12:34     INFO - Setting pref dom.animations-api.getAnimations.enabled (true)
[task 2022-02-03T02:12:34.528Z] 02:12:34     INFO - Setting pref dom.animations-api.implicit-keyframes.enabled (true)
[task 2022-02-03T02:12:34.630Z] 02:12:34     INFO - PID 9777 | 1643854354629	Marionette	INFO	Testing http://web-platform.test:8000/css/css-logical/cascading-001.html == http://web-platform.test:8000/css/css-logical/cascading-001-ref.html
[task 2022-02-03T02:12:54.563Z] 02:12:54     INFO - TEST-UNEXPECTED-TIMEOUT | /css/css-logical/cascading-001.html | expected PASS
[task 2022-02-03T02:12:54.564Z] 02:12:54     INFO - TEST-INFO took 20157ms
[task 2022-02-03T02:12:54.597Z] 02:12:54     INFO - PID 9777 | 1643854374596	Marionette	INFO	Stopped listening on port 60500
[task 2022-02-03T02:12:54.773Z] 02:12:54     INFO - PID 9777 | JavaScript error: resource://gre/modules/URLQueryStrippingListService.jsm, line 90: TypeError: can't access property "removeEventListener", Services.cpmm.sharedData is null
[task 2022-02-03T02:12:54.775Z] 02:12:54     INFO - PID 9777 | ###!!! [Parent][PCompositorManagerParent] Error: RunMessage(msgname=PCompositorBridge::Msg___delete__) Channel closing: too late to send/recv, messages will be lost
[task 2022-02-03T02:12:54.776Z] 02:12:54     INFO - PID 9777 | ###!!! [Parent][PImageBridgeParent] Error: RunMessage(msgname=PImageBridge::Msg_WillClose) Channel closing: too late to send/recv, messages will be lost
[task 2022-02-03T02:12:54.787Z] 02:12:54     INFO - PID 9777 | ###!!! [Parent][PBackgroundParent] Error: RunMessage(msgname=PRemoteWorkerService::Msg___delete__) Channel closing: too late to send/recv, messages will be lost
[task 2022-02-03T02:12:54.789Z] 02:12:54     INFO - PID 9777 | JavaScript error: resource://gre/modules/URLQueryStrippingListService.jsm, line 90: TypeError: can't access property "removeEventListener", Services.cpmm.sharedData is null
[task 2022-02-03T02:12:54.791Z] 02:12:54     INFO - PID 9777 | ###!!! [Parent][PCompositorManagerParent] Error: RunMessage(msgname=PCompositorBridge::Msg___delete__) Channel closing: too late to send/recv, messages will be lost
[task 2022-02-03T02:13:09.768Z] 02:13:09     INFO - PID 9777 | console.error: (new Error("SessionFile is closed", "resource:///modules/sessionstore/SessionFile.jsm", 446))
[task 2022-02-03T02:13:12.659Z] 02:13:12     INFO - PID 9777 | WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"ContentParent: id=7fbd21f45b00","state":{"remoteTypePrefix":"web"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3509,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7fbd22a3ab00","state":{"remoteTypePrefix":"webIsolated"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3509,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7fbd22a36f00","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3509,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7fbd21f41500","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3509,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7fbd21f41f00","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3509,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]}] Barrier: profile-before-change
[task 2022-02-03T02:13:22.888Z] 02:13:22     INFO - PID 9777 | JavaScript error: resource://gre/modules/CrashManager.jsm, line 898: AbortError: IOUtils: Shutting down and refusing additional I/O tasks
[task 2022-02-03T02:14:03.659Z] 02:14:03     INFO - PID 9777 | FATAL ERROR: AsyncShutdown timeout in profile-before-change Conditions: [{"name":"ContentParent: id=7fbd21f45b00","state":{"remoteTypePrefix":"web"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3509,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7fbd22a3ab00","state":{"remoteTypePrefix":"webIsolated"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3509,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7fbd22a36f00","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3509,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7fbd21f41500","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3509,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7fbd21f41f00","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3509,"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-02-03T02:14:03.660Z] 02:14:03     INFO - PID 9777 | [Parent 9777, Main Thread] ###!!! ABORT: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3509
[task 2022-02-03T02:14:03.662Z] 02:14:03     INFO - PID 9777 | ExceptionHandler::GenerateDump cloned child 10236
[task 2022-02-03T02:14:03.663Z] 02:14:03     INFO - PID 9777 | ExceptionHandler::SendContinueSignalToChild sent continue signal to child
[task 2022-02-03T02:14:03.663Z] 02:14:03     INFO - PID 9777 | ExceptionHandler::WaitForContinueSignal waiting for continue signal...
[task 2022-02-03T02:14:05.606Z] 02:14:05     INFO - Browser exited with return code 11
[task 2022-02-03T02:14:05.608Z] 02:14:05     INFO - Closing logging queue
[task 2022-02-03T02:14:05.608Z] 02:14:05     INFO - queue closed
[task 2022-02-03T02:14:05.634Z] 02:14:05     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmp_ep8ndxk
[task 2022-02-03T02:14:05.647Z] 02:14:05     INFO - PID 10096 | [CodeCoverage] Setting handlers for process 10096.
[task 2022-02-03T02:14:05.648Z] 02:14:05     INFO - PID 10096 | 1643854354582	Marionette	INFO	Marionette enabled
[task 2022-02-03T02:14:05.651Z] 02:14:05     INFO - PID 10096 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/tmpso8ns_sj/search.json.mozlz4", (void 0)))
[task 2022-02-03T02:14:05.651Z] 02:14:05     INFO - PID 10096 | 1643854359185	Marionette	INFO	Listening on port 49973
[task 2022-02-03T02:14:05.654Z] 02:14:05     INFO - Starting runner
[task 2022-02-03T02:14:06.571Z] 02:14:06     INFO - PID 10096 | [CodeCoverage] Setting handlers for process 10199.
[task 2022-02-03T02:14:06.634Z] 02:14:06     INFO - PID 10096 | [CodeCoverage] Setting handlers for process 10202.
[task 2022-02-03T02:14:06.847Z] 02:14:06     INFO - TEST-START | /css/css-logical/logical-values-float-clear-1.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
You need to log in before you can comment on or make changes to this bug.