1767261 - Intermittent TEST-UNEXPECTED-TIMEOUT | /css/WOFF2/blocks-extraneous-data-001.xht | expected PASS

Status: RESOLVED DUPLICATE of bug 1786991

(Core :: CSS Parsing and Computation, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2022-05-02T10:49:20.578Z] 10:49:20     INFO - TEST-START | /css/WOFF2/blocks-extraneous-data-001.xht
[task 2022-05-02T10:49:20.593Z] 10:49:20     INFO - Setting pref dom.animations-api.compositing.enabled to true
[task 2022-05-02T10:49:20.615Z] 10:49:20     INFO - Setting pref layout.css.cascade-layers.enabled to true
[task 2022-05-02T10:49:20.703Z] 10:49:20     INFO - PID 3379 | 1651488560702	Marionette	INFO	Testing http://web-platform.test:8000/css/WOFF2/blocks-extraneous-data-001.xht == http://web-platform.test:8000/css/WOFF2/blocks-extraneous-data-001-ref.xht
[task 2022-05-02T10:49:40.655Z] 10:49:40     INFO - TEST-UNEXPECTED-TIMEOUT | /css/WOFF2/blocks-extraneous-data-001.xht | expected PASS
[task 2022-05-02T10:49:40.655Z] 10:49:40     INFO - TEST-INFO took 20075ms
[task 2022-05-02T10:49:40.687Z] 10:49:40     INFO - PID 3379 | 1651488580685	Marionette	INFO	Stopped listening on port 40938
[task 2022-05-02T10:49:40.861Z] 10:49:40     INFO - PID 3379 | ###!!! [Parent][PCompositorManagerParent] Error: RunMessage(msgname=PCompositorBridge::Msg___delete__) Channel closing: too late to send/recv, messages will be lost
[task 2022-05-02T10:49:55.853Z] 10:49:55     INFO - PID 3379 | console.error: (new Error("SessionFile is closed", "resource:///modules/sessionstore/SessionFile.jsm", 379))
[task 2022-05-02T10:49:58.762Z] 10:49:58     INFO - PID 3379 | WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"ContentParent: id=7f14a7f58100","state":{"remoteTypePrefix":"web"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3638,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7f149ccea000","state":{"remoteTypePrefix":"webIsolated"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3638,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7f149ccea500","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3638,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7f14abe58600","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3638,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7f14abe5c700","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3638,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]}] Barrier: profile-before-change
[task 2022-05-02T10:50:49.762Z] 10:50:49     INFO - PID 3379 | FATAL ERROR: AsyncShutdown timeout in profile-before-change Conditions: [{"name":"ContentParent: id=7f14a7f58100","state":{"remoteTypePrefix":"web"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3638,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7f149ccea000","state":{"remoteTypePrefix":"webIsolated"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3638,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7f149ccea500","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3638,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7f14abe58600","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3638,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7f14abe5c700","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3638,"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-05-02T10:50:49.763Z] 10:50:49     INFO - PID 3379 | [Parent 3379, Main Thread] ###!!! ABORT: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3638
[task 2022-05-02T10:50:49.765Z] 10:50:49     INFO - PID 3379 | ExceptionHandler::GenerateDump cloned child 4842
[task 2022-05-02T10:50:49.766Z] 10:50:49     INFO - PID 3379 | ExceptionHandler::SendContinueSignalToChild sent continue signal to child
[task 2022-05-02T10:50:49.766Z] 10:50:49     INFO - PID 3379 | ExceptionHandler::WaitForContinueSignal waiting for continue signal...
[task 2022-05-02T10:50:51.699Z] 10:50:51     INFO - Browser exited with return code 11
[task 2022-05-02T10:50:51.699Z] 10:50:51     INFO - Closing logging queue
[task 2022-05-02T10:50:51.699Z] 10:50:51     INFO - queue closed
[task 2022-05-02T10:50:51.735Z] 10:50:51     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmp6oa55c_b
[task 2022-05-02T10:50:51.748Z] 10:50:51     INFO - PID 4684 | [CodeCoverage] Setting handlers for process 4684.
[task 2022-05-02T10:50:51.749Z] 10:50:51     INFO - PID 4684 | 1651488561057	Marionette	INFO	Marionette enabled
[task 2022-05-02T10:50:51.753Z] 10:50:51     INFO - PID 4684 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/tmptmo6_ffx/search.json.mozlz4", (void 0)))
[task 2022-05-02T10:50:51.754Z] 10:50:51     INFO - PID 4684 | 1651488566491	Marionette	INFO	Listening on port 36696
[task 2022-05-02T10:50:51.754Z] 10:50:51     INFO - Starting runner
[task 2022-05-02T10:50:52.807Z] 10:50:52     INFO - PID 4684 | [CodeCoverage] Setting handlers for process 4793.
[task 2022-05-02T10:50:53.168Z] 10:50:53     INFO - PID 4684 | [CodeCoverage] Setting handlers for process 4800.
[task 2022-05-02T10:50:53.532Z] 10:50:53     INFO - TEST-START | /css/WOFF2/blocks-extraneous-data-002.xht

Comment 1

[Intermittent Failures Robot]

1 failures in 3540 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-ccov-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1767261&startday=2022-05-02&endday=2022-05-08&tree=all

Comment 2

[BugBot [:suhaib / :marco/ :calixte]]

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

Comment 3

[Iulian Moraru]

This started to fail on tier 1: https://treeherder.mozilla.org/logviewer?job_id=388015914&repo=autoland&lineNumber=15293