Filed by: apavel [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=214049107&repo=autoland https://queue.taskcluster.net/v1/task/DYwOVT9qQxKdWcVbbaAD7A/runs/0/artifacts/public/logs/live_backing.log 05:35:56 INFO - TEST-START | browser/components/extensions/test/browser/test-oop-extensions/browser_ext_user_events.js 05:36:41 INFO - TEST-INFO | started process screenshot 05:36:41 INFO - TEST-INFO | screenshot: exit 0 05:36:41 INFO - Buffered messages logged at 05:35:56 05:36:41 INFO - Entering test bound testSources 05:36:41 INFO - Extension loaded 05:36:41 INFO - Console message: Warning: attempting to write 32140 bytes to preference extensions.webextensions.uuids. This is bad for general performance and memory usage. Such an amount of data should rather be written to an external file. This preference will not be sent to any content processes. 05:36:41 INFO - Console message: Warning: attempting to write 5905 bytes to preference browser.pageActions.persistedActions. This is bad for general performance and memory usage. Such an amount of data should rather be written to an external file. This preference will not be sent to any content processes. 05:36:41 INFO - TEST-PASS | browser/components/extensions/test/browser/test-oop-extensions/browser_ext_user_events.js | addon-webext-permissions notification shown - 05:36:41 INFO - TEST-PASS | browser/components/extensions/test/browser/test-oop-extensions/browser_ext_user_events.js | notification panel open - 05:36:41 INFO - Console message: PopupNotifications._onButtonEvent: Button click happened before the window was focused 05:36:41 INFO - Buffered messages finished 05:36:41 INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/test-oop-extensions/browser_ext_user_events.js | Test timed out - 05:36:41 INFO - Not taking screenshot here: see the one that was previously logged 05:36:41 INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/test-oop-extensions/browser_ext_user_events.js | no tasks awaiting on messages - Got ["request"], expected [] 05:36:41 INFO - Stack trace: 05:36:41 INFO - chrome://mochikit/content/browser-test.js:test_is:1303 05:36:41 INFO - chrome://mochikit/content/tests/SimpleTest/ExtensionTestUtils.js:ExtensionTestUtils.loadExtension/<:31 05:36:41 INFO - chrome://mochikit/content/browser-test.js:nextTest:696 05:36:41 INFO - chrome://mochikit/content/browser-test.js:timeoutFn:1190 05:36:41 INFO - setTimeout handler*chrome://mochikit/content/browser-test.js:Tester_execTest:1152 05:36:41 INFO - chrome://mochikit/content/browser-test.js:nextTest/<:982 05:36:41 INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:803 05:36:41 INFO - Not taking screenshot here: see the one that was previously logged 05:36:41 INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/test-oop-extensions/browser_ext_user_events.js | Extension left running at test shutdown - 05:36:41 INFO - Stack trace: 05:36:41 INFO - chrome://mochikit/content/browser-test.js:test_ok:1292 05:36:41 INFO - chrome://mochikit/content/tests/SimpleTest/ExtensionTestUtils.js:ExtensionTestUtils.loadExtension/<:109 05:36:41 INFO - chrome://mochikit/content/browser-test.js:nextTest:696 05:36:41 INFO - chrome://mochikit/content/browser-test.js:timeoutFn:1190 05:36:41 INFO - setTimeout handler*chrome://mochikit/content/browser-test.js:Tester_execTest:1152 05:36:41 INFO - chrome://mochikit/content/browser-test.js:nextTest/<:982 05:36:41 INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:803 05:36:41 INFO - Console message: [JavaScript Error: "[Exception... "Illegal value" nsresult: "0x80070057 (NS_ERROR_ILLEGAL_VALUE)" location: "JS frame :: resource://gre/modules/MessageChannel.jsm :: _handleMessage/deferred.promise< :: line 1003" data: no]"] 05:36:41 INFO - _handleMessage/deferred.promise<@resource://gre/modules/MessageChannel.jsm:1003:9 05:36:41 INFO - promise callback*_handleMessage@resource://gre/modules/MessageChannel.jsm:954:8 05:36:41 INFO - receiveMessage/<@resource://gre/modules/MessageChannel.jsm:218:9 05:36:41 INFO - receiveMessage@resource://gre/modules/MessageChannel.jsm:211:5 05:36:41 INFO - MessageListener.receiveMessage*FilteringMessageManager@resource://gre/modules/MessageChannel.jsm:201:5 05:36:41 INFO - get@resource://gre/modules/MessageChannel.jsm:436:14 05:36:41 INFO - addListener@resource://gre/modules/MessageChannel.jsm:763:7 05:36:41 INFO - ExtensionGlobal@jar:file:///Z:/task_1543295694/build/application/firefox/omni.ja!/components/extension-process-script.js:58:5 05:36:41 INFO - init/<@jar:file:///Z:/task_1543295694/build/application/firefox/omni.ja!/components/extension-process-script.js:113:42 05:36:41 INFO - @data:, 05:36:41 INFO - Components.utils.import("resource://gre/modules/Services.jsm"); 05:36:41 INFO - 05:36:41 INFO - Services.obs.notifyObservers(this, "tab-content-frameloader-created", ""); 05:36:41 INFO - :1:76 05:36:41 INFO - 05:36:41 INFO - Console message: Warning: attempting to write 32042 bytes to preference extensions.webextensions.uuids. This is bad for general performance and memory usage. Such an amount of data should rather be written to an external file. This preference will not be sent to any content processes. 05:36:41 INFO - GECKO(9208) | MEMORY STAT | vsize 1987MB | vsizeMaxContiguous 132596338MB | residentFast 423MB | heapAllocated 113MB 05:36:41 INFO - TEST-OK | browser/components/extensions/test/browser/test-oop-extensions/browser_ext_user_events.js | took 45107ms