Filed by: cbrindusan [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=258442896&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/WR8kM9EsRZSdnJP5z4DWqw/runs/0/artifacts/public/logs/live_backing.log
[task 2019-07-26T04:42:02.898Z] 04:42:02 INFO - TEST-START | browser/base/content/test/fullscreen/browser_bug1557041.js
[task 2019-07-26T04:42:02.995Z] 04:42:02 INFO - GECKO(1675) | [1682, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
[task 2019-07-26T04:42:02.995Z] 04:42:02 INFO - GECKO(1675) | [1682, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
[task 2019-07-26T04:42:02.995Z] 04:42:02 INFO - GECKO(1675) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/f7/3338gtvd45sftydk3hc9d5n0000017/T/tmpzD7QAy.mozrunner/runtests_leaks_tab_pid1682.log
[task 2019-07-26T04:42:02.995Z] 04:42:02 INFO - GECKO(1675) | [1682, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
[task 2019-07-26T04:42:02.996Z] 04:42:02 INFO - GECKO(1675) | [1682, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
[task 2019-07-26T04:42:03.288Z] 04:42:03 INFO - GECKO(1675) | ++DOCSHELL 0x11b109000 == 1 [pid = 1682] [id = {580203fb-f3f2-5f45-812d-b2437bfa3966}]
[task 2019-07-26T04:42:03.288Z] 04:42:03 INFO - GECKO(1675) | ++DOMWINDOW == 1 (0x11b14c020) [pid = 1682] [serial = 1] [outer = 0x0]
[task 2019-07-26T04:42:03.288Z] 04:42:03 INFO - GECKO(1675) | [Child 1682, Main Thread] WARNING: No active window: file /builds/worker/workspace/build/src/js/xpconnect/src/XPCJSContext.cpp, line 664
[task 2019-07-26T04:42:03.368Z] 04:42:03 INFO - GECKO(1675) | ++DOMWINDOW == 2 (0x11b130400) [pid = 1682] [serial = 2] [outer = 0x11b14c020]
[task 2019-07-26T04:42:03.396Z] 04:42:03 INFO - GECKO(1675) | ++DOMWINDOW == 3 (0x11b1ccc00) [pid = 1682] [serial = 3] [outer = 0x11b14c020]
[task 2019-07-26T04:42:03.396Z] 04:42:03 INFO - GECKO(1675) | [Child 1682, Main Thread] WARNING: NS_ENSURE_SUCCESS(mStatus, this) failed with result 0x80004005: file /builds/worker/workspace/build/src/obj-firefox/dist/include/nsIURIMutator.h, line 489
[task 2019-07-26T04:42:03.461Z] 04:42:03 INFO - GECKO(1675) | [Child 1682, Main Thread] WARNING: No active window: file /builds/worker/workspace/build/src/js/xpconnect/src/XPCJSContext.cpp, line 664
[task 2019-07-26T04:42:03.530Z] 04:42:03 INFO - GECKO(1675) | [Parent 1675, URL Classifier] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/workspace/build/src/toolkit/components/url-classifier/LookupCache.cpp, line 882
[task 2019-07-26T04:42:03.530Z] 04:42:03 INFO - GECKO(1675) | [Parent 1675, URL Classifier] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/workspace/build/src/toolkit/components/url-classifier/LookupCache.cpp, line 882
[task 2019-07-26T04:42:03.539Z] 04:42:03 INFO - GECKO(1675) | [Parent 1675, URL Classifier] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/workspace/build/src/toolkit/components/url-classifier/LookupCache.cpp, line 882
[task 2019-07-26T04:42:03.624Z] 04:42:03 INFO - GECKO(1675) | ++DOMWINDOW == 4 (0x11b4d9000) [pid = 1682] [serial = 4] [outer = 0x11b14c020]
[task 2019-07-26T04:42:03.632Z] 04:42:03 INFO - GECKO(1675) | [Parent 1675, Main Thread] WARNING: Constructing RangeBoundary with invalid value: 'mRef || aOffset == 0', file /builds/worker/workspace/build/src/obj-firefox/dist/include/mozilla/RangeBoundary.h, line 79
[task 2019-07-26T04:42:03.632Z] 04:42:03 INFO - GECKO(1675) | [Parent 1675, Main Thread] WARNING: Constructing RangeBoundary with invalid value: 'mRef || aOffset == 0', file /builds/worker/workspace/build/src/obj-firefox/dist/include/mozilla/RangeBoundary.h, line 79
[task 2019-07-26T04:42:03.655Z] 04:42:03 INFO - GECKO(1675) | [Parent 1675, Main Thread] WARNING: Constructing RangeBoundary with invalid value: 'mRef || aOffset == 0', file /builds/worker/workspace/build/src/obj-firefox/dist/include/mozilla/RangeBoundary.h, line 79
[task 2019-07-26T04:42:03.655Z] 04:42:03 INFO - GECKO(1675) | [Parent 1675, Main Thread] WARNING: Constructing RangeBoundary with invalid value: 'mRef || aOffset == 0', file /builds/worker/workspace/build/src/obj-firefox/dist/include/mozilla/RangeBoundary.h, line 79
[task 2019-07-26T04:42:03.656Z] 04:42:03 INFO - GECKO(1675) | [Parent 1675, Main Thread] WARNING: Constructing RangeBoundary with invalid value: 'mRef || aOffset == 0', file /builds/worker/workspace/build/src/obj-firefox/dist/include/mozilla/RangeBoundary.h, line 79
[task 2019-07-26T04:42:03.657Z] 04:42:03 INFO - GECKO(1675) | [Parent 1675, Main Thread] WARNING: Constructing RangeBoundary with invalid value: 'mRef || aOffset == 0', file /builds/worker/workspace/build/src/obj-firefox/dist/include/mozilla/RangeBoundary.h, line 79
[task 2019-07-26T04:42:04.052Z] 04:42:04 INFO - GECKO(1675) | [1683, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
[task 2019-07-26T04:42:04.052Z] 04:42:04 INFO - GECKO(1675) | [1683, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
[task 2019-07-26T04:42:04.052Z] 04:42:04 INFO - GECKO(1675) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/f7/3338gtvd45sftydk3hc9d5n0000017/T/tmpzD7QAy.mozrunner/runtests_leaks_tab_pid1683.log
[task 2019-07-26T04:42:04.052Z] 04:42:04 INFO - GECKO(1675) | [1683, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
[task 2019-07-26T04:42:04.052Z] 04:42:04 INFO - GECKO(1675) | [1683, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
[task 2019-07-26T04:42:04.386Z] 04:42:04 INFO - GECKO(1675) | JavaScript error: , line 0: TypeError: The expression cannot be converted to return the specified type.
[task 2019-07-26T04:42:04.728Z] 04:42:04 INFO - GECKO(1675) | JavaScript error: chrome://browser/content/browser-fullScreenAndPointerLock.js, line 407: Error: TelemetryStopwatch: key "FULLSCREEN_CHANGE_MS" was already initialized
[task 2019-07-26T04:42:08.176Z] 04:42:08 INFO - GECKO(1675) | --DOCSHELL 0x115009000 == 0 [pid = 1678] [id = {b36fbc07-670b-0a44-be8a-dcfcf6d9636c}] [url = about:blank]
[task 2019-07-26T04:42:09.563Z] 04:42:09 INFO - GECKO(1675) | --DOCSHELL 0x11b915000 == 4 [pid = 1677] [id = {b3eb9c86-c0d2-d349-8dbc-2ff24f7b198d}] [url = moz-extension://e0f9cb0e-a58c-2c47-8fee-25493d578ea6/_generated_background_page.html]
[task 2019-07-26T04:42:11.417Z] 04:42:11 INFO - GECKO(1675) | --DOMWINDOW == 12 (0x11f0a7c00) [pid = 1675] [serial = 8] [outer = 0x0] [url = about:blank]
[task 2019-07-26T04:42:12.500Z] 04:42:12 INFO - GECKO(1675) | --DOMWINDOW == 3 (0x11502b400) [pid = 1678] [serial = 2] [outer = 0x0] [url = about:blank]
[task 2019-07-26T04:42:12.519Z] 04:42:12 INFO - GECKO(1675) | --DOMWINDOW == 2 (0x115042020) [pid = 1678] [serial = 1] [outer = 0x0] [url = about:blank]
[task 2019-07-26T04:42:14.026Z] 04:42:14 INFO - GECKO(1675) | --DOMWINDOW == 14 (0x115de2000) [pid = 1677] [serial = 6] [outer = 0x0] [url = about:blank]
[task 2019-07-26T04:42:14.026Z] 04:42:14 INFO - GECKO(1675) | --DOMWINDOW == 13 (0x115de4000) [pid = 1677] [serial = 7] [outer = 0x0] [url = about:blank]
[task 2019-07-26T04:42:14.026Z] 04:42:14 INFO - GECKO(1675) | --DOMWINDOW == 12 (0x115de7400) [pid = 1677] [serial = 9] [outer = 0x0] [url = about:blank]
[task 2019-07-26T04:42:14.026Z] 04:42:14 INFO - GECKO(1675) | --DOMWINDOW == 11 (0x115de8c00) [pid = 1677] [serial = 10] [outer = 0x0] [url = about:blank]
[task 2019-07-26T04:42:14.026Z] 04:42:14 INFO - GECKO(1675) | --DOMWINDOW == 10 (0x115de5c00) [pid = 1677] [serial = 8] [outer = 0x0] [url = about:blank]
[task 2019-07-26T04:42:14.180Z] 04:42:14 INFO - GECKO(1675) | --DOMWINDOW == 9 (0x11b948980) [pid = 1677] [serial = 5] [outer = 0x0] [url = moz-extension://e0f9cb0e-a58c-2c47-8fee-25493d578ea6/_generated_background_page.html]
[task 2019-07-26T04:42:14.956Z] 04:42:14 INFO - GECKO(1675) | --DOMWINDOW == 2 (0x127f29000) [pid = 1681] [serial = 2] [outer = 0x0] [url = about:blank]
[task 2019-07-26T04:42:16.005Z] 04:42:16 INFO - GECKO(1675) | --DOMWINDOW == 3 (0x11b1ccc00) [pid = 1682] [serial = 3] [outer = 0x0] [url = about:blank]
[task 2019-07-26T04:42:16.005Z] 04:42:16 INFO - GECKO(1675) | --DOMWINDOW == 2 (0x11b130400) [pid = 1682] [serial = 2] [outer = 0x0] [url = about:blank][task 2019-07-26T04:42:16.680Z] 04:42:16 INFO - GECKO(1675) | --DOMWINDOW == 1 (0x10dc2a000) [pid = 1678] [serial = 3] [outer = 0x0] [url = about:blank]
[task 2019-07-26T04:42:16.680Z] 04:42:16 INFO - GECKO(1675) | --DOMWINDOW == 0 (0x1255d2c00) [pid = 1678] [serial = 4] [outer = 0x0] [url = about:blank]
[task 2019-07-26T04:42:17.401Z] 04:42:17 INFO - GECKO(1675) | --DOMWINDOW == 2 (0x12652d400) [pid = 1680] [serial = 2] [outer = 0x0] [url = about:blank]
[task 2019-07-26T04:42:18.202Z] 04:42:18 INFO - GECKO(1675) | --DOMWINDOW == 8 (0x11bb73400) [pid = 1677] [serial = 15] [outer = 0x0] [url = moz-extension://e0f9cb0e-a58c-2c47-8fee-25493d578ea6/_generated_background_page.html]
[task 2019-07-26T04:42:20.405Z] 04:42:20 INFO - GECKO(1675) | [Parent 1675, StreamTrans #4] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/modules/libjar/nsJARChannel.cpp, line 371
[task 2019-07-26T04:42:20.405Z] 04:42:20 INFO - GECKO(1675) | [Parent 1675, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file /builds/worker/workspace/build/src/modules/libjar/nsJARChannel.cpp, line 994
[task 2019-07-26T04:42:26.712Z] 04:42:26 INFO - GECKO(1675) | 2019-07-26 04:42:26.684 firefox[1675:8147] Persistent UI failed to open file file:///Users/cltbld/Library/Saved%20Application%20State/org.mozilla.nightlydebug.savedState/window_1.data: No such file or directory (2)
[task 2019-07-26T04:43:33.043Z] 04:43:33 INFO - TEST-INFO | started process screencapture
[task 2019-07-26T04:43:33.198Z] 04:43:33 INFO - TEST-INFO | screencapture: exit 0
[task 2019-07-26T04:43:33.198Z] 04:43:33 INFO - Buffered messages logged at 04:42:02
[task 2019-07-26T04:43:33.198Z] 04:43:33 INFO - Entering test bound test_identityPopupCausesFSExit
[task 2019-07-26T04:43:33.198Z] 04:43:33 INFO - Buffered messages logged at 04:42:03
[task 2019-07-26T04:43:33.198Z] 04:43:33 INFO - Entering DOM fullscreen
[task 2019-07-26T04:43:33.198Z] 04:43:33 INFO - Buffered messages logged at 04:42:04
[task 2019-07-26T04:43:33.198Z] 04:43:33 INFO - Console message: [JavaScript Warning: "Request for fullscreen was denied because requesting element is not in the currently focused tab." {file: "resource:///actors/DOMFullscreenChild.jsm" line: 20}]
[task 2019-07-26T04:43:33.198Z] 04:43:33 INFO - Console message: [JavaScript Error: "TypeError: The expression cannot be converted to return the specified type."]
[task 2019-07-26T04:43:33.198Z] 04:43:33 INFO - TEST-FAIL | browser/base/content/test/fullscreen/browser_bug1557041.js | uncaught exception: Error: TelemetryStopwatch: key "FULLSCREEN_CHANGE_MS" was already initialized at handleEvent@chrome://browser/content/browser-fullScreenAndPointerLock.js:407:28
[task 2019-07-26T04:43:33.198Z] 04:43:33 INFO - EventListener.handleEventinit@chrome://browser/content/browser-fullScreenAndPointerLock.js:274:12
[task 2019-07-26T04:43:33.198Z] 04:43:33 INFO - _delayedStartup@chrome://browser/content/browser.js:2029:16
[task 2019-07-26T04:43:33.198Z] 04:43:33 INFO - EventListener.handleEventonLoad@chrome://browser/content/browser.js:1843:12
[task 2019-07-26T04:43:33.198Z] 04:43:33 INFO - EventHandlerNonNull@chrome://browser/content/browser.xhtml:112:39
[task 2019-07-26T04:43:33.198Z] 04:43:33 INFO - -
[task 2019-07-26T04:43:33.199Z] 04:43:33 INFO - Console message: [JavaScript Error: "Error: TelemetryStopwatch: key "FULLSCREEN_CHANGE_MS" was already initialized" {file: "chrome://browser/content/browser-fullScreenAndPointerLock.js" line: 407}]
[task 2019-07-26T04:43:33.199Z] 04:43:33 INFO - handleEvent@chrome://browser/content/browser-fullScreenAndPointerLock.js:407:28
[task 2019-07-26T04:43:33.199Z] 04:43:33 INFO - EventListener.handleEventinit@chrome://browser/content/browser-fullScreenAndPointerLock.js:274:12
[task 2019-07-26T04:43:33.199Z] 04:43:33 INFO - _delayedStartup@chrome://browser/content/browser.js:2029:16
[task 2019-07-26T04:43:33.199Z] 04:43:33 INFO - EventListener.handleEventonLoad@chrome://browser/content/browser.js:1843:12
[task 2019-07-26T04:43:33.199Z] 04:43:33 INFO - EventHandlerNonNull*@chrome://browser/content/browser.xhtml:112:39
[task 2019-07-26T04:43:33.199Z] 04:43:33 INFO -
[task 2019-07-26T04:43:33.199Z] 04:43:33 INFO - Buffered messages finished
[task 2019-07-26T04:43:33.199Z] 04:43:33 INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/fullscreen/browser_bug1557041.js | Test timed out -
Description
•