Closed Bug 1686276 Opened 4 years ago Closed 3 years ago

Intermittent toolkit/components/windowcreator/test/browser_persist.js | Test timed out -

Categories

(Core :: Window Management, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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


[task 2021-01-12T11:38:05.411Z] 11:38:05     INFO - TEST-INFO | started process screencapture
[task 2021-01-12T11:38:05.498Z] 11:38:05     INFO - TEST-INFO | screencapture: exit 0
[task 2021-01-12T11:38:05.498Z] 11:38:05     INFO - Buffered messages logged at 11:36:35
[task 2021-01-12T11:38:05.498Z] 11:38:05     INFO - Entering test bound 
[task 2021-01-12T11:38:05.498Z] 11:38:05     INFO - Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "https://example.com/browser/toolkit/components/windowcreator/test/file_persist_srcset.html" line: 0}]
[task 2021-01-12T11:38:05.499Z] 11:38:05     INFO - snapshotting reference
[task 2021-01-12T11:38:05.499Z] 11:38:05     INFO - starting persistence
[task 2021-01-12T11:38:05.499Z] 11:38:05     INFO - persisting document
[task 2021-01-12T11:38:05.499Z] 11:38:05     INFO - load done, loading persisted document
[task 2021-01-12T11:38:05.499Z] 11:38:05     INFO - Buffered messages finished
[task 2021-01-12T11:38:05.500Z] 11:38:05     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/windowcreator/test/browser_persist.js | Test timed out - 
[task 2021-01-12T11:38:05.500Z] 11:38:05     INFO - GECKO(3318) | MEMORY STAT | vsize 7745MB | residentFast 327MB | heapAllocated 99MB
[task 2021-01-12T11:38:05.500Z] 11:38:05     INFO - TEST-OK | toolkit/components/windowcreator/test/browser_persist.js | took 90110ms
[task 2021-01-12T11:38:05.500Z] 11:38:05     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-01-12T11:38:05.501Z] 11:38:05     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/windowcreator/test/browser_persist.js | Found a tab after previous test timed out: file:///var/folders/v5/wp94s3pj29zchdflgdrn86fw000017/T/file_persist_srcset.html_saved.html - 
[task 2021-01-12T11:38:05.501Z] 11:38:05     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-01-12T11:38:05.501Z] 11:38:05     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/windowcreator/test/browser_persist.js | Found a tab after previous test timed out: https://example.com/browser/toolkit/components/windowcreator/test/file_persist_srcset.html - 
[task 2021-01-12T11:38:05.525Z] 11:38:05     INFO - GECKO(3318) | [Child 3323: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x118f48c00 == 1 [pid = 3323] [id = 1]
[task 2021-01-12T11:38:05.525Z] 11:38:05     INFO - GECKO(3318) | [Child 3323: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x111821e40) [pid = 3323] [serial = 3] [outer = 0x0]
[task 2021-01-12T11:38:05.525Z] 11:38:05     INFO - GECKO(3318) | [Child 3323: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x118fea000) [pid = 3323] [serial = 4] [outer = 0x111821e40]
[task 2021-01-12T11:38:05.558Z] 11:38:05     INFO - checking window state
[task 2021-01-12T11:38:05.627Z] 11:38:05     INFO - TEST-START | toolkit/components/windowcreator/test/browser_save_form_input_state.js
[task 2021-01-12T11:38:05.627Z] 11:38:05     INFO - GECKO(3318) | Chrome file doesn't exist: /Users/cltbld/tasks/task_1610442745/build/tests/mochitest/browser/toolkit/components/windowcreator/test/head.js
[task 2021-01-12T11:38:05.627Z] 11:38:05     INFO - GECKO(3318) | [Child 3320: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x10fc08400 == 2 [pid = 3320] [id = 18]
[task 2021-01-12T11:38:05.627Z] 11:38:05     INFO - GECKO(3318) | [Child 3320: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x10f221e40) [pid = 3320] [serial = 47] [outer = 0x0]
[task 2021-01-12T11:38:05.627Z] 11:38:05     INFO - GECKO(3318) | [Child 3320: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (0x10fc16400) [pid = 3320] [serial = 48] [outer = 0x10f221e40]
[task 2021-01-12T11:38:05.689Z] 11:38:05     INFO - GECKO(3318) | [Child 3326, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/checkouts/gecko/widget/cocoa/nsAppShell.mm:696
[task 2021-01-12T11:38:05.689Z] 11:38:05     INFO - GECKO(3318) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-01-12T11:38:05.689Z] 11:38:05     INFO - GECKO(3318) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-01-12T11:38:05.767Z] 11:38:05     INFO - GECKO(3318) | [Child 3320, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp:348
[task 2021-01-12T11:38:05.767Z] 11:38:05     INFO - GECKO(3318) | [Child 3320: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (0x1167dfc00) [pid = 3320] [serial = 49] [outer = 0x10f221e40]
[task 2021-01-12T11:38:05.767Z] 11:38:05     INFO - GECKO(3318) | [Child 3326: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x102654c00 == 0 [pid = 3326] [id = 0] [url = file:///var/folders/v5/wp94s3pj29zchdflgdrn86fw000017/T/file_persist_srcset.html_saved.html]
[task 2021-01-12T11:38:05.767Z] 11:38:05     INFO - GECKO(3318) | [Child 3326: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x101021e40) [pid = 3326] [serial = 1] [outer = 0x0] [url = about:neterror?e=fileNotFound&u=file%3A///var/folders/v5/wp94s3pj29zchdflgdrn86fw000017/T/file_persist_srcset.html_saved.html&c=UTF-8&d=Firefox%20can%E2%80%99t%20find%20the%20file%20at%20/var/folders/v5/wp94s3pj29zchdflgdrn86fw000017/T/file_persist_srcset.html_saved.html.]
[task 2021-01-12T11:38:05.768Z] 11:38:05     INFO - GECKO(3318) | [Child 3326: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x101a17400) [pid = 3326] [serial = 3] [outer = 0x0] [url = about:neterror?e=fileNotFound&u=file%3A///var/folders/v5/wp94s3pj29zchdflgdrn86fw000017/T/file_persist_srcset.html_saved.html&c=UTF-8&d=Firefox%20can%E2%80%99t%20find%20the%20file%20at%20/var/folders/v5/wp94s3pj29zchdflgdrn86fw000017/T/file_persist_srcset.html_saved.html.]
[task 2021-01-12T11:38:05.768Z] 11:38:05     INFO - GECKO(3318) | [Child 3326, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3364
[task 2021-01-12T11:38:05.768Z] 11:38:05     INFO - GECKO(3318) | [Child 3326, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4378
[task 2021-01-12T11:38:06.513Z] 11:38:06     INFO - GECKO(3318) | [3346, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:202
[task 2021-01-12T11:38:06.513Z] 11:38:06     INFO - GECKO(3318) | [3346, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:202
[task 2021-01-12T11:38:06.514Z] 11:38:06     INFO - GECKO(3318) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/v5/wp94s3pj29zchdflgdrn86fw000017/T/tmptpryHe.mozrunner/runtests_leaks_tab_pid3346.log
[task 2021-01-12T11:38:06.514Z] 11:38:06     INFO - GECKO(3318) | [3346, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:202
[task 2021-01-12T11:38:06.514Z] 11:38:06     INFO - GECKO(3318) | [3346, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:202
[task 2021-01-12T11:38:06.514Z] 11:38:06     INFO - GECKO(3318) | [3346, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:251
[task 2021-01-12T11:38:06.727Z] 11:38:06     INFO - GECKO(3318) | [Child 3346: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x108354c00 == 1 [pid = 3346] [id = 0]
[task 2021-01-12T11:38:06.727Z] 11:38:06     INFO - GECKO(3318) | [Child 3346: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x103921e40) [pid = 3346] [serial = 1] [outer = 0x0]
[task 2021-01-12T11:38:06.728Z] 11:38:06     INFO - GECKO(3318) | [Child 3346: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x1083cd800) [pid = 3346] [serial = 2] [outer = 0x103921e40]
[task 2021-01-12T11:38:06.775Z] 11:38:06     INFO - GECKO(3318) | [Child 3346: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x1083d5800) [pid = 3346] [serial = 3] [outer = 0x103921e40]
[task 2021-01-12T11:38:07.344Z] 11:38:07     INFO - GECKO(3318) | [Child 3346, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/checkouts/gecko/widget/cocoa/nsAppShell.mm:696```
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 4 years ago3 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.