Closed Bug 1738970 Opened 4 years ago Closed 4 years ago

Intermittent [Tier 2] TEST-UNEXPECTED-TIMEOUT | /html/browsers/browsing-the-web/back-forward-cache/events.html | unload - Test timed out

Categories

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

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

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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

INFO - TEST-PASS | /html/browsers/browsing-the-web/back-forward-cache/events.html | CrossSite 
[task 2021-11-02T19:12:42.007Z] 19:12:42     INFO - TEST-PRECONDITION_FAILED | /html/browsers/browsing-the-web/back-forward-cache/events.html | beforeunload - Should be BFCached but actually wasn't
[task 2021-11-02T19:12:42.007Z] 19:12:42     INFO - assert_bfcached@http://web-platform.test:8000/html/browsers/browsing-the-web/back-forward-cache/resources/helper.sub.js:30:29
[task 2021-11-02T19:12:42.007Z] 19:12:42     INFO - TEST-UNEXPECTED-TIMEOUT | /html/browsers/browsing-the-web/back-forward-cache/events.html | unload - Test timed out
[task 2021-11-02T19:12:42.007Z] 19:12:42     INFO - TEST-INFO | expected PRECONDITION_FAILED
[task 2021-11-02T19:12:42.008Z] 19:12:42     INFO - TEST-UNEXPECTED-TIMEOUT | /html/browsers/browsing-the-web/back-forward-cache/events.html | expected OK
[task 2021-11-02T19:12:42.008Z] 19:12:42     INFO - TEST-INFO took 120704ms
[task 2021-11-02T19:12:42.009Z] 19:12:42     INFO - PID 19556 | 1635880362009	Marionette	INFO	Stopped listening on port 39976
[task 2021-11-02T19:12:42.092Z] 19:12:42     INFO - PID 19556 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-11-02T19:12:52.022Z] 19:12:52     INFO - PID 19556 | 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:2634","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-02T19:13:33.105Z] 19:13:33     INFO - PID 19556 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-11-02T19:13:33.108Z] 19:13:33     INFO - PID 19556 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-11-02T19:13:33.109Z] 19:13:33     INFO - PID 19556 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-11-02T19:13:33.110Z] 19:13:33     INFO - PID 19556 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-11-02T19:13:33.111Z] 19:13:33     INFO - PID 19556 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-11-02T19:13:33.116Z] 19:13:33     INFO - PID 19556 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-11-02T19:13:43.193Z] 19:13:43     INFO - PID 19556 | WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"ContentParent: id=7fb0a5f26000","state":{"remoteTypePrefix":"webIsolated"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3514,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7fb0a5f21800","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3514,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7fb0b2204c00","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3514,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7fb0b2207600","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3514,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]}] Barrier: profile-before-change
[task 2021-11-02T19:13:48.094Z] 19:13:48     INFO - PID 19556 | console.error: (new Error("SessionFile is closed", "resource:///modules/sessionstore/SessionFile.jsm", 446))
[task 2021-11-02T19:13:53.032Z] 19:13:53     INFO - PID 19556 | [Parent 19556, IPC I/O Parent] WARNING: FileDescriptorSet destroyed with unconsumed descriptors: file /builds/worker/checkouts/gecko/ipc/chromium/src/chrome/common/file_descriptor_set_posix.cc:19
[task 2021-11-02T19:13:53.033Z] 19:13:53     INFO - PID 19556 | [Parent 19556, IPC I/O Parent] WARNING: FileDescriptorSet destroyed with unconsumed descriptors: file /builds/worker/checkouts/gecko/ipc/chromium/src/chrome/common/file_descriptor_set_posix.cc:19
[task 2021-11-02T19:13:53.034Z] 19:13:53     INFO - PID 19556 | [Parent 19556, IPC I/O Parent] WARNING: FileDescriptorSet destroyed with unconsumed descriptors: file /builds/worker/checkouts/gecko/ipc/chromium/src/chrome/common/file_descriptor_set_posix.cc:19
[task 2021-11-02T19:13:53.034Z] 19:13:53     INFO - PID 19556 | [Parent 19556, IPC I/O Parent] WARNING: FileDescriptorSet destroyed with unconsumed descriptors: file /builds/worker/checkouts/gecko/ipc/chromium/src/chrome/common/file_descriptor_set_posix.cc:19
[task 2021-11-02T19:13:53.034Z] 19:13:53     INFO - PID 19556 | [Parent 19556, IPC I/O Parent] WARNING: FileDescriptorSet destroyed with unconsumed descriptors: file /builds/worker/checkouts/gecko/ipc/chromium/src/chrome/common/file_descriptor_set_posix.cc:19
[task 2021-11-02T19:13:53.034Z] 19:13:53     INFO - PID 19556 | [Parent 19556, IPC I/O Parent] WARNING: FileDescriptorSet destroyed with unconsumed descriptors: file /builds/worker/checkouts/gecko/ipc/chromium/src/chrome/common/file_descriptor_set_posix.cc:19
[task 2021-11-02T19:13:53.034Z] 19:13:53     INFO - PID 19556 | [Parent 19556, IPC I/O Parent] WARNING: FileDescriptorSet destroyed with unconsumed descriptors: file /builds/worker/checkouts/gecko/ipc/chromium/src/chrome/common/file_descriptor_set_posix.cc:19
[task 2021-11-02T19:13:53.034Z] 19:13:53     INFO - PID 19556 | [Parent 19556, IPC I/O Parent] WARNING: FileDescriptorSet destroyed with unconsumed descriptors: file /builds/worker/checkouts/gecko/ipc/chromium/src/chrome/common/file_descriptor_set_posix.cc:19
[task 2021-11-02T19:13:53.056Z] 19:13:53     INFO - Browser exited with return code -15
[task 2021-11-02T19:13:53.058Z] 19:13:53     INFO - Closing logging queue
[task 2021-11-02T19:13:53.059Z] 19:13:53     INFO - queue closed
[task 2021-11-02T19:13:53.075Z] 19:13:53     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmpy___7p5r
[task 2021-11-02T19:13:53.092Z] 19:13:53     INFO - PID 19946 | [CodeCoverage] Setting handlers for process 19946.
[task 2021-11-02T19:13:53.092Z] 19:13:53     INFO - PID 19946 | 1635880241456	Marionette	INFO	Marionette enabled
[task 2021-11-02T19:13:53.093Z] 19:13:53     INFO - PID 19946 | [CodeCoverage] Setting handlers for process 20106.
[task 2021-11-02T19:13:53.093Z] 19:13:53     INFO - PID 19946 | [CodeCoverage] Setting handlers for process 20109.
[task 2021-11-02T19:13:53.094Z] 19:13:53     INFO - PID 19946 | [CodeCoverage] Setting handlers for process 20167.
[task 2021-11-02T19:13:53.094Z] 19:13:53     INFO - PID 19946 | [CodeCoverage] Setting handlers for process 20169.
[task 2021-11-02T19:13:53.095Z] 19:13:53     INFO - PID 19946 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/tmpy8_mofk8/search.json.mozlz4", (void 0)))
[task 2021-11-02T19:13:53.095Z] 19:13:53     INFO - PID 19946 | 1635880247050	Marionette	INFO	Listening on port 58279
[task 2021-11-02T19:13:53.096Z] 19:13:53     INFO - Starting runner
INFO - TEST-START | /html/browsers/browsing-the-web/history-traversal/001.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
You need to log in before you can comment on or make changes to this bug.