Filed by: apavel [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=160874165&repo=mozilla-central https://queue.taskcluster.net/v1/task/ZoMPpTnCRkeYmraGcODhTA/runs/0/artifacts/public/logs/live_backing.log 16:27:53 INFO - 33 INFO TEST-START | devtools/client/jsonview/test/browser_jsonview_encoding.js 16:27:53 INFO - GECKO(5920) | ++DOCSHELL 00000271ECF8D000 == 2 [pid = 5772] [id = {e5a20b71-1907-44be-b623-7cd29e99fd90}] 16:27:53 INFO - GECKO(5920) | ++DOMWINDOW == 11 (00000271E541A660) [pid = 5772] [serial = 27] [outer = 0000000000000000] 16:27:53 INFO - GECKO(5920) | ++DOMWINDOW == 12 (00000271ECFBF800) [pid = 5772] [serial = 28] [outer = 00000271E541A660] 16:27:53 INFO - GECKO(5920) | [Child 4704, Main Thread] WARNING: NS_ENSURE_TRUE(node) failed: file z:/build/build/src/layout/base/nsDocumentViewer.cpp, line 3713 16:27:53 INFO - GECKO(5920) | [Child 5772, Main Thread] WARNING: NS_ENSURE_TRUE(node) failed: file z:/build/build/src/layout/base/nsDocumentViewer.cpp, line 3713 16:27:53 INFO - GECKO(5920) | ++DOMWINDOW == 13 (00000271ED733400) [pid = 5772] [serial = 29] [outer = 00000271E541A660] 16:27:53 INFO - GECKO(5920) | [Child 5772, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file z:/build/build/src/parser/html/nsHtml5StreamParser.cpp, line 1009 16:27:53 INFO - GECKO(5920) | [Child 5772, Main Thread] WARNING: NS_ENSURE_TRUE(node) failed: file z:/build/build/src/layout/base/nsDocumentViewer.cpp, line 3713 16:27:53 INFO - GECKO(5920) | --DOMWINDOW == 15 (000001B91536FC00) [pid = 4704] [serial = 24] [outer = 0000000000000000] [url = about:blank] 16:27:53 INFO - GECKO(5920) | --DOMWINDOW == 14 (000001B916275800) [pid = 4704] [serial = 26] [outer = 0000000000000000] [url = about:blank] 16:27:53 INFO - GECKO(5920) | --DOMWINDOW == 13 (000001B9148CB000) [pid = 4704] [serial = 13] [outer = 0000000000000000] [url = data:application/json;foo=bar+json,[1,2,3]] 16:27:53 INFO - GECKO(5920) | --DOMWINDOW == 12 (000001B9148CBC00) [pid = 4704] [serial = 16] [outer = 0000000000000000] [url = data:application/manifest+json;foo=bar+json,[1,2,3]] 16:27:53 INFO - GECKO(5920) | --DOMWINDOW == 11 (000001B9148CA800) [pid = 4704] [serial = 19] [outer = 0000000000000000] [url = data:application/hal+json;foo=bar+json,[1,2,3]] 16:27:53 INFO - GECKO(5920) | --DOMWINDOW == 10 (000001B914AB7800) [pid = 4704] [serial = 22] [outer = 0000000000000000] [url = data:application/whatever+json;foo=bar+json,[1,2,3]] 16:27:54 INFO - GECKO(5920) | --DOMWINDOW == 6 (000001C720940800) [pid = 3372] [serial = 7] [outer = 0000000000000000] [url = data:application/vnd.api+json,[1,2,3]] 16:27:54 INFO - GECKO(5920) | --DOMWINDOW == 5 (000001C720C5E000) [pid = 3372] [serial = 10] [outer = 0000000000000000] [url = data:application/json+json,[1,2,3]] 16:27:54 INFO - GECKO(5920) | --DOMWINDOW == 4 (000001C722455800) [pid = 3372] [serial = 13] [outer = 0000000000000000] [url = data:text/json,[1,2,3]] 16:27:54 INFO - GECKO(5920) | --DOMWINDOW == 3 (000001C72245A400) [pid = 3372] [serial = 15] [outer = 0000000000000000] [url = about:blank] 16:27:54 INFO - GECKO(5920) | --DOMWINDOW == 2 (000001C71E835400) [pid = 3372] [serial = 4] [outer = 0000000000000000] [url = about:blank] 16:27:54 INFO - GECKO(5920) | --DOMWINDOW == 9 (000001B90CB1C6D0) [pid = 4704] [serial = 27] [outer = 0000000000000000] [url = about:blank] 16:27:55 INFO - GECKO(5920) | --DOMWINDOW == 4 (000001C4B0752400) [pid = 4460] [serial = 22] [outer = 0000000000000000] [url = about:blank] 16:27:58 INFO - GECKO(5920) | --DOMWINDOW == 8 (000001B9148CB400) [pid = 4704] [serial = 30] [outer = 0000000000000000] [url = about:blank] 16:27:58 INFO - GECKO(5920) | --DOMWINDOW == 7 (000001B9148CAC00) [pid = 4704] [serial = 28] [outer = 0000000000000000] [url = about:blank] 16:27:58 INFO - GECKO(5920) | --DOCSHELL 000001B90CB5F000 == 2 [pid = 4704] [id = {6e764b26-1752-41f6-a7eb-e166af70f959}] 16:27:58 INFO - GECKO(5920) | --DOCSHELL 000001B914A09000 == 1 [pid = 4704] [id = {c1b2f64e-c675-47cb-8016-575ee19c818a}] 16:28:01 INFO - GECKO(5920) | --DOCSHELL 000001C720172800 == 0 [pid = 3372] [id = {687c042d-c837-4863-8f16-4eb83e272d85}] 16:28:02 INFO - GECKO(5920) | --DOMWINDOW == 1 (000001C71861C560) [pid = 3372] [serial = 16] [outer = 0000000000000000] [url = about:blank] 16:28:02 INFO - TEST-INFO | started process screenshot 16:28:02 INFO - TEST-INFO | screenshot: exit 0 16:28:02 INFO - Buffered messages logged at 16:27:53 16:28:02 INFO - 34 INFO Entering test bound 16:28:02 INFO - 35 INFO Test JSON encoding started 16:28:02 INFO - 36 INFO Test decoding of "%EF%BB%BF". 16:28:02 INFO - 37 INFO Adding a new JSON tab with URL: 'data:application/json,%EF%BB%BF' 16:28:02 INFO - 38 INFO Adding a new tab with URL: data:application/json,%EF%BB%BF 16:28:02 INFO - 39 INFO DocReadyState is "loading". Await "complete" 16:28:02 INFO - 40 INFO DocReadyState is "interactive". Await "complete" 16:28:02 INFO - 41 INFO Console message: [JavaScript Warning: "Unknown property ‘user-select’. Declaration dropped." {file: "resource://devtools/client/shared/components/reps/reps.css" line: 257}] 16:28:02 INFO - 42 INFO Tab added and finished loading 16:28:02 INFO - 43 INFO AppReadyState is "uninitialized". Await "complete" 16:28:02 INFO - 44 INFO AppReadyState is "loading". Await "complete" 16:28:02 INFO - Buffered messages logged at 16:28:02 16:28:02 INFO - 45 INFO Error: Load timeout for modules: devtools/client/shared/vendor/lodash 16:28:02 INFO - http://requirejs.org/docs/errors.html#timeout 16:28:02 INFO - Buffered messages finished 16:28:02 ERROR - 46 INFO TEST-UNEXPECTED-FAIL | devtools/client/jsonview/test/browser_jsonview_encoding.js | RequireJS error - 16:28:02 INFO - Stack trace: 16:28:02 INFO - chrome://mochitests/content/browser/devtools/client/jsonview/test/head.js:addJsonViewTab/error</</requirejs.onError:78 16:28:02 INFO - Not taking screenshot here: see the one that was previously logged 16:28:02 ERROR - 47 INFO TEST-UNEXPECTED-FAIL | devtools/client/jsonview/test/browser_jsonview_encoding.js | Uncaught exception - at resource://devtools-client-jsonview/lib/require.js:166 - Error: Load timeout for modules: devtools/client/shared/vendor/lodash 16:28:02 INFO - http://requirejs.org/docs/errors.html#timeout 16:28:02 INFO - Stack trace: 16:28:02 INFO - makeError@resource://devtools-client-jsonview/lib/require.js:166:17 16:28:02 INFO - checkLoaded@resource://devtools-client-jsonview/lib/require.js:692:23 16:28:02 INFO - newContext/checkLoaded/checkLoadedTimeoutId<@resource://devtools-client-jsonview/lib/require.js:713:25 16:28:02 INFO - setTimeout handler*checkLoaded@resource://devtools-client-jsonview/lib/require.js:711:44 16:28:02 INFO - newContext/checkLoaded/checkLoadedTimeoutId<@resource://devtools-client-jsonview/lib/require.js:713:25 16:28:02 INFO - setTimeout handler*checkLoaded@resource://devtools-client-jsonview/lib/require.js:711:44 16:28:02 INFO - newContext/checkLoaded/checkLoadedTimeoutId<@resource://devtools-client-jsonview/lib/require.js:713:25 16:28:02 INFO - setTimeout handler*checkLoaded@resource://devtools-client-jsonview/lib/require.js:711:44 16:28:02 INFO - newContext/checkLoaded/checkLoadedTimeoutId<@resource://devtools-client-jsonview/lib/require.js:713:25 16:28:02 INFO - setTimeout handler*checkLoaded@resource://devtools-client-jsonview/lib/require.js:711:44 16:28:02 INFO - newContext/checkLoaded/checkLoadedTimeoutId<@resource://devtools-client-jsonview/lib/require.js:713:25 16:28:02 INFO - setTimeout handler*checkLoaded@resource://devtools-client-jsonview/lib/require.js:711:44 16:28:02 INFO - newContext/checkLoaded/checkLoadedTimeoutId<@resource://devtools-client-jsonview/lib/require.js:713:25 16:28:02 INFO - setTimeout handler*checkLoaded@resource://devtools-client-jsonview/lib/require.js:711:44 16:28:02 INFO - newContext/checkLoaded/checkLoadedTimeoutId<@resource://devtools-client-jsonview/lib/require.js:713:25 16:28:02 INFO - setTimeout handler*checkLoaded@resource://devtools-client-jsonview/lib/require.js:711:44 16:28:02 INFO - newContext/checkLoaded/checkLoadedTimeoutId<@resource://devtools-client-jsonview/lib/require.js:713:25 16:28:02 INFO - setTimeout handler*checkLoaded@resource://devtools-client-jsonview/lib/require.js:711:44 16:28:02 INFO - newContext/makeRequire/localRequire/<@resource://devtools-client-jsonview/lib/require.js:1428:25 16:28:02 INFO - setTimeout handler*req.nextTick<@resource://devtools-client-jsonview/lib/require.js:1763:9 16:28:02 INFO - localRequire@resource://devtools-client-jsonview/lib/require.js:1413:21 16:28:02 INFO - configure@resource://devtools-client-jsonview/lib/require.js:1351:21 16:28:02 INFO - requirejs@resource://devtools-client-jsonview/lib/require.js:1742:13 16:28:02 INFO - @resource://devtools-client-jsonview/lib/require.js:2075:5 16:28:02 INFO - @resource://devtools-client-jsonview/lib/require.js:12:2 16:28:02 INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1058:9 16:28:02 INFO - Tester.prototype.nextTest</<@chrome://mochikit/content/browser-test.js:958:9 16:28:02 INFO - SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:795:59 16:28:02 INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1058:9 16:28:02 INFO - Tester.prototype.nextTest</<@chrome://mochikit/content/browser-test.js:958:9 16:28:02 INFO - SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:795:59 16:28:02 INFO - 48 INFO Leaving test bound 16:28:02 INFO - 49 INFO Removing tab. 16:28:02 INFO - 50 INFO Waiting for event: 'TabClose' on [object XULElement]. 16:28:02 INFO - 51 INFO Got event: 'TabClose' on [object XULElement]. 16:28:02 INFO - 52 INFO Tab removed and finished closing 16:28:02 INFO - GECKO(5920) | [Child 4704, Main Thread] WARNING: NS_ENSURE_TRUE(node) failed: file z:/build/build/src/layout/base/nsDocumentViewer.cpp, line 3713 16:28:03 INFO - GECKO(5920) | MEMORY STAT | vsize 2223MB | vsizeMaxContiguous 129985031MB | residentFast 335MB | heapAllocated 89MB 16:28:03 INFO - 53 INFO TEST-OK | devtools/client/jsonview/test/browser_jsonview_encoding.js | took 9949ms