1688440 - Perma [tier 2] M-fis-xorig linux debug toolkit/components/extensions/test/mochitest/test_ext_tabs_permissions.html | TypeError: logger.warning is not a function - Should not throw any errors

Status: RESOLVED FIXED

(WebExtensions :: General, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=327595966&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/TSqYpITNRL-T0AkAvfehEA/runs/1/artifacts/public/logs/live_backing.log

---

[task 2021-01-23T23:26:57.413Z] 23:26:57     INFO - TEST-OK | http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_tabs_captureTab.html | took 19632ms
[task 2021-01-23T23:26:57.435Z] 23:26:57     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/workspace/obj-build/dist/include/mozilla/ipc/ProtocolUtils.h:299
[task 2021-01-23T23:26:57.452Z] 23:26:57     INFO - GECKO(11697) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-01-23T23:26:57.452Z] 23:26:57     INFO - GECKO(11697) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-01-23T23:26:57.480Z] 23:26:57     INFO - TEST-START | http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_tabs_permissions.html
[task 2021-01-23T23:26:57.649Z] 23:26:57     INFO - GECKO(11697) | [Child 14107, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3364
[task 2021-01-23T23:26:57.693Z] 23:26:57     INFO - GECKO(11697) | [Child 14107, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4378
[task 2021-01-23T23:26:57.780Z] 23:26:57     INFO - GECKO(11697) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp120qJc.mozrunner/runtests_leaks_tab_pid14274.log
[task 2021-01-23T23:26:57.780Z] 23:26:57     INFO - GECKO(11697) | [14274, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:251
[task 2021-01-23T23:26:57.881Z] 23:26:57     INFO - GECKO(11697) | [Child 14274, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:629
[task 2021-01-23T23:26:57.957Z] 23:26:57     INFO - GECKO(11697) | Console message: Warning: attempting to write 9771 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.
[task 2021-01-23T23:26:58.080Z] 23:26:58     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4279
[task 2021-01-23T23:26:58.095Z] 23:26:58     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1243
[task 2021-01-23T23:26:58.151Z] 23:26:58     INFO - GECKO(11697) | Console message: Warning: attempting to write 9842 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.
[task 2021-01-23T23:26:58.228Z] 23:26:58     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4279
[task 2021-01-23T23:26:58.235Z] 23:26:58     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1243
[task 2021-01-23T23:26:58.561Z] 23:26:58     INFO - GECKO(11697) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp120qJc.mozrunner/runtests_leaks_tab_pid14299.log
[task 2021-01-23T23:26:58.562Z] 23:26:58     INFO - GECKO(11697) | [14299, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:251
[task 2021-01-23T23:26:58.724Z] 23:26:58     INFO - GECKO(11697) | Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "http://www.example.com/tests/toolkit/components/extensions/test/mochitest/file_tabs_permission_page1.html" line: 0}]
[task 2021-01-23T23:26:58.744Z] 23:26:58     INFO - GECKO(11697) | [Child 14299, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:629
[task 2021-01-23T23:26:59.227Z] 23:26:59     INFO - GECKO(11697) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp120qJc.mozrunner/runtests_leaks_tab_pid14325.log
[task 2021-01-23T23:26:59.227Z] 23:26:59     INFO - GECKO(11697) | [14325, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:251
[task 2021-01-23T23:26:59.318Z] 23:26:59     INFO - GECKO(11697) | Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "http://example.net/tests/toolkit/components/extensions/test/mochitest/file_tabs_permission_page2.html" line: 0}]
[task 2021-01-23T23:26:59.435Z] 23:26:59     INFO - GECKO(11697) | [Child 14325, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:629
[task 2021-01-23T23:26:59.593Z] 23:26:59     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80040111 (NS_ERROR_NOT_AVAILABLE): file /builds/worker/checkouts/gecko/netwerk/protocol/res/SubstitutingProtocolHandler.cpp:414
[task 2021-01-23T23:26:59.724Z] 23:26:59     INFO - GECKO(11697) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-01-23T23:26:59.724Z] 23:26:59     INFO - GECKO(11697) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-01-23T23:26:59.724Z] 23:26:59     INFO - GECKO(11697) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-01-23T23:26:59.752Z] 23:26:59     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:181
[task 2021-01-23T23:26:59.820Z] 23:26:59     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-01-23T23:26:59.876Z] 23:26:59     INFO - GECKO(11697) | Console message: Warning: attempting to write 9771 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.
[task 2021-01-23T23:26:59.891Z] 23:26:59     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:181
[task 2021-01-23T23:26:59.908Z] 23:26:59     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-01-23T23:26:59.964Z] 23:26:59     INFO - GECKO(11697) | Console message: Warning: attempting to write 9705 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.
[task 2021-01-23T23:26:59.984Z] 23:26:59     INFO - GECKO(11697) | [Child 14254, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3364
[task 2021-01-23T23:26:59.988Z] 23:26:59     INFO - GECKO(11697) | Console message: Warning: attempting to write 9771 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.
[task 2021-01-23T23:27:00.015Z] 23:27:00     INFO - GECKO(11697) | [Child 14229, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3364
[task 2021-01-23T23:27:00.023Z] 23:27:00     INFO - GECKO(11697) | [Child 14254, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4378
[task 2021-01-23T23:27:00.044Z] 23:27:00     INFO - GECKO(11697) | [Child 14229, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4378
[task 2021-01-23T23:27:00.125Z] 23:27:00     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4279
[task 2021-01-23T23:27:00.141Z] 23:27:00     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1243
[task 2021-01-23T23:27:00.177Z] 23:27:00     INFO - GECKO(11697) | Console message: Warning: attempting to write 9842 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.
[task 2021-01-23T23:27:00.253Z] 23:27:00     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4279
[task 2021-01-23T23:27:00.260Z] 23:27:00     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1243
[task 2021-01-23T23:27:00.619Z] 23:27:00     INFO - GECKO(11697) | Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "http://www.example.com/tests/toolkit/components/extensions/test/mochitest/file_tabs_permission_page1.html" line: 0}]
[task 2021-01-23T23:27:01.002Z] 23:27:01     INFO - GECKO(11697) | Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "http://example.net/tests/toolkit/components/extensions/test/mochitest/file_tabs_permission_page2.html" line: 0}]
[task 2021-01-23T23:27:01.193Z] 23:27:01     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80040111 (NS_ERROR_NOT_AVAILABLE): file /builds/worker/checkouts/gecko/netwerk/protocol/res/SubstitutingProtocolHandler.cpp:414
[task 2021-01-23T23:27:01.324Z] 23:27:01     INFO - GECKO(11697) | [Child 14274, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3364
[task 2021-01-23T23:27:01.324Z] 23:27:01     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:181
[task 2021-01-23T23:27:01.344Z] 23:27:01     INFO - GECKO(11697) | [Child 14274, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4378
[task 2021-01-23T23:27:01.360Z] 23:27:01     INFO - GECKO(11697) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-01-23T23:27:01.376Z] 23:27:01     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-01-23T23:27:01.411Z] 23:27:01     INFO - GECKO(11697) | Console message: Warning: attempting to write 9771 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.
[task 2021-01-23T23:27:01.432Z] 23:27:01     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:181
[task 2021-01-23T23:27:01.459Z] 23:27:01     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-01-23T23:27:01.515Z] 23:27:01     INFO - GECKO(11697) | [Child 14299, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3364
[task 2021-01-23T23:27:01.536Z] 23:27:01     INFO - GECKO(11697) | [Child 14299, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4378
[task 2021-01-23T23:27:01.553Z] 23:27:01     INFO - GECKO(11697) | Console message: Warning: attempting to write 9705 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.
[task 2021-01-23T23:27:01.594Z] 23:27:01     INFO - GECKO(11697) | Console message: Warning: attempting to write 9771 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.
[task 2021-01-23T23:27:01.670Z] 23:27:01     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4279
[task 2021-01-23T23:27:01.706Z] 23:27:01     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1243
[task 2021-01-23T23:27:01.750Z] 23:27:01     INFO - GECKO(11697) | Console message: Warning: attempting to write 9842 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.
[task 2021-01-23T23:27:01.858Z] 23:27:01     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4279
[task 2021-01-23T23:27:01.866Z] 23:27:01     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1243
[task 2021-01-23T23:27:02.225Z] 23:27:02     INFO - GECKO(11697) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp120qJc.mozrunner/runtests_leaks_tab_pid14361.log
[task 2021-01-23T23:27:02.225Z] 23:27:02     INFO - GECKO(11697) | [14361, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:251
[task 2021-01-23T23:27:02.433Z] 23:27:02     INFO - GECKO(11697) | [Child 14361, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:629
[task 2021-01-23T23:27:02.461Z] 23:27:02     INFO - GECKO(11697) | Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "http://www.example.com/tests/toolkit/components/extensions/test/mochitest/file_tabs_permission_page1.html" line: 0}]
[task 2021-01-23T23:27:03.041Z] 23:27:03     INFO - GECKO(11697) | Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "http://example.net/tests/toolkit/components/extensions/test/mochitest/file_tabs_permission_page2.html" line: 0}]
[task 2021-01-23T23:27:03.365Z] 23:27:03     INFO - GECKO(11697) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-01-23T23:27:03.367Z] 23:27:03     INFO - GECKO(11697) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-01-23T23:27:03.387Z] 23:27:03     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80040111 (NS_ERROR_NOT_AVAILABLE): file /builds/worker/checkouts/gecko/netwerk/protocol/res/SubstitutingProtocolHandler.cpp:414
[task 2021-01-23T23:27:03.449Z] 23:27:03     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:181
[task 2021-01-23T23:27:03.489Z] 23:27:03     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-01-23T23:27:03.570Z] 23:27:03     INFO - GECKO(11697) | Console message: Warning: attempting to write 9771 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.
[task 2021-01-23T23:27:03.590Z] 23:27:03     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:181
[task 2021-01-23T23:27:03.626Z] 23:27:03     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-01-23T23:27:03.662Z] 23:27:03     INFO - GECKO(11697) | [Child 14361, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3364
[task 2021-01-23T23:27:03.682Z] 23:27:03     INFO - GECKO(11697) | [Child 14361, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4378
[task 2021-01-23T23:27:03.692Z] 23:27:03     INFO - GECKO(11697) | [Child 14325, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3364
[task 2021-01-23T23:27:03.705Z] 23:27:03     INFO - GECKO(11697) | Console message: Warning: attempting to write 9705 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.
[task 2021-01-23T23:27:03.722Z] 23:27:03     INFO - GECKO(11697) | [Child 14325, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4378
[task 2021-01-23T23:27:03.750Z] 23:27:03     INFO - GECKO(11697) | Console message: Warning: attempting to write 9771 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.
[task 2021-01-23T23:27:03.786Z] 23:27:03     INFO - GECKO(11697) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp120qJc.mozrunner/runtests_leaks_tab_pid14391.log
[task 2021-01-23T23:27:03.787Z] 23:27:03     INFO - GECKO(11697) | [14391, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:251
[task 2021-01-23T23:27:03.848Z] 23:27:03     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4279
[task 2021-01-23T23:27:03.863Z] 23:27:03     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1243
[task 2021-01-23T23:27:03.904Z] 23:27:03     INFO - GECKO(11697) | [Child 14391, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:629
[task 2021-01-23T23:27:03.924Z] 23:27:03     INFO - GECKO(11697) | Console message: Warning: attempting to write 9842 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.
[task 2021-01-23T23:27:04.052Z] 23:27:04     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4279
[task 2021-01-23T23:27:04.060Z] 23:27:04     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1243
[task 2021-01-23T23:27:04.499Z] 23:27:04     INFO - GECKO(11697) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp120qJc.mozrunner/runtests_leaks_tab_pid14412.log
[task 2021-01-23T23:27:04.499Z] 23:27:04     INFO - GECKO(11697) | [14412, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:251
[task 2021-01-23T23:27:04.515Z] 23:27:04     INFO - GECKO(11697) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp120qJc.mozrunner/runtests_leaks_tab_pid14419.log
[task 2021-01-23T23:27:04.515Z] 23:27:04     INFO - GECKO(11697) | [14419, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:251
[task 2021-01-23T23:27:04.596Z] 23:27:04     INFO - GECKO(11697) | Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "http://www.example.com/tests/toolkit/components/extensions/test/mochitest/file_tabs_permission_page1.html" line: 0}]
[task 2021-01-23T23:27:04.717Z] 23:27:04     INFO - GECKO(11697) | [Child 14419, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:629
[task 2021-01-23T23:27:04.717Z] 23:27:04     INFO - GECKO(11697) | [Child 14412, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:629
[task 2021-01-23T23:27:05.181Z] 23:27:05     INFO - GECKO(11697) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp120qJc.mozrunner/runtests_leaks_tab_pid14453.log
[task 2021-01-23T23:27:05.184Z] 23:27:05     INFO - GECKO(11697) | [14453, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:251
[task 2021-01-23T23:27:05.299Z] 23:27:05     INFO - GECKO(11697) | [Child 14453, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:629
[task 2021-01-23T23:27:05.997Z] 23:27:05     INFO - GECKO(11697) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp120qJc.mozrunner/runtests_leaks_tab_pid14473.log
[task 2021-01-23T23:27:05.998Z] 23:27:05     INFO - GECKO(11697) | [14473, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:251
[task 2021-01-23T23:27:06.216Z] 23:27:06     INFO - GECKO(11697) | Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "http://example.net/tests/toolkit/components/extensions/test/mochitest/file_tabs_permission_page2.html" line: 0}]
[task 2021-01-23T23:27:06.244Z] 23:27:06     INFO - GECKO(11697) | [Child 14473, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:629
[task 2021-01-23T23:27:06.506Z] 23:27:06     INFO - GECKO(11697) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-01-23T23:27:06.507Z] 23:27:06     INFO - GECKO(11697) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-01-23T23:27:06.542Z] 23:27:06     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80040111 (NS_ERROR_NOT_AVAILABLE): file /builds/worker/checkouts/gecko/netwerk/protocol/res/SubstitutingProtocolHandler.cpp:414
[task 2021-01-23T23:27:06.679Z] 23:27:06     INFO - GECKO(11697) | [Child 14391, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3364
[task 2021-01-23T23:27:06.701Z] 23:27:06     INFO - GECKO(11697) | [Child 14391, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4378
[task 2021-01-23T23:27:06.721Z] 23:27:06     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:181
[task 2021-01-23T23:27:06.741Z] 23:27:06     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-01-23T23:27:06.789Z] 23:27:06     INFO - GECKO(11697) | Console message: Warning: attempting to write 9771 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.
[task 2021-01-23T23:27:06.806Z] 23:27:06     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:181
[task 2021-01-23T23:27:06.827Z] 23:27:06     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-01-23T23:27:06.867Z] 23:27:06     INFO - GECKO(11697) | [Child 14412, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3364
[task 2021-01-23T23:27:06.870Z] 23:27:06     INFO - GECKO(11697) | Console message: Warning: attempting to write 9705 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.
[task 2021-01-23T23:27:06.891Z] 23:27:06     INFO - GECKO(11697) | [Child 14412, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4378
[task 2021-01-23T23:27:06.906Z] 23:27:06     INFO - GECKO(11697) | Console message: Warning: attempting to write 9771 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.
[task 2021-01-23T23:27:06.982Z] 23:27:06     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4279
[task 2021-01-23T23:27:06.999Z] 23:27:06     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1243
[task 2021-01-23T23:27:07.027Z] 23:27:07     INFO - GECKO(11697) | Console message: Warning: attempting to write 9842 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.
[task 2021-01-23T23:27:07.087Z] 23:27:07     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4279
[task 2021-01-23T23:27:07.095Z] 23:27:07     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1243
[task 2021-01-23T23:27:07.369Z] 23:27:07     INFO - GECKO(11697) | Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "http://www.example.com/tests/toolkit/components/extensions/test/mochitest/file_tabs_permission_page1.html" line: 0}]
[task 2021-01-23T23:27:07.687Z] 23:27:07     INFO - GECKO(11697) | Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "http://example.net/tests/toolkit/components/extensions/test/mochitest/file_tabs_permission_page2.html" line: 0}]
[task 2021-01-23T23:27:07.899Z] 23:27:07     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80040111 (NS_ERROR_NOT_AVAILABLE): file /builds/worker/checkouts/gecko/netwerk/protocol/res/SubstitutingProtocolHandler.cpp:414
[task 2021-01-23T23:27:07.973Z] 23:27:07     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:181
[task 2021-01-23T23:27:08.015Z] 23:27:08     INFO - GECKO(11697) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
<...>
[task 2021-01-23T23:27:17.135Z] 23:27:17     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_tabs_permissions.html | test result correct 
[task 2021-01-23T23:27:17.135Z] 23:27:17     INFO - add_task | Leaving test has_restricted_properties_with_tabs_permission
[task 2021-01-23T23:27:17.135Z] 23:27:17     INFO - Buffered messages finished
[task 2021-01-23T23:27:17.135Z] 23:27:17     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_tabs_permissions.html | TypeError: logger.warning is not a function - Should not throw any errors
[task 2021-01-23T23:27:17.136Z] 23:27:17     INFO - skipTask@http://mochi.test:8888/tests/SimpleTest/SimpleTest.js:2233:20
[task 2021-01-23T23:27:17.136Z] 23:27:17     INFO - nextTick/<@http://mochi.test:8888/tests/SimpleTest/SimpleTest.js:2246:25
[task 2021-01-23T23:27:17.136Z] 23:27:17     INFO - GECKO(11697) | [Child 14602, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3364
[task 2021-01-23T23:27:17.136Z] 23:27:17     INFO - GECKO(11697) | MEMORY STAT | vsize 2557MB | residentFast 158MB | heapAllocated 12MB
[task 2021-01-23T23:27:17.136Z] 23:27:17     INFO - GECKO(11697) | [Child 14602, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4378
[task 2021-01-23T23:27:18.961Z] 23:27:18     INFO - TEST-OK | http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_tabs_permissions.html | took 21481ms
[task 2021-01-23T23:27:22.966Z] 23:27:22     INFO - Error: Unable to restore focus, expect failures and timeouts.
[task 2021-01-23T23:27:23.022Z] 23:27:23     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: Received `load` event on unbridged BrowserParent!: file /builds/worker/checkouts/gecko/dom/ipc/BrowserParent.cpp:4013
[task 2021-01-23T23:27:23.023Z] 23:27:23     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: Received `load` event on unbridged BrowserParent!: file /builds/worker/checkouts/gecko/dom/ipc/BrowserParent.cpp:4013
[task 2021-01-23T23:27:23.044Z] 23:27:23     INFO - GECKO(11697) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-01-23T23:27:23.069Z] 23:27:23     INFO - TEST-START | http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_tabs_query_popup.html
[task 2021-01-23T23:27:23.170Z] 23:27:23     INFO - GECKO(11697) | [Child 14203, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3364
[task 2021-01-23T23:27:23.185Z] 23:27:23     INFO - GECKO(11697) | [Child 14203, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4378
[task 2021-01-23T23:27:26.082Z] 23:27:26     INFO - Error: Unable to restore focus, expect failures and timeouts.
[task 2021-01-23T23:27:26.186Z] 23:27:26     INFO - GECKO(11697) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp120qJc.mozrunner/runtests_leaks_tab_pid14698.log
[task 2021-01-23T23:27:26.187Z] 23:27:26     INFO - GECKO(11697) | [14698, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:251
[task 2021-01-23T23:27:26.254Z] 23:27:26     INFO - GECKO(11697) | [Child 14698, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:629
[task 2021-01-23T23:27:27.495Z] 23:27:27     INFO - add_task | Entering test test_query_currentWindow_from_popup
[task 2021-01-23T23:27:27.503Z] 23:27:27     INFO - Extension loaded
[task 2021-01-23T23:27:27.519Z] 23:27:27     INFO - GECKO(11697) | Console message: Warning: attempting to write 9779 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.
[task 2021-01-23T23:27:27.781Z] 23:27:27     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4279
[task 2021-01-23T23:27:27.808Z] 23:27:27     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1243
[task 2021-01-23T23:27:28.129Z] 23:27:28     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1243
[task 2021-01-23T23:27:28.265Z] 23:27:28     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:181
[task 2021-01-23T23:27:28.281Z] 23:27:28     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: 'aOwner->IsDiscarded()', file /builds/worker/workspace/obj-build/dist/include/mozilla/dom/SyncedContextInlines.h:32
[task 2021-01-23T23:27:28.303Z] 23:27:28     INFO - GECKO(11697) | [Parent 11697, Main Thread] WARNING: '!aContent->GetComposedDoc()', file /builds/worker/checkouts/gecko/layout/base/nsCSSFrameConstructor.cpp:8459
[task 2021-01-23T23:27:28.573Z] 23:27:28     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_tabs_query_popup.html | Got one tab - Expected: 1, Actual: 1 ```

Comment 1

[Alexandru Michis [:malexandru]]

Rob, could you please take a look?
The test was introduced in Bug 1679688.

Comment 2

[Rob Wu [:robwu]]

Error originates from here: https://searchfox.org/mozilla-central/rev/ed2fc6bdcef6fe27b5588aa5d5891f6792708d09/testing/mochitest/tests/SimpleTest/SimpleTest.js#2233

I guess that the structuredLogger at https://searchfox.org/mozilla-central/rev/ed2fc6bdcef6fe27b5588aa5d5891f6792708d09/testing/mochitest/tests/SimpleTest/SimpleTest.js#121 is missing a warning method.

Comment 3

[Rob Wu [:robwu]]

Attachment: Bug 1688440 - Add missing logger.warning/error to xOriginRunner

Comment 4

[Rob Wu [:robwu]]

Not really a problem with the test itself, but an error in the test runner.

Comment 5

[Intermittent Failures Robot]

7 failures in 3488 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 5
• try: 2

Platform and build breakdown:

• linux1804-64: 7
  • debug: 7

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1688440&startday=2021-01-18&endday=2021-01-24&tree=all

Comment 6

[Nika Layzell [:nika] (ni? for response)]

Tracking for Fission M7 as we'd like to have xorigin tests working properly

Comment 7

[BugBot [:suhaib / :marco/ :calixte]]

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

Comment 8

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/e4285493ec6b
Add missing logger.warning/error to xOriginRunner r=kmag

Comment 9

[Alexandru Michis [:malexandru]]

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

Comment 10

[Intermittent Failures Robot]

32 failures in 3891 pushes (0.008 failures/push) were associated with this bug in the last 7 days.

This is the #36 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• try: 14
• mozilla-central: 18

Platform and build breakdown:

• linux1804-64: 32
  • debug: 32

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1688440&startday=2021-01-25&endday=2021-01-31&tree=all