Filed by: nerli [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=292277097&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/HDSQdgHrTniSl2Epdr7lqw/runs/0/artifacts/public/logs/live_backing.log
[task 2020-03-09T15:50:18.666Z] 15:50:18 INFO - TEST-START | /fetch/metadata/window-open.https.sub.html
[task 2020-03-09T15:50:18.688Z] 15:50:18 INFO - Closing window 15032385553
[task 2020-03-09T15:50:20.971Z] 15:50:20 INFO - PID 13501 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpXde_UY.mozrunner/runtests_leaks_979_tab_pid14138.log
[task 2020-03-09T15:50:21.100Z] 15:50:21 INFO - PID 13501 | [Child 14138, Main Thread] WARNING: No CID found when attempting to map contract ID: file /builds/worker/checkouts/gecko/xpcom/components/nsComponentManager.cpp, line 721
[task 2020-03-09T15:50:21.325Z] 15:50:21 INFO - PID 13501 | [Child 14031, Main Thread] WARNING: NS_ENSURE_TRUE(request) failed: file /builds/worker/checkouts/gecko/netwerk/base/nsLoadGroup.cpp, line 584
[task 2020-03-09T15:50:21.455Z] 15:50:21 INFO - PID 13501 | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2020-03-09T15:50:21.678Z] 15:50:21 INFO - PID 13501 | [Child 14138, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/checkouts/gecko/dom/ipc/ContentChild.cpp, line 1808
[task 2020-03-09T15:50:21.735Z] 15:50:21 INFO - PID 13501 | [Child 14138, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/base/nsPresContext.cpp, line 839
[task 2020-03-09T15:50:22.609Z] 15:50:22 INFO - PID 13501 | [Child 14031, Main Thread] WARNING: NS_ENSURE_TRUE(request) failed: file /builds/worker/checkouts/gecko/netwerk/base/nsLoadGroup.cpp, line 584
[task 2020-03-09T15:50:23.710Z] 15:50:23 INFO - PID 13501 | [Child 14138, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3352
[task 2020-03-09T15:50:23.809Z] 15:50:23 INFO - PID 13501 | nsStringStats
[task 2020-03-09T15:50:23.811Z] 15:50:23 INFO - PID 13501 | => mAllocCount: 9815
[task 2020-03-09T15:50:23.812Z] 15:50:23 INFO - PID 13501 | => mReallocCount: 0
[task 2020-03-09T15:50:23.813Z] 15:50:23 INFO - PID 13501 | => mFreeCount: 9815
[task 2020-03-09T15:50:23.814Z] 15:50:23 INFO - PID 13501 | => mShareCount: 9451
[task 2020-03-09T15:50:23.817Z] 15:50:23 INFO - PID 13501 | => mAdoptCount: 393
[task 2020-03-09T15:50:23.818Z] 15:50:23 INFO - PID 13501 | => mAdoptFreeCount: 401
[task 2020-03-09T15:50:23.819Z] 15:50:23 INFO - PID 13501 | => Process ID: 14138, Thread ID: 139890713769856
[task 2020-03-09T15:50:25.922Z] 15:50:25 INFO - PID 13501 | [Child 14031, Main Thread] WARNING: NS_ENSURE_TRUE(request) failed: file /builds/worker/checkouts/gecko/netwerk/base/nsLoadGroup.cpp, line 584
[task 2020-03-09T15:50:26.019Z] 15:50:26 INFO - PID 13501 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpXde_UY.mozrunner/runtests_leaks_979_tab_pid14165.log
[task 2020-03-09T15:50:26.233Z] 15:50:26 INFO - PID 13501 | [Child 14165, Main Thread] WARNING: No CID found when attempting to map contract ID: file /builds/worker/checkouts/gecko/xpcom/components/nsComponentManager.cpp, line 721
[task 2020-03-09T15:50:26.436Z] 15:50:26 INFO - PID 13501 | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2020-03-09T15:50:26.679Z] 15:50:26 INFO - PID 13501 | [Child 14165, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/checkouts/gecko/dom/ipc/ContentChild.cpp, line 1808
[task 2020-03-09T15:50:26.740Z] 15:50:26 INFO - PID 13501 | [Child 14165, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/base/nsPresContext.cpp, line 839
[task 2020-03-09T15:50:27.811Z] 15:50:27 INFO - PID 13501 | [Child 14165, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3352
[task 2020-03-09T15:50:27.849Z] 15:50:27 INFO - PID 13501 | nsStringStats
[task 2020-03-09T15:50:27.850Z] 15:50:27 INFO - PID 13501 | => mAllocCount: 8620
[task 2020-03-09T15:50:27.850Z] 15:50:27 INFO - PID 13501 | => mReallocCount: 0
[task 2020-03-09T15:50:27.851Z] 15:50:27 INFO - PID 13501 | => mFreeCount: 8620
[task 2020-03-09T15:50:27.852Z] 15:50:27 INFO - PID 13501 | => mShareCount: 7360
[task 2020-03-09T15:50:27.852Z] 15:50:27 INFO - PID 13501 | => mAdoptCount: 386
[task 2020-03-09T15:50:27.853Z] 15:50:27 INFO - PID 13501 | => mAdoptFreeCount: 390
[task 2020-03-09T15:50:27.853Z] 15:50:27 INFO - PID 13501 | => Process ID: 14165, Thread ID: 139726464935808
[task 2020-03-09T15:50:27.899Z] 15:50:27 INFO - TEST-UNEXPECTED-ERROR | /fetch/metadata/window-open.https.sub.html | 4 duplicate test names: "Same-origin window, forced, reloaded: sec-fetch-dest", "Same-origin window, forced, reloaded: sec-fetch-mode", "Same-origin window, forced, reloaded: sec-fetch-site", "Same-origin window, forced, reloaded: sec-fetch-user"
[task 2020-03-09T15:50:27.899Z] 15:50:27 INFO - TEST-INFO took 9198ms
[task 2020-03-09T15:50:27.900Z] 15:50:27 INFO - PID 13501 | 1583769027889 Marionette INFO Stopped listening on port 55777
[task 2020-03-09T15:50:28.250Z] 15:50:28 INFO - PID 13501 | JavaScript error: chrome://marionette/content/listener.js, line 378: SecurityError: Permission denied to access property "windowUtils" on cross-origin object
[task 2020-03-09T15:50:28.257Z] 15:50:28 INFO - PID 13501 | [GPU 13550, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp, line 428
[task 2020-03-09T15:50:28.320Z] 15:50:28 INFO - PID 13501 | [Child 14031, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp, line 477
[task 2020-03-09T15:50:28.348Z] 15:50:28 INFO - PID 13501 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-03-09T15:50:28.417Z] 15:50:28 INFO - PID 13501 | [2020-03-09T15:50:28Z WARN xulstore::persist] tried to remove key that isn't in the store
[task 2020-03-09T15:50:28.418Z] 15:50:28 INFO - PID 13501 | [2020-03-09T15:50:28Z WARN xulstore::persist] tried to remove key that isn't in the store
[task 2020-03-09T15:50:28.615Z] 15:50:28 INFO - PID 13501 | [Child 13593, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3352
[task 2020-03-09T15:50:28.652Z] 15:50:28 INFO - PID 13501 | nsStringStats
[task 2020-03-09T15:50:28.653Z] 15:50:28 INFO - PID 13501 | => mAllocCount: 23258
[task 2020-03-09T15:50:28.653Z] 15:50:28 INFO - PID 13501 | => mReallocCount: 0
[task 2020-03-09T15:50:28.653Z] 15:50:28 INFO - PID 13501 | => mFreeCount: 23258
[task 2020-03-09T15:50:28.653Z] 15:50:28 INFO - PID 13501 | => mShareCount: 11839
[task 2020-03-09T15:50:28.654Z] 15:50:28 INFO - PID 13501 | => mAdoptCount: 452
[task 2020-03-09T15:50:28.654Z] 15:50:28 INFO - PID 13501 | => mAdoptFreeCount: 484
[task 2020-03-09T15:50:28.654Z] 15:50:28 INFO - PID 13501 | => Process ID: 13593, Thread ID: 140410629306240
[task 2020-03-09T15:50:28.831Z] 15:50:28 INFO - PID 13501 | [Child 14031, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3352
[task 2020-03-09T15:50:28.852Z] 15:50:28 INFO - PID 13501 | nsStringStats
[task 2020-03-09T15:50:28.854Z] 15:50:28 INFO - PID 13501 | => mAllocCount: 80604
[task 2020-03-09T15:50:28.855Z] 15:50:28 INFO - PID 13501 | => mReallocCount: 0
[task 2020-03-09T15:50:28.855Z] 15:50:28 INFO - PID 13501 | => mFreeCount: 80604
[task 2020-03-09T15:50:28.855Z] 15:50:28 INFO - PID 13501 | => mShareCount: 163116
[task 2020-03-09T15:50:28.855Z] 15:50:28 INFO - PID 13501 | => mAdoptCount: 1349
[task 2020-03-09T15:50:28.855Z] 15:50:28 INFO - PID 13501 | => mAdoptFreeCount: 1435
[task 2020-03-09T15:50:28.855Z] 15:50:28 INFO - PID 13501 | => Process ID: 14031, Thread ID: 140021692069760
[task 2020-03-09T15:50:29.046Z] 15:50:29 INFO - PID 13501 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-03-09T15:50:29.046Z] 15:50:29 INFO - PID 13501 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-03-09T15:50:29.102Z] 15:50:29 INFO - PID 13501 | nsStringStats
[task 2020-03-09T15:50:29.103Z] 15:50:29 INFO - PID 13501 | => mAllocCount: 5998
[task 2020-03-09T15:50:29.103Z] 15:50:29 INFO - PID 13501 | => mReallocCount: 0
[task 2020-03-09T15:50:29.103Z] 15:50:29 INFO - PID 13501 | => mFreeCount: 5998
[task 2020-03-09T15:50:29.104Z] 15:50:29 INFO - PID 13501 | => mShareCount: 3068
[task 2020-03-09T15:50:29.105Z] 15:50:29 INFO - PID 13501 | => mAdoptCount: 72
[task 2020-03-09T15:50:29.105Z] 15:50:29 INFO - PID 13501 | => mAdoptFreeCount: 72
[task 2020-03-09T15:50:29.106Z] 15:50:29 INFO - PID 13501 | => Process ID: 13768, Thread ID: 140324095162240
Description
•