Closed Bug 1663899 Opened 4 years ago Closed 4 years ago

Intermittent browser/components/enterprisepolicies/tests/browser/managedbookmarks/browser_policy_managedbookmarks.js | Test timed out -

Categories

(Firefox :: Enterprise Policies, defect, P5)

Product:
Firefox
Component:
Enterprise Policies
Type:
defect

Tracking

()

Status:
RESOLVED FIXED
Milestone:
82 Branch
Tracking Flags:
Tracking Status
firefox-esr68 --- unaffected
firefox-esr78 --- unaffected
firefox80 --- unaffected
firefox81 --- unaffected
firefox82 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: mkaply)

References

(Regression)

Details

(Keywords: intermittent-failure, regression)

Attachments

(1 file)

Bug 1663899 - make creation of the popuphidden promise in managed bookmarks test happen earlier, r?Gijs!
47 bytes, text/x-phabricator-request
Details | Review

Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=315186266&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/BapulY0-Rkmlx3WjtG29kg/runs/0/artifacts/public/logs/live_backing.log

[task 2020-09-09T09:50:36.196Z] 09:50:36 INFO - TEST-START | browser/components/enterprisepolicies/tests/browser/managedbookmarks/browser_policy_managedbookmarks.js
[task 2020-09-09T09:50:36.196Z] 09:50:36 INFO - GECKO(4001) | Chrome file doesn't exist: /builds/worker/workspace/build/tests/mochitest/browser/browser/components/enterprisepolicies/tests/browser/managedbookmarks/head.js
[task 2020-09-09T09:50:36.381Z] 09:50:36 INFO - GECKO(4001) | [4310, Unnamed thread 7f0b4f45d5e0] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp, line 202
[task 2020-09-09T09:50:36.382Z] 09:50:36 INFO - GECKO(4001) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpv76i0U.mozrunner/runtests_leaks.log
[task 2020-09-09T09:50:36.383Z] 09:50:36 INFO - GECKO(4001) | [4310, Unnamed thread 7f0b4f45d5e0] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp, line 202
[task 2020-09-09T09:50:36.383Z] 09:50:36 INFO - GECKO(4001) | [4310, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp, line 246
[task 2020-09-09T09:50:36.492Z] 09:50:36 INFO - GECKO(4001) | [Child 4310, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp, line 626
[task 2020-09-09T09:50:39.200Z] 09:50:39 INFO - GECKO(4001) | [Child 4156: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fd4a5ce7400 == 0 [pid = 4156] [id = {9a599f3e-4e53-47e8-b460-15c63810d4c1}] [url = about:blank]
[task 2020-09-09T09:50:43.320Z] 09:50:43 INFO - GECKO(4001) | [Child 4156: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (0x7fd49f947c00) [pid = 4156] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2020-09-09T09:50:43.379Z] 09:50:43 INFO - GECKO(4001) | [Child 4156: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x7fd4bbe93520) [pid = 4156] [serial = 1] [outer = (nil)] [url = about:blank]
[task 2020-09-09T09:50:43.421Z] 09:50:43 INFO - GECKO(4001) | [Parent 4001: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (0x7fc4aa20a400) [pid = 4001] [serial = 4] [outer = (nil)] [url = about:blank]
[task 2020-09-09T09:50:43.422Z] 09:50:43 INFO - GECKO(4001) | [Parent 4001: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (0x7fc4a85c1800) [pid = 4001] [serial = 9] [outer = (nil)] [url = about:blank]
[task 2020-09-09T09:50:43.999Z] 09:50:43 INFO - GECKO(4001) | [Child 4175: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (0x7f5acd93b800) [pid = 4175] [serial = 8] [outer = (nil)] [url = about:blank]
[task 2020-09-09T09:50:44.000Z] 09:50:44 INFO - GECKO(4001) | [Child 4175: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (0x7f5acd6b4c00) [pid = 4175] [serial = 6] [outer = (nil)] [url = about:blank]
[task 2020-09-09T09:50:44.001Z] 09:50:44 INFO - GECKO(4001) | [Child 4175: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (0x7f5acd6b2800) [pid = 4175] [serial = 4] [outer = (nil)] [url = about:blank]
[task 2020-09-09T09:50:44.001Z] 09:50:44 INFO - GECKO(4001) | [Child 4175: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (0x7f5acd650400) [pid = 4175] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2020-09-09T09:50:44.056Z] 09:50:44 INFO - GECKO(4001) | [Child 4175: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (0x7f5ae9c936f0) [pid = 4175] [serial = 3] [outer = (nil)] [url = moz-extension://839bc286-00f2-4ac2-8164-5e0cdf118f6b/_generated_background_page.html]
[task 2020-09-09T09:50:44.057Z] 09:50:44 INFO - GECKO(4001) | [Child 4175: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7f5acd655400 == 3 [pid = 4175] [id = {3760c86a-0af1-4c7c-8b02-a403319ad1fe}] [url = moz-extension://839bc286-00f2-4ac2-8164-5e0cdf118f6b/_generated_background_page.html]
[task 2020-09-09T09:50:47.349Z] 09:50:47 INFO - GECKO(4001) | [Child 4156: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7fd49fc21400) [pid = 4156] [serial = 3] [outer = (nil)] [url = about:blank]
[task 2020-09-09T09:50:47.350Z] 09:50:47 INFO - GECKO(4001) | [Child 4156: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x7fd49f99c400) [pid = 4156] [serial = 4] [outer = (nil)] [url = about:blank]
[task 2020-09-09T09:50:47.410Z] 09:50:47 INFO - GECKO(4001) | [Child 4236: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x7f8139849000) [pid = 4236] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2020-09-09T09:50:48.096Z] 09:50:48 INFO - GECKO(4001) | [Child 4175: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (0x7f5acd64fc00) [pid = 4175] [serial = 10] [outer = (nil)] [url = moz-extension://839bc286-00f2-4ac2-8164-5e0cdf118f6b/_generated_background_page.html]
[task 2020-09-09T09:52:06.239Z] 09:52:06 INFO - TEST-INFO | started process screentopng
[task 2020-09-09T09:52:06.492Z] 09:52:06 INFO - TEST-INFO | screentopng: exit 0
[task 2020-09-09T09:52:06.492Z] 09:52:06 INFO - Buffered messages logged at 09:50:36
[task 2020-09-09T09:52:06.492Z] 09:52:06 INFO - Entering test bound test_policy_managedbookmarks
[task 2020-09-09T09:52:06.492Z] 09:52:06 INFO - TEST-PASS | browser/components/enterprisepolicies/tests/browser/managedbookmarks/browser_policy_managedbookmarks.js | Managed bookmarks button should be visible. -
[task 2020-09-09T09:52:06.492Z] 09:52:06 INFO - TEST-PASS | browser/components/enterprisepolicies/tests/browser/managedbookmarks/browser_policy_managedbookmarks.js | Managed bookmarks buttons should have correct label -
[task 2020-09-09T09:52:06.492Z] 09:52:06 INFO - TEST-PASS | browser/components/enterprisepolicies/tests/browser/managedbookmarks/browser_policy_managedbookmarks.js | Bookmark should have correct label -
[task 2020-09-09T09:52:06.492Z] 09:52:06 INFO - TEST-PASS | browser/components/enterprisepolicies/tests/browser/managedbookmarks/browser_policy_managedbookmarks.js | Bookmark should have correct link -
[task 2020-09-09T09:52:06.492Z] 09:52:06 INFO - TEST-PASS | browser/components/enterprisepolicies/tests/browser/managedbookmarks/browser_policy_managedbookmarks.js | Bookmark should have correct label -
[task 2020-09-09T09:52:06.492Z] 09:52:06 INFO - TEST-PASS | browser/components/enterprisepolicies/tests/browser/managedbookmarks/browser_policy_managedbookmarks.js | Bookmark should have correct link -
[task 2020-09-09T09:52:06.492Z] 09:52:06 INFO - TEST-PASS | browser/components/enterprisepolicies/tests/browser/managedbookmarks/browser_policy_managedbookmarks.js | Subfolder should have correct label -
[task 2020-09-09T09:52:06.492Z] 09:52:06 INFO - TEST-PASS | browser/components/enterprisepolicies/tests/browser/managedbookmarks/browser_policy_managedbookmarks.js | Bookmark should have correct label -
[task 2020-09-09T09:52:06.492Z] 09:52:06 INFO - TEST-PASS | browser/components/enterprisepolicies/tests/browser/managedbookmarks/browser_policy_managedbookmarks.js | Bookmark should have correct link -
[task 2020-09-09T09:52:06.504Z] 09:52:06 INFO - TEST-PASS | browser/components/enterprisepolicies/tests/browser/managedbookmarks/browser_policy_managedbookmarks.js | Bookmark should have correct link -
[task 2020-09-09T09:52:06.504Z] 09:52:06 INFO - TEST-PASS | browser/components/enterprisepolicies/tests/browser/managedbookmarks/browser_policy_managedbookmarks.js | Bookmark should have correct label -
[task 2020-09-09T09:52:06.510Z] 09:52:06 INFO - TEST-PASS | browser/components/enterprisepolicies/tests/browser/managedbookmarks/browser_policy_managedbookmarks.js | Bookmark should have correct label -
[task 2020-09-09T09:52:06.510Z] 09:52:06 INFO - TEST-PASS | browser/components/enterprisepolicies/tests/browser/managedbookmarks/browser_policy_managedbookmarks.js | Bookmark should have correct link -
[task 2020-09-09T09:52:06.511Z] 09:52:06 INFO - TEST-PASS | browser/components/enterprisepolicies/tests/browser/managedbookmarks/browser_policy_managedbookmarks.js | Bookmark should have correct link -
[task 2020-09-09T09:52:06.511Z] 09:52:06 INFO - TEST-PASS | browser/components/enterprisepolicies/tests/browser/managedbookmarks/browser_policy_managedbookmarks.js | Bookmark should have correct label -
[task 2020-09-09T09:52:06.512Z] 09:52:06 INFO - Buffered messages finished
[task 2020-09-09T09:52:06.519Z] 09:52:06 INFO - TEST-UNEXPECTED-FAIL | browser/components/enterprisepolicies/tests/browser/managedbookmarks/browser_policy_managedbookmarks.js | Test timed out -
[task 2020-09-09T09:52:06.519Z] 09:52:06 INFO - GECKO(4001) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
[task 2020-09-09T09:52:06.520Z] 09:52:06 INFO - GECKO(4001) | MEMORY STAT | vsize 3278MB | residentFast 302MB | heapAllocated 69MB
[task 2020-09-09T09:52:06.520Z] 09:52:06 INFO - TEST-OK | browser/components/enterprisepolicies/tests/browser/managedbookmarks/browser_policy_managedbookmarks.js | took 90069ms
[task 2020-09-09T09:52:06.521Z] 09:52:06 INFO - GECKO(4001) | [Child 4156: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fd49f947c00 == 1 [pid = 4156] [id = {3b375a2a-1b5b-4fd2-818e-3b5925d4d57b}]
[task 2020-09-09T09:52:06.522Z] 09:52:06 INFO - GECKO(4001) | [Child 4156: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7fd4bbe93180) [pid = 4156] [serial = 5] [outer = (nil)]
[task 2020-09-09T09:52:06.522Z] 09:52:06 INFO - GECKO(4001) | [Child 4156: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7fd49f94d400) [pid = 4156] [serial = 6] [outer = 0x7fd4bbe93180]
[task 2020-09-09T09:52:06.523Z] 09:52:06 INFO - checking window state
[task 2020-09-09T09:52:06.523Z] 09:52:06 INFO - GECKO(4001) | [Parent 4001: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (0x7fc49f59a400) [pid = 4001] [serial = 13] [outer = 0x7fc4a803a9f0]
[task 2020-09-09T09:52:06.524Z] 09:52:06 INFO - GECKO(4001) | [Parent 4001, Main Thread] WARNING: NS_ENSURE_TRUE(presShell) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp, line 4269
[task 2020-09-09T09:52:06.632Z] 09:52:06 INFO - GECKO(4001) | [Child 4261: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7f372dde4800 == 0 [pid = 4261] [id = {35f7e937-5987-4164-a1e9-78baa8041d8f}] [url = about:blank]
[task 2020-09-09T09:52:07.060Z] 09:52:07 INFO - GECKO(4001) | [Child 4236: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7f813fbea800 == 0 [pid = 4236] [id = {70d5163a-52e2-497c-88cd-80611f94c01e}] [url = about:newtab]
[task 2020-09-09T09:52:07.060Z] 09:52:07 INFO - GECKO(4001) | [Child 4236: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7f8155e936f0) [pid = 4236] [serial = 1] [outer = (nil)] [url = about:newtab]
[task 2020-09-09T09:52:07.060Z] 09:52:07 INFO - GECKO(4001) | [Child 4236: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x7f81398b1800) [pid = 4236] [serial = 3] [outer = (nil)] [url = about:newtab]
[task 2020-09-09T09:52:08.053Z] 09:52:08 INFO - GECKO(4001) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-09-09T09:52:08.162Z] 09:52:08 INFO - GECKO(4001) | [Child 4310, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-09-09T09:52:08.230Z] 09:52:08 INFO - GECKO(4001) | [Child 4310, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp, line 4286
[task 2020-09-09T09:52:08.438Z] 09:52:08 INFO - GECKO(4001) | [Child 4261: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7f3727a54c00) [pid = 4261] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2020-09-09T09:52:08.438Z] 09:52:08 INFO - GECKO(4001) | [Child 4261: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x7f3744093520) [pid = 4261] [serial = 1] [outer = (nil)] [url = about:blank]
[task 2020-09-09T09:52:09.632Z] 09:52:09 INFO - GECKO(4001) | Completed ShutdownLeaks collections in process 4156
[task 2020-09-09T09:52:09.721Z] 09:52:09 INFO - GECKO(4001) | Completed ShutdownLeaks collections in process 4261
[task 2020-09-09T09:52:09.749Z] 09:52:09 INFO - GECKO(4001) | Completed ShutdownLeaks collections in process 4236
[task 2020-09-09T09:52:10.069Z] 09:52:10 INFO - GECKO(4001) | Completed ShutdownLeaks collections in process 4175
[task 2020-09-09T09:52:10.150Z] 09:52:10 INFO - GECKO(4001) | [Parent 4001: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (0x7fc4a720c800) [pid = 4001] [serial = 7] [outer = (nil)] [url = about:blank]
[task 2020-09-09T09:52:10.668Z] 09:52:10 INFO - GECKO(4001) | [Parent 4001: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (0x7fc49f54c400) [pid = 4001] [serial = 14] [outer = 0x7fc4a803a9f0]
[task 2020-09-09T09:52:12.994Z] 09:52:12 INFO - GECKO(4001) | [Parent 4001: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (0x7fc49f59a400) [pid = 4001] [serial = 13] [outer = (nil)] [url = about:blank]
[task 2020-09-09T09:52:13.498Z] 09:52:13 INFO - GECKO(4001) | Completed ShutdownLeaks collections in process 4001
[task 2020-09-09T09:52:13.498Z] 09:52:13 INFO - TEST-START | Shutdown

Set release status flags based on info from the regressing bug 1498688

status-firefox80: --- → unaffected
status-firefox81: --- → unaffected
status-firefox82: --- → affected
status-firefox-esr68: --- → unaffected
status-firefox-esr78: --- → unaffected
Assignee: nobody → mozilla
Status: NEW → ASSIGNED
Attachment #9174773 - Attachment description: Bug 1663899 - Explicitly set label for managed bookmarks subfolder. r?Gijs! → Bug 1663899 - make creation of the popuphidden promise in managed bookmarks test happen earlier, r?Gijs!
Pushed by mozilla@kaply.com: https://hg.mozilla.org/integration/autoland/rev/a5542ff1e69e make creation of the popuphidden promise in managed bookmarks test happen earlier, r=Gijs

https://hg.mozilla.org/mozilla-central/rev/a5542ff1e69e

Status: ASSIGNED → RESOLVED
Closed: 4 years ago
status-firefox82: affected → fixed
Resolution: --- → FIXED
Target Milestone: --- → 82 Branch
See Also: → 1664852
Has Regression Range: --- → yes
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: