Closed Bug 1743208 Opened 4 years ago Closed 3 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /feature-policy/experimental-features/vertical-scroll-disabled-scrollbar-tentative.html | expected FAIL

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=359402737&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/VDtrhX2BQ-abLhge7qDd9A/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/VDtrhX2BQ-abLhge7qDd9A/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

[task 2021-11-26T22:24:19.611Z] 22:24:19     INFO - TEST-START | /html/editing/editing-0/contenteditable/contenteditable-overflow-height.html
[task 2021-11-26T22:24:19.724Z] 22:24:19     INFO - PID 12080 | 1637965459723	Marionette	INFO	Testing http://web-platform.test:8000/html/editing/editing-0/contenteditable/contenteditable-overflow-height.html == http://web-platform.test:8000/html/editing/editing-0/contenteditable/contenteditable-overflow-height-ref.html
[task 2021-11-26T22:24:38.357Z] 22:24:38     INFO - TEST-UNEXPECTED-TIMEOUT | /feature-policy/experimental-features/vertical-scroll-disabled-scrollbar-tentative.html | expected FAIL
[task 2021-11-26T22:24:38.357Z] 22:24:38     INFO - TEST-INFO expected FAIL | took 20206ms
[task 2021-11-26T22:24:38.358Z] 22:24:38     INFO - Restarting browser for new test group
[task 2021-11-26T22:24:38.393Z] 22:24:38     INFO - PID 11993 | 1637965478392	Marionette	INFO	Stopped listening on port 38220
[task 2021-11-26T22:24:38.561Z] 22:24:38     INFO - PID 11993 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-11-26T22:24:38.569Z] 22:24:38     INFO - PID 11993 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-11-26T22:24:38.570Z] 22:24:38     INFO - PID 11993 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-11-26T22:24:39.640Z] 22:24:39     INFO - TEST-UNEXPECTED-TIMEOUT | /html/editing/editing-0/contenteditable/contenteditable-overflow-height.html | expected PASS
[task 2021-11-26T22:24:39.640Z] 22:24:39     INFO - TEST-INFO took 20033ms
[task 2021-11-26T22:24:39.669Z] 22:24:39     INFO - PID 12080 | 1637965479667	Marionette	INFO	Stopped listening on port 42598
[task 2021-11-26T22:24:49.679Z] 22:24:49     INFO - PID 12080 | 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":2057,"stack":["resource:///modules/sessionstore/SessionStore.jsm:ssi_onQuitApplicationGranted:2057","resource:///modules/sessionstore/SessionStore.jsm:ssi_observe:976","chrome://remote/content/marionette/driver.js:GeckoDriver.prototype.quit:2677","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 2021-11-26T22:24:53.557Z] 22:24:53     INFO - PID 11993 | console.error: (new Error("SessionFile is closed", "resource:///modules/sessionstore/SessionFile.jsm", 446))
[task 2021-11-26T22:24:56.446Z] 22:24:56     INFO - PID 11993 | WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"ContentParent: id=7fbe5d9dfa00","state":{"remoteTypePrefix":"web"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3520,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7fbe604d7a00","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3520,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]}] Barrier: profile-before-change
[task 2021-11-26T22:25:08.890Z] 22:25:08     INFO - PID 11993 | JavaScript error: resource://gre/modules/CrashManager.jsm, line 882: AbortError: IOUtils: Shutting down and refusing additional I/O tasks
[task 2021-11-26T22:25:40.758Z] 22:25:40     INFO - PID 12080 | WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"Extension shutdown: webcompat@mozilla.org","state":{"state":"Startup: Run manifest, asyncEmitManifestEntry(\"background\")"},"filename":"resource://gre/modules/addons/XPIProvider.jsm","lineNumber":2598,"stack":["resource://gre/modules/addons/XPIProvider.jsm:startup/<:2598","resource://gre/modules/AsyncShutdown.jsm:observe:575","chrome://remote/content/marionette/driver.js:GeckoDriver.prototype.quit:2677","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"]},{"name":"Extension shutdown: webcompat-reporter@mozilla.org","state":{"state":"Startup: Run manifest, asyncEmitManifestEntry(\"background\")"},"filename":"resource://gre/modules/addons/XPIProvider.jsm","lineNumber":2598,"stack":["resource://gre/modules/addons/XPIProvider.jsm:startup/<:2598","resource://gre/modules/AsyncShutdown.jsm:observe:575","chrome://remote/content/marionette/driver.js:GeckoDriver.prototype.quit:2677","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"]},{"name":"Extension shutdown: pictureinpicture@mozilla.org","state":{"state":"Startup: Run manifest, asyncEmitManifestEntry(\"background\")"},"filename":"resource://gre/modules/addons/XPIProvider.jsm","lineNumber":2598,"stack":["resource://gre/modules/addons/XPIProvider.jsm:startup/<:2598","resource://gre/modules/AsyncShutdown.jsm:observe:575","chrome://remote/content/marionette/driver.js:GeckoDriver.prototype.quit:2677","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"]},{"name":"Extension shutdown: formautofill@mozilla.org","state":{"state":"Startup: Run manifest, asyncEmitManifestEntry(\"background\")"},"filename":"resource://gre/modules/addons/XPIProvider.jsm","lineNumber":2598,"stack":["resource://gre/modules/addons/XPIProvider.jsm:startup/<:2598","resource://gre/modules/AsyncShutdown.jsm:observe:575","chrome://remote/content/marionette/driver.js:GeckoDriver.prototype.quit:2677","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"]},{"name":"Extension shutdown: addons-search-detection@mozilla.com","state":{"state":"Startup: Run manifest, asyncEmitManifestEntry(\"background\")"},"filename":"resource://gre/modules/addons/XPIProvider.jsm","lineNumber":2598,"stack":["resource://gre/modules/addons/XPIProvider.jsm:startup/<:2598","resource://gre/modules/AsyncShutdown.jsm:observe:575","chrome://remote/content/marionette/driver.js:GeckoDriver.prototype.quit:2677","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: profile-change-teardown
[task 2021-11-26T22:25:47.447Z] 22:25:47     INFO - PID 11993 | FATAL ERROR: AsyncShutdown timeout in profile-before-change Conditions: [{"name":"ContentParent: id=7fbe5d9dfa00","state":{"remoteTypePrefix":"web"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3520,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7fbe604d7a00","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3520,"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 2021-11-26T22:25:47.447Z] 22:25:47     INFO - PID 11993 | [Parent 11993, Main Thread] ###!!! ABORT: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3520
[task 2021-11-26T22:25:47.450Z] 22:25:47     INFO - PID 11993 | ExceptionHandler::WaitForContinueSignal waiting for continue signal...
[task 2021-11-26T22:25:47.451Z] 22:25:47     INFO - PID 11993 | ExceptionHandler::GenerateDump cloned child 12320
[task 2021-11-26T22:25:47.451Z] 22:25:47     INFO - PID 11993 | ExceptionHandler::SendContinueSignalToChild sent continue signal to child
[task 2021-11-26T22:25:49.400Z] 22:25:49     INFO - Browser exited with return code 11
[task 2021-11-26T22:25:49.401Z] 22:25:49     INFO - Closing logging queue
[task 2021-11-26T22:25:49.403Z] 22:25:49     INFO - queue closed
[task 2021-11-26T22:25:49.429Z] 22:25:49     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmp20kym0xh
[task 2021-11-26T22:25:49.451Z] 22:25:49     INFO - Starting runner
[task 2021-11-26T22:25:49.516Z] 22:25:49     INFO - PID 12322 | [CodeCoverage] Setting handlers for process 12322.
[task 2021-11-26T22:25:50.533Z] 22:25:50     INFO - PID 12080 | [CodeCoverage] Setting handlers for process 12227.
[task 2021-11-26T22:25:50.580Z] 22:25:50     INFO - PID 12080 | [GFX1-]: Failed to connect WebRenderBridgeChild.
[task 2021-11-26T22:25:50.626Z] 22:25:50     INFO - PID 12080 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-11-26T22:25:50.688Z] 22:25:50     INFO - Browser exited with return code -15
[task 2021-11-26T22:25:50.692Z] 22:25:50     INFO - Closing logging queue
[task 2021-11-26T22:25:50.692Z] 22:25:50     INFO - queue closed
[task 2021-11-26T22:25:50.727Z] 22:25:50     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmpo49hrcui
[task 2021-11-26T22:25:50.741Z] 22:25:50     INFO - Starting runner
[task 2021-11-26T22:25:50.806Z] 22:25:50     INFO - PID 12398 | [CodeCoverage] Setting handlers for process 12398.
[task 2021-11-26T22:25:50.875Z] 22:25:50     INFO - PID 12322 | 1637965550874	Marionette	INFO	Marionette enabled
[task 2021-11-26T22:25:51.836Z] 22:25:51     INFO - PID 12398 | 1637965551835	Marionette	INFO	Marionette enabled
[task 2021-11-26T22:25:52.680Z] 22:25:52     INFO - PID 12322 | [CodeCoverage] Setting handlers for process 12523.
[task 2021-11-26T22:25:52.971Z] 22:25:52     INFO - PID 12322 | [CodeCoverage] Setting handlers for process 12532.
[task 2021-11-26T22:25:53.465Z] 22:25:53     INFO - PID 12398 | [CodeCoverage] Setting handlers for process 12556.
[task 2021-11-26T22:25:53.691Z] 22:25:53     INFO - PID 12398 | [CodeCoverage] Setting handlers for process 12565.
[task 2021-11-26T22:25:55.036Z] 22:25:55     INFO - PID 12398 | [CodeCoverage] Setting handlers for process 12630.
[task 2021-11-26T22:25:55.544Z] 22:25:55     INFO - PID 12322 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/tmp20kym0xh/search.json.mozlz4", (void 0)))
[task 2021-11-26T22:25:56.683Z] 22:25:56     INFO - PID 12322 | [CodeCoverage] Setting handlers for process 12663.
[task 2021-11-26T22:25:56.789Z] 22:25:56     INFO - PID 12398 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/tmpo49hrcui/search.json.mozlz4", (void 0)))
[task 2021-11-26T22:25:57.390Z] 22:25:57     INFO - PID 12322 | 1637965557389	Marionette	INFO	Listening on port 47728
[task 2021-11-26T22:25:57.561Z] 22:25:57     INFO - TEST-START | /html/rendering/the-details-element/details-after.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=364016427&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: 4 years ago → 4 years ago
Resolution: --- → INCOMPLETE
Summary: Intermittent [tier 2] TEST-UNEXPECTED-TIMEOUT | /feature-policy/experimental-features/vertical-scroll-disabled-scrollbar-tentative.html | expected FAIL → Intermittent TEST-UNEXPECTED-TIMEOUT | /feature-policy/experimental-features/vertical-scroll-disabled-scrollbar-tentative.html | expected FAIL

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=392906031&repo=autoland

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: 4 years ago → 3 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.