Skip to content

Instantly share code, notes, and snippets.

@daleharvey
Created April 11, 2019 15:00
Show Gist options
  • Save daleharvey/9a5d07e36bd9e908e877e48f238cc458 to your computer and use it in GitHub Desktop.
Save daleharvey/9a5d07e36bd9e908e877e48f238cc458 to your computer and use it in GitHub Desktop.
~/src/gecko (webextensions)$ ./mach test toolkit/components/search/tests/xpcshell/test_webextensions_install.js --sequential
0:01.75 INFO Found node at /Users/dharvey/.mozbuild/node/bin/node
0:01.75 INFO Found moz-http2 at /Users/dharvey/src/gecko/testing/xpcshell/moz-http2/moz-http2.js
0:01.88 INFO Running tests sequentially.
0:01.88 SUITE_START: xpcshell - running 1 tests
0:01.89 INFO profile dir is /var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile
0:01.91 TEST_START: toolkit/components/search/tests/xpcshell/test_webextensions_install.js
0:01.91 INFO toolkit/components/search/tests/xpcshell/test_webextensions_install.js | full command: ['/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/dist/Nightly.app/Contents/MacOS/xpcshell', '-g', '/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/dist/Nightly.app/Contents/Resources', '-a', '/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/dist/Nightly.app/Contents/Resources/browser', '-r', '/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/dist/Nightly.app/Contents/Resources/components/httpd.manifest', '-m', '-s', '-e', 'const _HEAD_JS_PATH = "/Users/dharvey/src/gecko/testing/xpcshell/head.js";', '-e', 'const _MOZINFO_JS_PATH = "/var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/mozinfo.json";', '-e', 'const _PREFS_FILE = "/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/temp/user.js";', '-e', 'const _TESTING_MODULES_DIR = "/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/modules/";', '-f', '/Users/dharvey/src/gecko/testing/xpcshell/head.js', '-p', '/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/temp/xpc-plugins-gGKcin', '-e', u'const _HEAD_FILES = ["/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell/head_search.js", "/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell/head_opensearch.js"];', '-e', 'const _JSDEBUGGER_PORT = 0;', '-e', u'const _TEST_FILE = ["/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell/test_webextensions_install.js"];', '-e', u'const _TEST_NAME = "toolkit/components/search/tests/xpcshell/test_webextensions_install.js";', '-e', '_execute_test(); quit(0);']
0:01.91 INFO toolkit/components/search/tests/xpcshell/test_webextensions_install.js | current directory: u'/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell'
0:01.91 INFO toolkit/components/search/tests/xpcshell/test_webextensions_install.js | environment: ['DYLD_LIBRARY_PATH=/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/dist/Nightly.app/Contents/MacOS', 'XPCOM_DEBUG_BREAK=stack-and-abort', 'MOZ_DEVELOPER_REPO_DIR=/Users/dharvey/src/gecko', 'MOZ_CRASHREPORTER=1', 'XPCSHELL_TEST_PROFILE_DIR=/var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile', 'XPCSHELL_TEST_TEMP_DIR=/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/temp/xpc-other-m0QPg_', 'MOZHTTP2_PORT=49557', 'MOZ_DISABLE_CONTENT_SANDBOX=1', 'MOZ_DISABLE_NONLOCAL_CONNECTIONS=1', 'MOZ_CRASHREPORTER_NO_REPORT=1']
0:01.91 INFO toolkit/components/search/tests/xpcshell/test_webextensions_install.js | as shell command: (cd /Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell; DYLD_LIBRARY_PATH=/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/dist/Nightly.app/Contents/MacOS XPCOM_DEBUG_BREAK=stack-and-abort MOZ_DEVELOPER_REPO_DIR=/Users/dharvey/src/gecko MOZ_CRASHREPORTER=1 XPCSHELL_TEST_PROFILE_DIR=/var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile XPCSHELL_TEST_TEMP_DIR=/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/temp/xpc-other-m0QPg_ MOZHTTP2_PORT=49557 MOZ_DISABLE_CONTENT_SANDBOX=1 MOZ_DISABLE_NONLOCAL_CONNECTIONS=1 MOZ_CRASHREPORTER_NO_REPORT=1 /Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/dist/Nightly.app/Contents/MacOS/xpcshell -g /Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/dist/Nightly.app/Contents/Resources -a /Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/dist/Nightly.app/Contents/Resources/browser -r /Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/dist/Nightly.app/Contents/Resources/components/httpd.manifest -m -s -e 'const _HEAD_JS_PATH = "/Users/dharvey/src/gecko/testing/xpcshell/head.js";' -e 'const _MOZINFO_JS_PATH = "/var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/mozinfo.json";' -e 'const _PREFS_FILE = "/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/temp/user.js";' -e 'const _TESTING_MODULES_DIR = "/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/modules/";' -f /Users/dharvey/src/gecko/testing/xpcshell/head.js -p /Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/temp/xpc-plugins-gGKcin -e 'const _HEAD_FILES = ["/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell/head_search.js", "/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell/head_opensearch.js"];' -e 'const _JSDEBUGGER_PORT = 0;' -e 'const _TEST_FILE = ["/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell/test_webextensions_install.js"];' -e 'const _TEST_NAME = "toolkit/components/search/tests/xpcshell/test_webextensions_install.js";' -e '_execute_test(); quit(0);')
0:02.17 pid:87331 Full command: ['/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/dist/Nightly.app/Contents/MacOS/xpcshell', '-g', '/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/dist/Nightly.app/Contents/Resources', '-a', '/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/dist/Nightly.app/Contents/Resources/browser', '-r', '/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/dist/Nightly.app/Contents/Resources/components/httpd.manifest', '-m', '-s', '-e', 'const _HEAD_JS_PATH = "/Users/dharvey/src/gecko/testing/xpcshell/head.js";', '-e', 'const _MOZINFO_JS_PATH = "/var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/mozinfo.json";', '-e', 'const _PREFS_FILE = "/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/temp/user.js";', '-e', 'const _TESTING_MODULES_DIR = "/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/modules/";', '-f', '/Users/dharvey/src/gecko/testing/xpcshell/head.js', '-p', '/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/temp/xpc-plugins-gGKcin', '-e', u'const _HEAD_FILES = ["/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell/head_search.js", "/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell/head_opensearch.js"];', '-e', 'const _JSDEBUGGER_PORT = 0;', '-e', u'const _TEST_FILE = ["/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell/test_webextensions_install.js"];', '-e', u'const _TEST_NAME = "toolkit/components/search/tests/xpcshell/test_webextensions_install.js";', '-e', '_execute_test(); quit(0);']
pid:87331 JavaScript strict warning: resource://testing-common/AddonTestUtils.jsm, line 278: ReferenceError: reference to undefined property "testScope"
0:02.17 INFO "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "ReferenceError: reference to undefined property "testScope"" {file: "resource://testing-common/AddonTestUtils.jsm" line: 278}]"
0:02.24 INFO (xpcshell/head.js) | test MAIN run_test pending (1)
0:02.24 INFO (xpcshell/head.js) | test run_next_test 0 pending (2)
0:02.24 INFO (xpcshell/head.js) | test MAIN run_test finished (2)
0:02.24 INFO running event loop
0:02.24 INFO toolkit/components/search/tests/xpcshell/test_webextensions_install.js | Starting setup
0:02.24 INFO (xpcshell/head.js) | test setup pending (2)
0:02.25 pid:87331 1554994795794 addons.manager DEBUG Application has been upgraded
0:02.27 pid:87331 1554994795821 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/XPIProvider.jsm: ["XPIProvider", "XPIInternal"]
0:02.28 pid:87331 1554994795831 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/GMPProvider.jsm
0:02.29 pid:87331 1554994795836 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/PluginProvider.jsm
0:02.29 pid:87331 1554994795836 addons.manager DEBUG Starting provider: XPIProvider
0:02.29 pid:87331 1554994795836 addons.xpi DEBUG startup
0:02.29 pid:87331 1554994795837 addons.xpi WARN List of valid built-in add-ons could not be parsed.: [Exception... "Component returned failure code: 0xc1f30001 (NS_ERROR_NOT_INITIALIZED) [nsIXPCComponents_Utils.readUTF8URI]" nsresult: "0xc1f30001 (NS_ERROR_NOT_INITIALIZED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm :: startup :: line 2243" data: no] Stack trace: startup()@resource://gre/modules/addons/XPIProvider.jsm:2243
0:02.29 pid:87331 callProvider()@resource://gre/modules/AddonManager.jsm:200
0:02.29 pid:87331 _startProvider()@resource://gre/modules/AddonManager.jsm:651
0:02.29 pid:87331 startup()@resource://gre/modules/AddonManager.jsm:807
0:02.29 pid:87331 startup()@resource://gre/modules/AddonManager.jsm:2801
0:02.29 pid:87331 observe()@resource://gre/modules/addonManager.js:65
0:02.29 pid:87331 promiseStartupManager()@resource://testing-common/AddonTestUtils.jsm:747
0:02.29 pid:87331 setup()@/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell/test_webextensions_install.js:59
0:02.29 pid:87331 run_next_test/_run_next_test/<()@/Users/dharvey/src/gecko/testing/xpcshell/head.js:1437
0:02.29 pid:87331 _run_next_test()@/Users/dharvey/src/gecko/testing/xpcshell/head.js:1437
0:02.29 pid:87331 run()@/Users/dharvey/src/gecko/testing/xpcshell/head.js:688
0:02.29 pid:87331 _do_main()@/Users/dharvey/src/gecko/testing/xpcshell/head.js:227
0:02.29 pid:87331 _execute_test()@/Users/dharvey/src/gecko/testing/xpcshell/head.js:529
0:02.29 pid:87331 -e:1
0:02.29 pid:87331 1554994795839 addons.xpi INFO SystemAddonLocation directory is missing
0:02.30 pid:87331 1554994795848 addons.xpi INFO Removing all system add-on upgrades.
0:02.30 pid:87331 1554994795848 addons.xpi DEBUG checkForChanges
0:02.30 pid:87331 1554994795848 addons.xpi DEBUG Loaded add-on state: ${}
0:02.30 pid:87331 1554994795849 addons.xpi DEBUG scanForChanges changed: false, state: {}
0:02.30 pid:87331 1554994795849 addons.xpi DEBUG Empty XPI database, setting schema version preference to 29
0:02.30 pid:87331 1554994795849 addons.xpi DEBUG No changes found
0:02.32 pid:87331 1554994795862 addons.xpi-utils DEBUG Error: Synchronously loading the add-ons database(resource://gre/modules/addons/XPIDatabase.jsm:1351:15) JS Stack trace: [email protected]:1351:15
0:02.32 pid:87331 [email protected]:2289:23
0:02.32 pid:87331 [email protected]:200:31
0:02.32 pid:87331 [email protected]:651:5
0:02.32 pid:87331 [email protected]:807:14
0:02.32 pid:87331 [email protected]:2801:26
0:02.32 pid:87331 [email protected]:65:29
0:02.32 pid:87331 [email protected]:747:34
0:02.32 pid:87331 setup@test_webextensions_install.js:59:9
0:02.32 pid:87331 run_next_test/_run_next_test/<@head.js:1437:22
0:02.32 pid:87331 [email protected]:1437:38
0:02.32 pid:87331 [email protected]:688:9
0:02.32 pid:87331 [email protected]:227:6
0:02.32 pid:87331 [email protected]:529:5
0:02.32 pid:87331 @-e:1:1
0:02.32 pid:87331 1554994795863 addons.xpi-utils DEBUG Starting async load of XPI database /var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/extensions.json
0:02.32 INFO "CONSOLE_MESSAGE: (info) 1554994795794 addons.manager DEBUG Application has been upgraded"
0:02.32 INFO "CONSOLE_MESSAGE: (info) 1554994795821 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/XPIProvider.jsm: ["XPIProvider", "XPIInternal"]"
0:02.32 INFO "CONSOLE_MESSAGE: (info) 1554994795831 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/GMPProvider.jsm"
0:02.32 INFO "CONSOLE_MESSAGE: (info) 1554994795836 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/PluginProvider.jsm"
0:02.32 INFO "CONSOLE_MESSAGE: (info) 1554994795836 addons.manager DEBUG Starting provider: XPIProvider"
0:02.32 INFO "CONSOLE_MESSAGE: (info) 1554994795836 addons.xpi DEBUG startup"
0:02.32 INFO "CONSOLE_MESSAGE: (info) 1554994795837 addons.xpi WARN List of valid built-in add-ons could not be parsed.: [Exception... "Component returned failure code: 0xc1f30001 (NS_ERROR_NOT_INITIALIZED) [nsIXPCComponents_Utils.readUTF8URI]" nsresult: "0xc1f30001 (NS_ERROR_NOT_INITIALIZED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm :: startup :: line 2243" data: no] Stack trace: startup()@resource://gre/modules/addons/XPIProvider.jsm:2243
callProvider()@resource://gre/modules/AddonManager.jsm:200
_startProvider()@resource://gre/modules/AddonManager.jsm:651
startup()@resource://gre/modules/AddonManager.jsm:807
startup()@resource://gre/modules/AddonManager.jsm:2801
observe()@resource://gre/modules/addonManager.js:65
promiseStartupManager()@resource://testing-common/AddonTestUtils.jsm:747
setup()@/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell/test_webextensions_install.js:59
run_next_test/_run_next_test/<()@/Users/dharvey/src/gecko/testing/xpcshell/head.js:1437
_run_next_test()@/Users/dharvey/src/gecko/testing/xpcshell/head.js:1437
run()@/Users/dharvey/src/gecko/testing/xpcshell/head.js:688
_do_main()@/Users/dharvey/src/gecko/testing/xpcshell/head.js:227
_execute_test()@/Users/dharvey/src/gecko/testing/xpcshell/head.js:529
-e:1"
0:02.32 INFO "CONSOLE_MESSAGE: (info) 1554994795839 addons.xpi INFO SystemAddonLocation directory is missing"
0:02.32 INFO "CONSOLE_MESSAGE: (info) 1554994795848 addons.xpi INFO Removing all system add-on upgrades."
0:02.32 INFO "CONSOLE_MESSAGE: (info) 1554994795848 addons.xpi DEBUG checkForChanges"
0:02.32 INFO "CONSOLE_MESSAGE: (info) 1554994795848 addons.xpi DEBUG Loaded add-on state: ${}"
0:02.32 INFO "CONSOLE_MESSAGE: (info) 1554994795849 addons.xpi DEBUG scanForChanges changed: false, state: {}"
0:02.32 INFO "CONSOLE_MESSAGE: (info) 1554994795849 addons.xpi DEBUG Empty XPI database, setting schema version preference to 29"
0:02.32 INFO "CONSOLE_MESSAGE: (info) 1554994795849 addons.xpi DEBUG No changes found"
0:02.32 INFO "CONSOLE_MESSAGE: (info) 1554994795862 addons.xpi-utils DEBUG Error: Synchronously loading the add-ons database(resource://gre/modules/addons/XPIDatabase.jsm:1351:15) JS Stack trace: [email protected]:1351:15
[email protected]:2289:23
[email protected]:200:31
[email protected]:651:5
[email protected]:807:14
[email protected]:2801:26
[email protected]:65:29
[email protected]:747:34
setup@test_webextensions_install.js:59:9
run_next_test/_run_next_test/<@head.js:1437:22
[email protected]:1437:38
[email protected]:688:9
[email protected]:227:6
[email protected]:529:5
@-e:1:1"
0:02.32 INFO "CONSOLE_MESSAGE: (info) 1554994795863 addons.xpi-utils DEBUG Starting async load of XPI database /var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/extensions.json"
0:02.40 pid:87331 1554994795952 addons.xpi-utils DEBUG Rebuilding XPI database with no extensions
0:02.41 pid:87331 1554994795954 addons.manager DEBUG Registering shutdown blocker for XPIProvider
0:02.41 pid:87331 1554994795954 addons.manager DEBUG Provider finished startup: XPIProvider
0:02.41 pid:87331 1554994795955 addons.manager DEBUG Starting provider: GMPProvider
0:02.41 pid:87331 1554994795956 addons.manager DEBUG Registering shutdown blocker for GMPProvider
0:02.41 pid:87331 1554994795957 addons.manager DEBUG Provider finished startup: GMPProvider
0:02.41 pid:87331 1554994795957 addons.manager DEBUG Starting provider: PluginProvider
0:02.41 pid:87331 1554994795957 addons.manager DEBUG Registering shutdown blocker for PluginProvider
0:02.41 pid:87331 1554994795957 addons.manager DEBUG Provider finished startup: PluginProvider
0:02.41 pid:87331 1554994795961 addons.manager DEBUG Completed startup sequence
0:02.41 INFO (xpcshell/head.js) | test run_next_test 0 finished (2)
0:02.42 INFO "CONSOLE_MESSAGE: (info) 1554994795952 addons.xpi-utils DEBUG Rebuilding XPI database with no extensions"
0:02.42 INFO "CONSOLE_MESSAGE: (info) 1554994795954 addons.manager DEBUG Registering shutdown blocker for XPIProvider"
0:02.42 INFO "CONSOLE_MESSAGE: (info) 1554994795954 addons.manager DEBUG Provider finished startup: XPIProvider"
0:02.42 INFO "CONSOLE_MESSAGE: (info) 1554994795955 addons.manager DEBUG Starting provider: GMPProvider"
0:02.42 INFO "CONSOLE_MESSAGE: (info) 1554994795956 addons.manager DEBUG Registering shutdown blocker for GMPProvider"
0:02.42 INFO "CONSOLE_MESSAGE: (info) 1554994795957 addons.manager DEBUG Provider finished startup: GMPProvider"
0:02.42 INFO "CONSOLE_MESSAGE: (info) 1554994795957 addons.manager DEBUG Starting provider: PluginProvider"
0:02.42 INFO "CONSOLE_MESSAGE: (info) 1554994795957 addons.manager DEBUG Registering shutdown blocker for PluginProvider"
0:02.42 INFO "CONSOLE_MESSAGE: (info) 1554994795957 addons.manager DEBUG Provider finished startup: PluginProvider"
0:02.42 INFO "CONSOLE_MESSAGE: (info) 1554994795961 addons.manager DEBUG Completed startup sequence"
0:02.42 INFO (xpcshell/head.js) | test run_next_test 1 pending (2)
0:02.42 INFO (xpcshell/head.js) | test setup finished (2)
0:02.42 INFO toolkit/components/search/tests/xpcshell/test_webextensions_install.js | Starting basic_install_test
0:02.42 INFO (xpcshell/head.js) | test basic_install_test pending (2)
0:02.43 pid:87331 *** Search: SearchService.init
0:02.43 pid:87331 *** Search: _init start
0:02.43 INFO (xpcshell/head.js) | test run_next_test 1 finished (2)
0:02.43 INFO "CONSOLE_MESSAGE: (info) SearchService.init"
0:02.43 INFO "CONSOLE_MESSAGE: (info) _init start"
0:02.44 pid:87331 *** Search: _readCacheFile: Error reading cache file: Unix error 2 during operation open on file /var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/search.json.mozlz4 (No such file or directory)
0:02.44 pid:87331 *** Search: _fetchRegion starting with endpoint
0:02.44 pid:87331 *** Search: _loadEngines: start
0:02.44 pid:87331 *** Search: _findEngines: looking for engines in JARs
0:02.44 INFO "CONSOLE_MESSAGE: (info) _readCacheFile: Error reading cache file: Unix error 2 during operation open on file /var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/search.json.mozlz4 (No such file or directory)"
0:02.44 INFO "CONSOLE_MESSAGE: (info) _fetchRegion starting with endpoint "
0:02.44 INFO "CONSOLE_MESSAGE: (info) _loadEngines: start"
0:02.44 INFO "CONSOLE_MESSAGE: (info) _findEngines: looking for engines in JARs"
0:02.44 pid:87331 *** Search: _loadEngines: Absent or outdated cache. Loading engines from disk.
0:02.44 pid:87331 *** Search: _loadEngines: Installing [email protected]
0:02.44 INFO "CONSOLE_MESSAGE: (error) [JavaScript Error: "parseListJSON: No searchDefault" {file: "resource://gre/modules/SearchService.jsm" line: 3485}]
SRCH_SVC_parseListJSON@resource://gre/modules/SearchService.jsm:3485:10
_findEngines@resource://gre/modules/SearchService.jsm:3342:10
async*_loadEngines@resource://gre/modules/SearchService.jsm:2801:30
_init@resource://gre/modules/SearchService.jsm:2646:18
async*init@resource://gre/modules/SearchService.jsm:3653:18
basic_install_test@/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell/test_webextensions_install.js:70:25
run_next_test/_run_next_test/<@/Users/dharvey/src/gecko/testing/xpcshell/head.js:1437:22
_run_next_test@/Users/dharvey/src/gecko/testing/xpcshell/head.js:1437:38
run@/Users/dharvey/src/gecko/testing/xpcshell/head.js:688:9
_do_main@/Users/dharvey/src/gecko/testing/xpcshell/head.js:227:6
_execute_test@/Users/dharvey/src/gecko/testing/xpcshell/head.js:529:5
@-e:1:1
"
0:02.44 INFO "CONSOLE_MESSAGE: (info) _loadEngines: Absent or outdated cache. Loading engines from disk."
0:02.44 INFO "CONSOLE_MESSAGE: (info) _loadEngines: Installing [email protected]"
0:02.61 pid:87331 1554994796156 addons.xpi-utils DEBUG Make addon app-builtin:[email protected] visible
0:02.61 pid:87331 1554994796156 addons.xpi DEBUG XPIStates adding add-on [email protected] in {"addons":{},"staged":{}}: null
0:02.61 pid:87331 JavaScript strict warning: resource://gre/modules/addons/XPIProvider.jsm, line 574: ReferenceError: reference to undefined property "version"
0:02.61 pid:87331 JavaScript strict warning: resource://gre/modules/addons/XPIProvider.jsm, line 470: ReferenceError: reference to undefined property "currentModifiedTime"
0:02.61 pid:87331 1554994796157 addons.xpi DEBUG Updating XPIState for {"id":"[email protected]","syncGUID":"{8f1ad06f-6c5f-704e-ba8a-4dcf6f706d3f}","version":"1.0","type":"extension","loader":null,"updateURL":null,"optionsURL":null,"optionsType":null,"optionsBrowserStyle":true,"aboutURL":null,"defaultLocale":{"name":"Plain","description":"Plain Engine","creator":null,"developers":null,"translators":null,"contributors":null},"visible":true,"active":true,"userDisabled":false,"appDisabled":false,"installDate":1554994796156,"applyBackgroundUpdates":1,"path":null,"skinnable":false,"sourceURI":null,"releaseNotesURI":null,"softDisabled":false,"foreignInstall":false,"strictCompatibility":true,"locales":[],"targetApplications":[{"id":"[email protected]","minVersion":null,"maxVersion":null}],"targetPlatforms":[],"seen":true,"dependencies":[],"incognito":"spanning","userPermissions":{"permissions":[],"origins":[]},"icons":{"16":"favicon.ico"},"iconURL":null,"blocklistState":0,"blocklistURL":null,"startupData":null,"hidden":true,"installTelemetryInfo":null,"rootURI":"resource://search-extensions/plainengine/","location":"app-builtin"}
0:02.61 pid:87331 1554994796158 addons.xpi DEBUG Loading bootstrap scope from resource://search-extensions/plainengine/
0:02.61 pid:87331 1554994796158 addons.xpi DEBUG Calling bootstrap method install on [email protected] version 1.0
0:02.61 pid:87331 1554994796158 addons.xpi DEBUG Calling bootstrap method startup on [email protected] version 1.0
0:02.61 INFO "CONSOLE_MESSAGE: (info) 1554994796156 addons.xpi-utils DEBUG Make addon app-builtin:[email protected] visible"
0:02.61 INFO "CONSOLE_MESSAGE: (info) 1554994796156 addons.xpi DEBUG XPIStates adding add-on [email protected] in {"addons":{},"staged":{}}: null"
0:02.61 INFO "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "ReferenceError: reference to undefined property "version"" {file: "resource://gre/modules/addons/XPIProvider.jsm" line: 574}]"
0:02.61 INFO "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "ReferenceError: reference to undefined property "currentModifiedTime"" {file: "resource://gre/modules/addons/XPIProvider.jsm" line: 470}]"
0:02.61 INFO "CONSOLE_MESSAGE: (info) 1554994796157 addons.xpi DEBUG Updating XPIState for {"id":"[email protected]","syncGUID":"{8f1ad06f-6c5f-704e-ba8a-4dcf6f706d3f}","version":"1.0","type":"extension","loader":null,"updateURL":null,"optionsURL":null,"optionsType":null,"optionsBrowserStyle":true,"aboutURL":null,"defaultLocale":{"name":"Plain","description":"Plain Engine","creator":null,"developers":null,"translators":null,"contributors":null},"visible":true,"active":true,"userDisabled":false,"appDisabled":false,"installDate":1554994796156,"applyBackgroundUpdates":1,"path":null,"skinnable":false,"sourceURI":null,"releaseNotesURI":null,"softDisabled":false,"foreignInstall":false,"strictCompatibility":true,"locales":[],"targetApplications":[{"id":"[email protected]","minVersion":null,"maxVersion":null}],"targetPlatforms":[],"seen":true,"dependencies":[],"incognito":"spanning","userPermissions":{"permissions":[],"origins":[]},"icons":{"16":"favicon.ico"},"iconURL":null,"blocklistState":0,"blocklistURL":null,"startupData":null,"hidden":true,"installTelemetryInfo":null,"rootURI":"resource://search-extensions/plainengine/","location":"app-builtin"}"
0:02.61 INFO "CONSOLE_MESSAGE: (info) 1554994796158 addons.xpi DEBUG Loading bootstrap scope from resource://search-extensions/plainengine/"
0:02.61 INFO "CONSOLE_MESSAGE: (info) 1554994796158 addons.xpi DEBUG Calling bootstrap method install on [email protected] version 1.0"
0:02.61 INFO "CONSOLE_MESSAGE: (info) 1554994796158 addons.xpi DEBUG Calling bootstrap method startup on [email protected] version 1.0"
0:02.75 pid:87331 JavaScript strict warning: resource://gre/modules/ExtensionSettingsStore.jsm, line 64: ReferenceError: reference to undefined property "version"
0:02.75 pid:87331 *** Search: addEnginesFromExtension: [email protected]
0:02.75 pid:87331 *** Search: addEnginesFromExtension: installing locale: [email protected]:default
0:02.75 pid:87331 *** Search: _setIcon: Setting icon url "moz-extension://8df0018e-6cae-814c-9248-2e117c3d669a/favicon.ico" for engine "Plain".
0:02.75 pid:87331 JavaScript strict warning: resource://gre/modules/SearchService.jsm, line 1799: ReferenceError: reference to undefined property "size"
0:02.75 pid:87331 *** Search: _addEngineToStore: Adding engine: "Plain"
0:02.75 pid:87331 *** Search: _loadEngines: [email protected] installed
0:02.75 pid:87331 *** Search: _loadEngines: Installing [email protected]
0:02.64 INFO "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "ReferenceError: reference to undefined property "version"" {file: "resource://gre/modules/ExtensionSettingsStore.jsm" line: 64}]"
0:02.64 INFO "CONSOLE_MESSAGE: (info) addEnginesFromExtension: [email protected]"
0:02.64 INFO "CONSOLE_MESSAGE: (info) addEnginesFromExtension: installing locale: [email protected]:default"
0:02.64 INFO "CONSOLE_MESSAGE: (info) _setIcon: Setting icon url "moz-extension://8df0018e-6cae-814c-9248-2e117c3d669a/favicon.ico" for engine "Plain"."
0:02.64 INFO "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "ReferenceError: reference to undefined property "size"" {file: "resource://gre/modules/SearchService.jsm" line: 1799}]"
0:02.64 INFO "CONSOLE_MESSAGE: (info) _addEngineToStore: Adding engine: "Plain""
0:02.64 INFO "CONSOLE_MESSAGE: (info) _loadEngines: [email protected] installed"
0:02.64 INFO "CONSOLE_MESSAGE: (info) _loadEngines: Installing [email protected]"
0:02.75 pid:87331 1554994796195 addons.xpi-utils DEBUG XPI Database saved, setting schema version preference to 29
0:02.65 INFO "CONSOLE_MESSAGE: (info) 1554994796195 addons.xpi-utils DEBUG XPI Database saved, setting schema version preference to 29"
0:02.75 pid:87331 1554994796197 addons.xpi-utils DEBUG Make addon app-builtin:[email protected] visible
0:02.75 pid:87331 1554994796197 addons.xpi DEBUG XPIStates adding add-on [email protected] in {"addons":{"[email protected]":{"dependencies":[],"enabled":true,"lastModifiedTime":1554727954946,"loader":null,"path":null,"rootURI":"resource://search-extensions/plainengine/","runInSafeMode":false,"telemetryKey":"plainengine%40search.mozilla.org:1.0","version":"1.0"}},"staged":{}}: null
0:02.75 pid:87331 1554994796198 addons.xpi DEBUG Updating XPIState for {"id":"[email protected]","syncGUID":"{ab083c1a-b025-e14f-a69f-dea955f77c5d}","version":"1.0","type":"extension","loader":null,"updateURL":null,"optionsURL":null,"optionsType":null,"optionsBrowserStyle":true,"aboutURL":null,"defaultLocale":{"name":"Special","description":"Special Engine","creator":null,"developers":null,"translators":null,"contributors":null},"visible":true,"active":true,"userDisabled":false,"appDisabled":false,"installDate":1554994796197,"applyBackgroundUpdates":1,"path":null,"skinnable":false,"sourceURI":null,"releaseNotesURI":null,"softDisabled":false,"foreignInstall":false,"strictCompatibility":true,"locales":[],"targetApplications":[{"id":"[email protected]","minVersion":null,"maxVersion":null}],"targetPlatforms":[],"seen":true,"dependencies":[],"incognito":"spanning","userPermissions":{"permissions":[],"origins":[]},"icons":{"16":"favicon.ico"},"iconURL":null,"blocklistState":0,"blocklistURL":null,"startupData":null,"hidden":true,"installTelemetryInfo":null,"rootURI":"resource://search-extensions/special-engine/","location":"app-builtin"}
0:02.75 pid:87331 1554994796199 addons.xpi DEBUG Loading bootstrap scope from resource://search-extensions/special-engine/
0:02.75 pid:87331 1554994796199 addons.xpi DEBUG Calling bootstrap method install on [email protected] version 1.0
0:02.75 pid:87331 1554994796199 addons.xpi DEBUG Calling bootstrap method startup on [email protected] version 1.0
0:02.65 INFO "CONSOLE_MESSAGE: (info) 1554994796197 addons.xpi-utils DEBUG Make addon app-builtin:[email protected] visible"
0:02.65 INFO "CONSOLE_MESSAGE: (info) 1554994796197 addons.xpi DEBUG XPIStates adding add-on [email protected] in {"addons":{"[email protected]":{"dependencies":[],"enabled":true,"lastModifiedTime":1554727954946,"loader":null,"path":null,"rootURI":"resource://search-extensions/plainengine/","runInSafeMode":false,"telemetryKey":"plainengine%40search.mozilla.org:1.0","version":"1.0"}},"staged":{}}: null"
0:02.65 INFO "CONSOLE_MESSAGE: (info) 1554994796198 addons.xpi DEBUG Updating XPIState for {"id":"[email protected]","syncGUID":"{ab083c1a-b025-e14f-a69f-dea955f77c5d}","version":"1.0","type":"extension","loader":null,"updateURL":null,"optionsURL":null,"optionsType":null,"optionsBrowserStyle":true,"aboutURL":null,"defaultLocale":{"name":"Special","description":"Special Engine","creator":null,"developers":null,"translators":null,"contributors":null},"visible":true,"active":true,"userDisabled":false,"appDisabled":false,"installDate":1554994796197,"applyBackgroundUpdates":1,"path":null,"skinnable":false,"sourceURI":null,"releaseNotesURI":null,"softDisabled":false,"foreignInstall":false,"strictCompatibility":true,"locales":[],"targetApplications":[{"id":"[email protected]","minVersion":null,"maxVersion":null}],"targetPlatforms":[],"seen":true,"dependencies":[],"incognito":"spanning","userPermissions":{"permissions":[],"origins":[]},"icons":{"16":"favicon.ico"},"iconURL":null,"blocklistState":0,"blocklistURL":null,"startupData":null,"hidden":true,"installTelemetryInfo":null,"rootURI":"resource://search-extensions/special-engine/","location":"app-builtin"}"
0:02.65 INFO "CONSOLE_MESSAGE: (info) 1554994796199 addons.xpi DEBUG Loading bootstrap scope from resource://search-extensions/special-engine/"
0:02.65 INFO "CONSOLE_MESSAGE: (info) 1554994796199 addons.xpi DEBUG Calling bootstrap method install on [email protected] version 1.0"
0:02.65 INFO "CONSOLE_MESSAGE: (info) 1554994796199 addons.xpi DEBUG Calling bootstrap method startup on [email protected] version 1.0"
0:02.76 pid:87331 *** Search: addEnginesFromExtension: [email protected]
0:02.76 pid:87331 *** Search: addEnginesFromExtension: installing locale: [email protected]:default
0:02.76 pid:87331 *** Search: _setIcon: Setting icon url "moz-extension://f4882ddf-80e0-844b-b27d-e5ca4e866cf4/favicon.ico" for engine "Special".
0:02.76 pid:87331 *** Search: _addEngineToStore: Adding engine: "Special"
0:02.76 pid:87331 *** Search: _loadEngines: [email protected] installed
0:02.76 pid:87331 *** Search: _loadEngines: loading user-installed engines from the obsolete cache
0:02.76 pid:87331 *** Search: _loadEngines: done using rebuilt cache
0:02.76 pid:87331 *** Search: _buildCache: Writing to cache file.
0:02.76 pid:87331 *** Search: _init: Completed _init
0:02.67 INFO "CONSOLE_MESSAGE: (info) addEnginesFromExtension: [email protected]"
0:02.67 INFO "CONSOLE_MESSAGE: (info) addEnginesFromExtension: installing locale: [email protected]:default"
0:02.67 INFO "CONSOLE_MESSAGE: (info) _setIcon: Setting icon url "moz-extension://f4882ddf-80e0-844b-b27d-e5ca4e866cf4/favicon.ico" for engine "Special"."
0:02.67 INFO "CONSOLE_MESSAGE: (info) _addEngineToStore: Adding engine: "Special""
0:02.67 INFO "CONSOLE_MESSAGE: (info) _loadEngines: [email protected] installed"
0:02.67 INFO "CONSOLE_MESSAGE: (info) _loadEngines: loading user-installed engines from the obsolete cache"
0:02.67 INFO "CONSOLE_MESSAGE: (info) _loadEngines: done using rebuilt cache"
0:02.67 INFO "CONSOLE_MESSAGE: (info) _buildCache: Writing to cache file."
0:02.67 INFO "CONSOLE_MESSAGE: (info) _init: Completed _init"
0:02.77 pid:87331 *** Search: _buildCache: cache file written to disk.
0:02.77 pid:87331 *** Search: SearchService.init
0:02.77 pid:87331 *** Search: getEngines: getting all engines
0:02.77 pid:87331 *** Search: _buildSortedEngineList: building list
0:02.77 pid:87331 JavaScript strict warning: resource://gre/modules/SearchService.jsm, line 2735: ReferenceError: reference to undefined property 0
0:02.67 PASS basic_install_test - [basic_install_test : 74] ["Plain","Special"] deepEqual ["Plain","Special"]
0:02.67 INFO "CONSOLE_MESSAGE: (info) _buildCache: cache file written to disk."
0:02.77 pid:87331 1554994796216 addons.manager DEBUG shutdown
0:02.77 pid:87331 1554994796218 addons.manager DEBUG Calling shutdown blocker for XPIProvider
0:02.77 pid:87331 1554994796218 addons.xpi DEBUG shutdown
0:02.77 pid:87331 1554994796218 addons.xpi-utils DEBUG shutdown
0:02.77 pid:87331 1554994796218 addons.manager DEBUG Calling shutdown blocker for GMPProvider
0:02.77 pid:87331 1554994796218 addons.manager DEBUG Calling shutdown blocker for PluginProvider
0:02.67 INFO "CONSOLE_MESSAGE: (info) SearchService.init"
0:02.67 INFO "CONSOLE_MESSAGE: (info) getEngines: getting all engines"
0:02.67 INFO "CONSOLE_MESSAGE: (info) _buildSortedEngineList: building list"
0:02.67 INFO "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "ReferenceError: reference to undefined property 0" {file: "resource://gre/modules/SearchService.jsm" line: 2735}]"
0:02.67 INFO "CONSOLE_MESSAGE: (info) 1554994796216 addons.manager DEBUG shutdown"
0:02.67 INFO "CONSOLE_MESSAGE: (info) 1554994796218 addons.manager DEBUG Calling shutdown blocker for XPIProvider"
0:02.67 INFO "CONSOLE_MESSAGE: (info) 1554994796218 addons.xpi DEBUG shutdown"
0:02.67 INFO "CONSOLE_MESSAGE: (info) 1554994796218 addons.xpi-utils DEBUG shutdown"
0:02.67 INFO "CONSOLE_MESSAGE: (info) 1554994796218 addons.manager DEBUG Calling shutdown blocker for GMPProvider"
0:02.67 INFO "CONSOLE_MESSAGE: (info) 1554994796218 addons.manager DEBUG Calling shutdown blocker for PluginProvider"
0:02.78 pid:87331 1554994796222 addons.manager DEBUG Async provider shutdown done
0:02.78 pid:87331 1554994796224 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/XPIProvider.jsm: ["XPIProvider", "XPIInternal"]
0:02.78 pid:87331 1554994796224 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/GMPProvider.jsm
0:02.78 pid:87331 1554994796224 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/PluginProvider.jsm
0:02.78 pid:87331 1554994796224 addons.manager DEBUG Starting provider: GMPProvider
0:02.78 pid:87331 1554994796225 addons.manager DEBUG Registering shutdown blocker for GMPProvider
0:02.78 pid:87331 1554994796225 addons.manager DEBUG Provider finished startup: GMPProvider
0:02.78 pid:87331 1554994796225 addons.manager DEBUG Starting provider: PluginProvider
0:02.78 pid:87331 1554994796225 addons.manager DEBUG Registering shutdown blocker for PluginProvider
0:02.78 pid:87331 1554994796225 addons.manager DEBUG Provider finished startup: PluginProvider
0:02.78 pid:87331 1554994796225 addons.manager DEBUG Starting provider: XPIProvider
0:02.78 pid:87331 1554994796225 addons.xpi DEBUG startup
0:02.78 pid:87331 1554994796225 addons.xpi WARN List of valid built-in add-ons could not be parsed.: [Exception... "Component returned failure code: 0xc1f30001 (NS_ERROR_NOT_INITIALIZED) [nsIXPCComponents_Utils.readUTF8URI]" nsresult: "0xc1f30001 (NS_ERROR_NOT_INITIALIZED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm :: startup :: line 2243" data: no] Stack trace: startup()@resource://gre/modules/addons/XPIProvider.jsm:2243
0:02.78 pid:87331 callProvider()@resource://gre/modules/AddonManager.jsm:200
0:02.78 pid:87331 _startProvider()@resource://gre/modules/AddonManager.jsm:651
0:02.78 pid:87331 startup()@resource://gre/modules/AddonManager.jsm:807
0:02.78 pid:87331 startup()@resource://gre/modules/AddonManager.jsm:2801
0:02.78 pid:87331 observe()@resource://gre/modules/addonManager.js:65
0:02.78 pid:87331 promiseStartupManager()@resource://testing-common/AddonTestUtils.jsm:747
0:02.78 pid:87331 promiseRestartManager()@resource://testing-common/AddonTestUtils.jsm:825
0:02.78 pid:87331 1554994796226 addons.xpi INFO SystemAddonLocation directory is missing
0:02.78 pid:87331 1554994796227 addons.xpi DEBUG checkForChanges
0:02.78 pid:87331 1554994796228 addons.xpi DEBUG Loaded add-on state: {}
0:02.78 pid:87331 1554994796229 addons.xpi DEBUG scanForChanges changed: false, state: {}
0:02.78 pid:87331 1554994796229 addons.xpi DEBUG No changes found
0:02.78 pid:87331 1554994796229 addons.manager DEBUG Registering shutdown blocker for XPIProvider
0:02.78 pid:87331 1554994796230 addons.manager DEBUG Provider finished startup: XPIProvider
0:02.78 pid:87331 1554994796231 addons.manager DEBUG Completed startup sequence
0:02.69 INFO "CONSOLE_MESSAGE: (info) 1554994796222 addons.manager DEBUG Async provider shutdown done"
0:02.69 INFO "CONSOLE_MESSAGE: (info) 1554994796224 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/XPIProvider.jsm: ["XPIProvider", "XPIInternal"]"
0:02.69 INFO "CONSOLE_MESSAGE: (info) 1554994796224 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/GMPProvider.jsm"
0:02.69 INFO "CONSOLE_MESSAGE: (info) 1554994796224 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/PluginProvider.jsm"
0:02.69 INFO "CONSOLE_MESSAGE: (info) 1554994796224 addons.manager DEBUG Starting provider: GMPProvider"
0:02.69 INFO "CONSOLE_MESSAGE: (info) 1554994796225 addons.manager DEBUG Registering shutdown blocker for GMPProvider"
0:02.69 INFO "CONSOLE_MESSAGE: (info) 1554994796225 addons.manager DEBUG Provider finished startup: GMPProvider"
0:02.69 INFO "CONSOLE_MESSAGE: (info) 1554994796225 addons.manager DEBUG Starting provider: PluginProvider"
0:02.69 INFO "CONSOLE_MESSAGE: (info) 1554994796225 addons.manager DEBUG Registering shutdown blocker for PluginProvider"
0:02.69 INFO "CONSOLE_MESSAGE: (info) 1554994796225 addons.manager DEBUG Provider finished startup: PluginProvider"
0:02.69 INFO "CONSOLE_MESSAGE: (info) 1554994796225 addons.manager DEBUG Starting provider: XPIProvider"
0:02.69 INFO "CONSOLE_MESSAGE: (info) 1554994796225 addons.xpi DEBUG startup"
0:02.69 INFO "CONSOLE_MESSAGE: (info) 1554994796225 addons.xpi WARN List of valid built-in add-ons could not be parsed.: [Exception... "Component returned failure code: 0xc1f30001 (NS_ERROR_NOT_INITIALIZED) [nsIXPCComponents_Utils.readUTF8URI]" nsresult: "0xc1f30001 (NS_ERROR_NOT_INITIALIZED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm :: startup :: line 2243" data: no] Stack trace: startup()@resource://gre/modules/addons/XPIProvider.jsm:2243
callProvider()@resource://gre/modules/AddonManager.jsm:200
_startProvider()@resource://gre/modules/AddonManager.jsm:651
startup()@resource://gre/modules/AddonManager.jsm:807
startup()@resource://gre/modules/AddonManager.jsm:2801
observe()@resource://gre/modules/addonManager.js:65
promiseStartupManager()@resource://testing-common/AddonTestUtils.jsm:747
promiseRestartManager()@resource://testing-common/AddonTestUtils.jsm:825"
0:02.69 INFO "CONSOLE_MESSAGE: (info) 1554994796226 addons.xpi INFO SystemAddonLocation directory is missing"
0:02.69 INFO "CONSOLE_MESSAGE: (info) 1554994796227 addons.xpi DEBUG checkForChanges"
0:02.69 INFO "CONSOLE_MESSAGE: (info) 1554994796228 addons.xpi DEBUG Loaded add-on state: {}"
0:02.69 INFO "CONSOLE_MESSAGE: (info) 1554994796229 addons.xpi DEBUG scanForChanges changed: false, state: {}"
0:02.69 INFO "CONSOLE_MESSAGE: (info) 1554994796229 addons.xpi DEBUG No changes found"
0:02.69 INFO "CONSOLE_MESSAGE: (info) 1554994796229 addons.manager DEBUG Registering shutdown blocker for XPIProvider"
0:02.69 INFO "CONSOLE_MESSAGE: (info) 1554994796230 addons.manager DEBUG Provider finished startup: XPIProvider"
0:02.69 INFO "CONSOLE_MESSAGE: (info) 1554994796231 addons.manager DEBUG Completed startup sequence"
0:02.79 pid:87331 *** Search: _reInit
0:02.69 INFO "CONSOLE_MESSAGE: (info) _reInit"
0:02.79 pid:87331 *** Search: _fetchRegion starting with endpoint data:application/json,{"country_code": "FR"}
0:02.69 INFO "CONSOLE_MESSAGE: (info) _fetchRegion starting with endpoint data:application/json,{"country_code": "FR"}"
0:02.79 pid:87331 *** Search: _fetchRegion got success response in 0ms: FR
0:02.80 pid:87331 *** Search: fetchRegionDefault starting with endpoint http://localhost:49558/lookup_defaults?
0:02.70 INFO "CONSOLE_MESSAGE: (info) _fetchRegion got success response in 0ms: FR"
0:02.70 INFO "CONSOLE_MESSAGE: (error) [JavaScript Error: "Platform XPCShell has system country code but no search service telemetry probes" {file: "resource://gre/modules/SearchService.jsm" line: 467}]
storeRegion@resource://gre/modules/SearchService.jsm:467:12
resolveAndReportSuccess@resource://gre/modules/SearchService.jsm:526:9
fetchRegion/</request.onload@resource://gre/modules/SearchService.jsm:568:7
_do_main@/Users/dharvey/src/gecko/testing/xpcshell/head.js:227:6
_execute_test@/Users/dharvey/src/gecko/testing/xpcshell/head.js:529:5
@-e:1:1
"
0:02.70 INFO "CONSOLE_MESSAGE: (info) fetchRegionDefault starting with endpoint http://localhost:49558/lookup_defaults?"
0:02.80 pid:87331 *** Search: received ({interval:31536000, settings:{searchDefault:"Test search engine", visibleDefaultEngines:["special-engine"]}})
0:02.80 pid:87331 *** Search: fetchRegionDefault saved searchDefault: Test search engine
0:02.80 pid:87331 *** Search: fetchRegionDefault saved visibleDefaultEngines: special-engine
0:02.80 pid:87331 *** Search: fetchRegionDefault got success response in 32ms
0:02.80 pid:87331 WTF _MAYBERELOADENGINES
0:02.80 pid:87331 WTF _MAYBERELOADENGINES RETURN
0:02.80 pid:87331 *** Search: _loadEngines: start
0:02.80 pid:87331 *** Search: _findEngines: looking for engines in JARs
0:02.73 INFO "CONSOLE_MESSAGE: (info) received ({interval:31536000, settings:{searchDefault:"Test search engine", visibleDefaultEngines:["special-engine"]}})"
0:02.73 INFO "CONSOLE_MESSAGE: (info) fetchRegionDefault saved searchDefault: Test search engine"
0:02.73 INFO "CONSOLE_MESSAGE: (info) fetchRegionDefault saved visibleDefaultEngines: special-engine"
0:02.73 INFO "CONSOLE_MESSAGE: (info) fetchRegionDefault got success response in 32ms"
0:02.73 INFO "CONSOLE_MESSAGE: (info) _loadEngines: start"
0:02.73 INFO "CONSOLE_MESSAGE: (info) _findEngines: looking for engines in JARs"
0:02.80 pid:87331 *** Search: _loadEngines: Absent or outdated cache. Loading engines from disk.
0:02.80 pid:87331 *** Search: _loadEngines: Found previously installed extension
0:02.80 pid:87331 *** Search: addEnginesFromExtension: [email protected]
0:02.80 pid:87331 *** Search: addEnginesFromExtension: installing locale: [email protected]:default
0:02.80 pid:87331 *** Search: _setIcon: Setting icon url "moz-extension://f4882ddf-80e0-844b-b27d-e5ca4e866cf4/favicon.ico" for engine "Special".
0:02.80 pid:87331 *** Search: _addEngineToStore: Adding engine: "Special"
0:02.80 pid:87331 *** Search: _loadEngines: loading user-installed engines from the obsolete cache
0:02.81 pid:87331 *** Search: _loadEnginesFromCache: Loading 2 engines from cache
0:02.81 pid:87331 *** Search: _loadEnginesFromCache: skipped 2 read-only engines.
0:02.81 pid:87331 *** Search: _loadEnginesMetadataFromCache, transfering metadata for Special
0:02.81 pid:87331 *** Search: _loadEngines: done using rebuilt cache
0:02.81 pid:87331 *** Search: _buildCache: Writing to cache file.
0:02.73 INFO "CONSOLE_MESSAGE: (error) [JavaScript Error: "parseListJSON: No searchDefault" {file: "resource://gre/modules/SearchService.jsm" line: 3485}]
SRCH_SVC_parseListJSON@resource://gre/modules/SearchService.jsm:3485:10
_findEngines@resource://gre/modules/SearchService.jsm:3342:10
async*_loadEngines@resource://gre/modules/SearchService.jsm:2801:30
_reInit/<@resource://gre/modules/SearchService.jsm:3028:20
async*_reInit@resource://gre/modules/SearchService.jsm:3045:7
reInit@resource://gre/modules/SearchService.jsm:3679:17
asyncReInit@/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell/head_search.js:430:19
restart@/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell/test_webextensions_install.js:23:9
async*cont@/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell/test_webextensions_install.js:79:11
withGeoServer@/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell/head_search.js:338:11
basic_install_test@/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell/test_webextensions_install.js:77:9
async*run_next_test/_run_next_test/<@/Users/dharvey/src/gecko/testing/xpcshell/head.js:1437:22
_run_next_test@/Users/dharvey/src/gecko/testing/xpcshell/head.js:1437:38
run@/Users/dharvey/src/gecko/testing/xpcshell/head.js:688:9
_do_main@/Users/dharvey/src/gecko/testing/xpcshell/head.js:227:6
_execute_test@/Users/dharvey/src/gecko/testing/xpcshell/head.js:529:5
@-e:1:1
"
0:02.73 INFO "CONSOLE_MESSAGE: (info) _loadEngines: Absent or outdated cache. Loading engines from disk."
0:02.73 INFO "CONSOLE_MESSAGE: (info) _loadEngines: Found previously installed extension"
0:02.73 INFO "CONSOLE_MESSAGE: (info) addEnginesFromExtension: [email protected]"
0:02.73 INFO "CONSOLE_MESSAGE: (info) addEnginesFromExtension: installing locale: [email protected]:default"
0:02.73 INFO "CONSOLE_MESSAGE: (info) _setIcon: Setting icon url "moz-extension://f4882ddf-80e0-844b-b27d-e5ca4e866cf4/favicon.ico" for engine "Special"."
0:02.73 INFO "CONSOLE_MESSAGE: (info) _addEngineToStore: Adding engine: "Special""
0:02.73 INFO "CONSOLE_MESSAGE: (info) _loadEngines: loading user-installed engines from the obsolete cache"
0:02.73 INFO "CONSOLE_MESSAGE: (info) _loadEnginesFromCache: Loading 2 engines from cache"
0:02.73 INFO "CONSOLE_MESSAGE: (info) _loadEnginesFromCache: skipped 2 read-only engines."
0:02.73 INFO "CONSOLE_MESSAGE: (info) _loadEnginesMetadataFromCache, transfering metadata for Special"
0:02.73 INFO "CONSOLE_MESSAGE: (info) _loadEngines: done using rebuilt cache"
0:02.73 INFO "CONSOLE_MESSAGE: (info) _buildCache: Writing to cache file."
0:02.81 pid:87331 *** Search: _buildCache: cache file written to disk.
0:02.81 pid:87331 *** Search: SearchService.init
0:02.81 pid:87331 *** Search: getEngines: getting all engines
0:02.81 pid:87331 *** Search: _buildSortedEngineList: building list
0:02.73 PASS basic_install_test - [basic_install_test : 80] ["Special"] deepEqual ["Special"]
0:02.74 INFO "CONSOLE_MESSAGE: (info) _buildCache: cache file written to disk."
0:02.74 INFO "CONSOLE_MESSAGE: (info) SearchService.init"
0:02.74 INFO "CONSOLE_MESSAGE: (info) getEngines: getting all engines"
0:02.74 INFO "CONSOLE_MESSAGE: (info) _buildSortedEngineList: building list"
0:02.82 pid:87331 JavaScript strict warning: resource://gre/modules/addons/XPIInstall.jsm, line 475: ReferenceError: reference to undefined property "description"
0:02.75 INFO "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "ReferenceError: reference to undefined property "description"" {file: "resource://gre/modules/addons/XPIInstall.jsm" line: 475}]"
0:02.82 pid:87331 JavaScript strict warning: resource://gre/modules/addons/XPIInstall.jsm, line 665: ReferenceError: reference to undefined property "organizationalUnit"
0:02.82 pid:87331 1554994796297 addons.repository DEBUG cacheAddons: enabled false IDs ["[email protected]"]
0:02.82 pid:87331 1554994796297 addons.xpi-utils DEBUG Starting async load of XPI database /var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/extensions.json
0:02.75 INFO "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "ReferenceError: reference to undefined property "organizationalUnit"" {file: "resource://gre/modules/addons/XPIInstall.jsm" line: 665}]"
0:02.75 INFO "CONSOLE_MESSAGE: (info) 1554994796297 addons.repository DEBUG cacheAddons: enabled false IDs ["[email protected]"]"
0:02.75 INFO "CONSOLE_MESSAGE: (info) 1554994796297 addons.xpi-utils DEBUG Starting async load of XPI database /var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/extensions.json"
0:02.82 pid:87331 1554994796298 addons.xpi-utils DEBUG Finished async read of XPI database, parsing...
0:02.75 INFO "CONSOLE_MESSAGE: (info) 1554994796298 addons.xpi-utils DEBUG Finished async read of XPI database, parsing..."
0:02.82 pid:87331 1554994796301 addons.xpi-utils DEBUG Successfully read XPI database
0:02.82 pid:87331 1554994796303 addons.xpi DEBUG Starting install of [email protected] from file:///var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/tmp/generated-extension.xpi
0:02.76 INFO "CONSOLE_MESSAGE: (info) 1554994796301 addons.xpi-utils DEBUG Successfully read XPI database"
0:02.76 INFO "CONSOLE_MESSAGE: (info) 1554994796303 addons.xpi DEBUG Starting install of [email protected] from file:///var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/tmp/generated-extension.xpi"
0:02.83 pid:87331 1554994796308 addons.xpi DEBUG Addon [email protected] will be installed as a packed xpi
0:02.76 INFO "CONSOLE_MESSAGE: (info) 1554994796308 addons.xpi DEBUG Addon [email protected] will be installed as a packed xpi"
0:02.83 pid:87331 1554994796314 addons.xpi-utils DEBUG Make addon app-profile:[email protected] visible
0:02.83 pid:87331 1554994796314 addons.xpi DEBUG XPIStates adding add-on [email protected] in {"addons":{},"staged":{},"path":"/var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/extensions"}: /var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/extensions/[email protected]
0:02.83 pid:87331 1554994796315 addons.xpi DEBUG Updating XPIState for {"id":"[email protected]","syncGUID":"{d1a22d83-dc8d-b748-8a45-f247a95174cb}","version":"1.0","type":"extension","loader":null,"updateURL":null,"optionsURL":null,"optionsType":null,"optionsBrowserStyle":true,"aboutURL":null,"defaultLocale":{"name":"Generated extension","creator":null,"developers":null,"translators":null,"contributors":null},"visible":true,"active":true,"userDisabled":false,"appDisabled":false,"installDate":1554994796302,"updateDate":1554994796302,"applyBackgroundUpdates":1,"path":"/var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/extensions/[email protected]","skinnable":false,"sourceURI":"file:///var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/tmp/generated-extension.xpi","releaseNotesURI":null,"softDisabled":false,"foreignInstall":false,"strictCompatibility":true,"locales":[],"targetApplications":[{"id":"[email protected]","minVersion":null,"maxVersion":null}],"targetPlatforms":[],"signedState":2,"seen":true,"dependencies":[],"incognito":"spanning","userPermissions":{"permissions":[],"origins":[]},"icons":{},"iconURL":null,"blocklistState":0,"blocklistURL":null,"startupData":null,"hidden":false,"installTelemetryInfo":null,"rootURI":"jar:file:///var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/extensions/[email protected]!/","location":"app-profile"}
0:02.83 pid:87331 1554994796315 addons.xpi DEBUG Install of file:///var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/tmp/generated-extension.xpi completed.
0:02.83 pid:87331 1554994796316 addons.xpi DEBUG Loading bootstrap scope from jar:file:///var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/extensions/[email protected]!/
0:02.83 pid:87331 1554994796316 addons.xpi DEBUG Calling bootstrap method install on [email protected] version 1.0
0:02.83 pid:87331 1554994796317 addons.xpi DEBUG Calling bootstrap method startup on [email protected] version 1.0
0:02.77 INFO "CONSOLE_MESSAGE: (info) 1554994796314 addons.xpi-utils DEBUG Make addon app-profile:[email protected] visible"
0:02.77 INFO "CONSOLE_MESSAGE: (info) 1554994796314 addons.xpi DEBUG XPIStates adding add-on [email protected] in {"addons":{},"staged":{},"path":"/var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/extensions"}: /var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/extensions/[email protected]"
0:02.77 INFO "CONSOLE_MESSAGE: (info) 1554994796315 addons.xpi DEBUG Updating XPIState for {"id":"[email protected]","syncGUID":"{d1a22d83-dc8d-b748-8a45-f247a95174cb}","version":"1.0","type":"extension","loader":null,"updateURL":null,"optionsURL":null,"optionsType":null,"optionsBrowserStyle":true,"aboutURL":null,"defaultLocale":{"name":"Generated extension","creator":null,"developers":null,"translators":null,"contributors":null},"visible":true,"active":true,"userDisabled":false,"appDisabled":false,"installDate":1554994796302,"updateDate":1554994796302,"applyBackgroundUpdates":1,"path":"/var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/extensions/[email protected]","skinnable":false,"sourceURI":"file:///var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/tmp/generated-extension.xpi","releaseNotesURI":null,"softDisabled":false,"foreignInstall":false,"strictCompatibility":true,"locales":[],"targetApplications":[{"id":"[email protected]","minVersion":null,"maxVersion":null}],"targetPlatforms":[],"signedState":2,"seen":true,"dependencies":[],"incognito":"spanning","userPermissions":{"permissions":[],"origins":[]},"icons":{},"iconURL":null,"blocklistState":0,"blocklistURL":null,"startupData":null,"hidden":false,"installTelemetryInfo":null,"rootURI":"jar:file:///var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/extensions/[email protected]!/","location":"app-profile"}"
0:02.77 INFO "CONSOLE_MESSAGE: (info) 1554994796315 addons.xpi DEBUG Install of file:///var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/tmp/generated-extension.xpi completed."
0:02.77 INFO "CONSOLE_MESSAGE: (info) 1554994796316 addons.xpi DEBUG Loading bootstrap scope from jar:file:///var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/extensions/[email protected]!/"
0:02.77 INFO "CONSOLE_MESSAGE: (info) 1554994796316 addons.xpi DEBUG Calling bootstrap method install on [email protected] version 1.0"
0:02.77 INFO "CONSOLE_MESSAGE: (info) 1554994796317 addons.xpi DEBUG Calling bootstrap method startup on [email protected] version 1.0"
0:02.78 INFO "Extension attached"
0:02.84 pid:87331 *** Search: addEnginesFromExtension: [email protected]
0:02.84 pid:87331 *** Search: addEnginesFromExtension: User installed extension [email protected]
0:02.84 pid:87331 *** Search: addEnginesFromExtension: installing locale: [email protected]:default
0:02.84 pid:87331 *** Search: NOTIFY: Engine: "Example"; Verb: "engine-changed"
0:02.84 pid:87331 *** Search: _addEngineToStore: Adding engine: "Example"
0:02.84 pid:87331 *** Search: SRCH_SVC_saveSortedEngineList: starting
0:02.84 pid:87331 *** Search: SRCH_SVC_saveSortedEngineList: done
0:02.84 pid:87331 *** Search: NOTIFY: Engine: "Example"; Verb: "engine-added"
0:02.84 pid:87331 *** Search: SearchService.init
0:02.84 pid:87331 1554994796328 addons.xpi DEBUG removeTemporaryFile: file:///var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/tmp/generated-extension.xpi does not own temp file
0:02.84 pid:87331 *** Search: getEngines: getting all engines
0:02.78 PASS basic_install_test - [basic_install_test : 85] ["Special","Example"] deepEqual ["Special","Example"]
0:02.78 INFO "CONSOLE_MESSAGE: (info) addEnginesFromExtension: [email protected]"
0:02.78 INFO "CONSOLE_MESSAGE: (info) addEnginesFromExtension: User installed extension [email protected]"
0:02.78 INFO "CONSOLE_MESSAGE: (info) addEnginesFromExtension: installing locale: [email protected]:default"
0:02.78 INFO "CONSOLE_MESSAGE: (info) NOTIFY: Engine: "Example"; Verb: "engine-changed""
0:02.78 INFO "CONSOLE_MESSAGE: (info) _addEngineToStore: Adding engine: "Example""
0:02.78 INFO "CONSOLE_MESSAGE: (info) SRCH_SVC_saveSortedEngineList: starting"
0:02.78 INFO "CONSOLE_MESSAGE: (info) SRCH_SVC_saveSortedEngineList: done"
0:02.78 INFO "CONSOLE_MESSAGE: (info) NOTIFY: Engine: "Example"; Verb: "engine-added""
0:02.78 INFO "CONSOLE_MESSAGE: (info) SearchService.init"
0:02.78 INFO "CONSOLE_MESSAGE: (info) 1554994796328 addons.xpi DEBUG removeTemporaryFile: file:///var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/tmp/generated-extension.xpi does not own temp file"
0:02.78 INFO "CONSOLE_MESSAGE: (info) getEngines: getting all engines"
0:02.85 pid:87331 1554994796337 addons.xpi DEBUG Calling bootstrap method shutdown on [email protected] version 1.0
0:02.85 pid:87331 1554994796338 addons.manager DEBUG shutdown
0:02.85 pid:87331 1554994796339 addons.manager DEBUG Calling shutdown blocker for GMPProvider
0:02.85 pid:87331 1554994796339 addons.manager DEBUG Calling shutdown blocker for PluginProvider
0:02.85 pid:87331 1554994796339 addons.manager DEBUG Calling shutdown blocker for XPIProvider
0:02.85 pid:87331 1554994796339 addons.xpi DEBUG shutdown
0:02.85 pid:87331 1554994796339 addons.xpi-utils DEBUG shutdown
0:02.79 INFO "CONSOLE_MESSAGE: (info) 1554994796337 addons.xpi DEBUG Calling bootstrap method shutdown on [email protected] version 1.0"
0:02.79 INFO "CONSOLE_MESSAGE: (info) 1554994796338 addons.manager DEBUG shutdown"
0:02.79 INFO "CONSOLE_MESSAGE: (info) 1554994796339 addons.manager DEBUG Calling shutdown blocker for GMPProvider"
0:02.79 INFO "CONSOLE_MESSAGE: (info) 1554994796339 addons.manager DEBUG Calling shutdown blocker for PluginProvider"
0:02.79 INFO "CONSOLE_MESSAGE: (info) 1554994796339 addons.manager DEBUG Calling shutdown blocker for XPIProvider"
0:02.79 INFO "CONSOLE_MESSAGE: (info) 1554994796339 addons.xpi DEBUG shutdown"
0:02.79 INFO "CONSOLE_MESSAGE: (info) 1554994796339 addons.xpi-utils DEBUG shutdown"
0:02.85 pid:87331 JavaScript strict warning: resource://gre/modules/Extension.jsm, line 2108: ReferenceError: reference to undefined property "cleanupFile"
0:02.80 INFO "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "ReferenceError: reference to undefined property "cleanupFile"" {file: "resource://gre/modules/Extension.jsm" line: 2108}]"
0:02.85 pid:87331 1554994796346 addons.xpi-utils DEBUG XPI Database saved, setting schema version preference to 29
0:02.85 pid:87331 1554994796347 addons.manager DEBUG Async provider shutdown done
0:02.85 pid:87331 1554994796349 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/XPIProvider.jsm: ["XPIProvider", "XPIInternal"]
0:02.85 pid:87331 1554994796349 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/GMPProvider.jsm
0:02.85 pid:87331 1554994796349 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/PluginProvider.jsm
0:02.85 pid:87331 1554994796350 addons.manager DEBUG Starting provider: GMPProvider
0:02.85 pid:87331 1554994796350 addons.manager DEBUG Registering shutdown blocker for GMPProvider
0:02.85 pid:87331 1554994796350 addons.manager DEBUG Provider finished startup: GMPProvider
0:02.85 pid:87331 1554994796350 addons.manager DEBUG Starting provider: PluginProvider
0:02.85 pid:87331 1554994796350 addons.manager DEBUG Registering shutdown blocker for PluginProvider
0:02.85 pid:87331 1554994796350 addons.manager DEBUG Provider finished startup: PluginProvider
0:02.85 pid:87331 1554994796351 addons.manager DEBUG Starting provider: XPIProvider
0:02.85 pid:87331 1554994796351 addons.xpi DEBUG startup
0:02.85 pid:87331 1554994796351 addons.xpi WARN List of valid built-in add-ons could not be parsed.: [Exception... "Component returned failure code: 0xc1f30001 (NS_ERROR_NOT_INITIALIZED) [nsIXPCComponents_Utils.readUTF8URI]" nsresult: "0xc1f30001 (NS_ERROR_NOT_INITIALIZED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm :: startup :: line 2243" data: no] Stack trace: startup()@resource://gre/modules/addons/XPIProvider.jsm:2243
0:02.85 pid:87331 callProvider()@resource://gre/modules/AddonManager.jsm:200
0:02.85 pid:87331 _startProvider()@resource://gre/modules/AddonManager.jsm:651
0:02.85 pid:87331 startup()@resource://gre/modules/AddonManager.jsm:807
0:02.85 pid:87331 startup()@resource://gre/modules/AddonManager.jsm:2801
0:02.85 pid:87331 observe()@resource://gre/modules/addonManager.js:65
0:02.85 pid:87331 promiseStartupManager()@resource://testing-common/AddonTestUtils.jsm:747
0:02.86 pid:87331 promiseRestartManager()@resource://testing-common/AddonTestUtils.jsm:825
0:02.86 pid:87331 1554994796352 addons.xpi INFO SystemAddonLocation directory is missing
0:02.86 pid:87331 1554994796353 addons.xpi DEBUG checkForChanges
0:02.86 pid:87331 1554994796353 addons.xpi DEBUG Loaded add-on state: {"app-profile":{"addons":{"[email protected]":{"dependencies":[],"enabled":true,"lastModifiedTime":1554994796000,"loader":null,"path":"[email protected]","rootURI":"jar:file:///var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/extensions/[email protected]!/","runInSafeMode":false,"signedState":2,"telemetryKey":"example%40tests.mozilla.org:1.0","version":"1.0"}},"staged":{},"path":"/var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/extensions"}}
0:02.86 pid:87331 1554994796354 addons.xpi DEBUG Existing add-on [email protected] in app-profile
0:02.86 pid:87331 1554994796354 addons.xpi DEBUG scanForChanges changed: false, state: {}
0:02.86 pid:87331 1554994796355 addons.xpi DEBUG No changes found
0:02.86 pid:87331 1554994796355 addons.xpi DEBUG Loading bootstrap scope from jar:file:///var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/extensions/[email protected]!/
0:02.86 pid:87331 1554994796355 addons.xpi DEBUG Calling bootstrap method startup on [email protected] version 1.0
0:02.86 pid:87331 1554994796356 addons.manager DEBUG Registering shutdown blocker for XPIProvider
0:02.86 pid:87331 1554994796356 addons.manager DEBUG Provider finished startup: XPIProvider
0:02.86 pid:87331 1554994796357 addons.manager DEBUG Completed startup sequence
0:02.86 pid:87331 1554994796359 addons.xpi-utils DEBUG Starting async load of XPI database /var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/extensions.json
0:02.81 INFO "Extension attached"
0:02.86 pid:87331 *** Search: addEnginesFromExtension: [email protected]
0:02.86 pid:87331 *** Search: addEnginesFromExtension: User installed extension [email protected]
0:02.86 pid:87331 *** Search: addEnginesFromExtension: installing locale: [email protected]:default
0:02.86 pid:87331 *** Search: An engine with that name already exists!
0:02.86 pid:87331 *** Search: addEnginesFromExtension: Failed to install [email protected]: "An engine with that name already exists!"
0:02.86 pid:87331 FAIL@resource://gre/modules/SearchService.jsm:223:20
0:02.86 pid:87331 addEngineWithDetails@resource://gre/modules/SearchService.jsm:3823:9
0:02.86 pid:87331 _addEngineForManifest@resource://gre/modules/SearchService.jsm:3929:17
0:02.86 pid:87331 installLocale@resource://gre/modules/SearchService.jsm:3860:21
0:02.86 pid:87331 addEnginesFromExtension@resource://gre/modules/SearchService.jsm:3870:30
0:02.86 pid:87331 addSearchEngine@chrome://browser/content/parent/ext-chrome-settings-overrides.js:353:43
0:02.86 pid:87331 processSearchProviderManifestEntry@chrome://browser/content/parent/ext-chrome-settings-overrides.js:282:16
0:02.87 pid:87331 onManifestEntry@chrome://browser/content/parent/ext-chrome-settings-overrides.js:241:16
0:02.87 pid:87331 async*asyncEmitManifestEntry@resource://gre/modules/ExtensionCommon.jsm:1421:18
0:02.87 pid:87331 async*runManifest@resource://gre/modules/Extension.jsm:1765:31
0:02.87 pid:87331 startup@resource://gre/modules/Extension.jsm:1978:14
0:02.87 pid:87331 async*startup@resource://gre/modules/Extension.jsm:1314:27
0:02.87 pid:87331 callBootstrapMethod@resource://gre/modules/addons/XPIProvider.jsm:1742:33
0:02.87 pid:87331 startup@resource://gre/modules/addons/XPIProvider.jsm:1841:32
0:02.87 pid:87331 startup@resource://gre/modules/addons/XPIProvider.jsm:2322:39
0:02.87 pid:87331 callProvider@resource://gre/modules/AddonManager.jsm:200:31
0:02.87 pid:87331 _startProvider@resource://gre/modules/AddonManager.jsm:651:5
0:02.87 pid:87331 startup@resource://gre/modules/AddonManager.jsm:807:14
0:02.87 pid:87331 startup@resource://gre/modules/AddonManager.jsm:2801:26
0:02.87 pid:87331 observe@resource://gre/modules/addonManager.js:65:29
0:02.87 pid:87331 promiseStartupManager@resource://testing-common/AddonTestUtils.jsm:747:34
0:02.87 pid:87331 promiseRestartManager@resource://testing-common/AddonTestUtils.jsm:825:16
0:02.87 pid:87331 Async*restart@/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell/test_webextensions_install.js:22:9
0:02.87 pid:87331 cont@/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell/test_webextensions_install.js:91:11
0:02.87 pid:87331 withGeoServer@/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell/head_search.js:338:11
0:02.87 pid:87331 basic_install_test@/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell/test_webextensions_install.js:90:9
0:02.87 pid:87331 async*run_next_test/_run_next_test/<@/Users/dharvey/src/gecko/testing/xpcshell/head.js:1437:22
0:02.87 pid:87331 _run_next_test@/Users/dharvey/src/gecko/testing/xpcshell/head.js:1437:38
0:02.87 pid:87331 run@/Users/dharvey/src/gecko/testing/xpcshell/head.js:688:9
0:02.87 pid:87331 _do_main@/Users/dharvey/src/gecko/testing/xpcshell/head.js:227:6
0:02.87 pid:87331 _execute_test@/Users/dharvey/src/gecko/testing/xpcshell/head.js:529:5
0:02.87 pid:87331 @-e:1:1
0:02.82 INFO "CONSOLE_MESSAGE: (info) 1554994796346 addons.xpi-utils DEBUG XPI Database saved, setting schema version preference to 29"
0:02.82 INFO "CONSOLE_MESSAGE: (info) 1554994796347 addons.manager DEBUG Async provider shutdown done"
0:02.82 INFO "CONSOLE_MESSAGE: (info) 1554994796349 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/XPIProvider.jsm: ["XPIProvider", "XPIInternal"]"
0:02.82 INFO "CONSOLE_MESSAGE: (info) 1554994796349 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/GMPProvider.jsm"
0:02.82 INFO "CONSOLE_MESSAGE: (info) 1554994796349 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/PluginProvider.jsm"
0:02.82 INFO "CONSOLE_MESSAGE: (info) 1554994796350 addons.manager DEBUG Starting provider: GMPProvider"
0:02.82 INFO "CONSOLE_MESSAGE: (info) 1554994796350 addons.manager DEBUG Registering shutdown blocker for GMPProvider"
0:02.82 INFO "CONSOLE_MESSAGE: (info) 1554994796350 addons.manager DEBUG Provider finished startup: GMPProvider"
0:02.82 INFO "CONSOLE_MESSAGE: (info) 1554994796350 addons.manager DEBUG Starting provider: PluginProvider"
0:02.82 INFO "CONSOLE_MESSAGE: (info) 1554994796350 addons.manager DEBUG Registering shutdown blocker for PluginProvider"
0:02.82 INFO "CONSOLE_MESSAGE: (info) 1554994796350 addons.manager DEBUG Provider finished startup: PluginProvider"
0:02.82 INFO "CONSOLE_MESSAGE: (info) 1554994796351 addons.manager DEBUG Starting provider: XPIProvider"
0:02.82 INFO "CONSOLE_MESSAGE: (info) 1554994796351 addons.xpi DEBUG startup"
0:02.82 INFO "CONSOLE_MESSAGE: (info) 1554994796351 addons.xpi WARN List of valid built-in add-ons could not be parsed.: [Exception... "Component returned failure code: 0xc1f30001 (NS_ERROR_NOT_INITIALIZED) [nsIXPCComponents_Utils.readUTF8URI]" nsresult: "0xc1f30001 (NS_ERROR_NOT_INITIALIZED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm :: startup :: line 2243" data: no] Stack trace: startup()@resource://gre/modules/addons/XPIProvider.jsm:2243
callProvider()@resource://gre/modules/AddonManager.jsm:200
_startProvider()@resource://gre/modules/AddonManager.jsm:651
startup()@resource://gre/modules/AddonManager.jsm:807
startup()@resource://gre/modules/AddonManager.jsm:2801
observe()@resource://gre/modules/addonManager.js:65
promiseStartupManager()@resource://testing-common/AddonTestUtils.jsm:747
promiseRestartManager()@resource://testing-common/AddonTestUtils.jsm:825"
0:02.82 INFO "CONSOLE_MESSAGE: (info) 1554994796352 addons.xpi INFO SystemAddonLocation directory is missing"
0:02.82 INFO "CONSOLE_MESSAGE: (info) 1554994796353 addons.xpi DEBUG checkForChanges"
0:02.82 INFO "CONSOLE_MESSAGE: (info) 1554994796353 addons.xpi DEBUG Loaded add-on state: {"app-profile":{"addons":{"[email protected]":{"dependencies":[],"enabled":true,"lastModifiedTime":1554994796000,"loader":null,"path":"[email protected]","rootURI":"jar:file:///var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/extensions/[email protected]!/","runInSafeMode":false,"signedState":2,"telemetryKey":"example%40tests.mozilla.org:1.0","version":"1.0"}},"staged":{},"path":"/var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/extensions"}}"
0:02.82 INFO "CONSOLE_MESSAGE: (info) 1554994796354 addons.xpi DEBUG Existing add-on [email protected] in app-profile"
0:02.82 INFO "CONSOLE_MESSAGE: (info) 1554994796354 addons.xpi DEBUG scanForChanges changed: false, state: {}"
0:02.82 INFO "CONSOLE_MESSAGE: (info) 1554994796355 addons.xpi DEBUG No changes found"
0:02.83 INFO "CONSOLE_MESSAGE: (info) 1554994796355 addons.xpi DEBUG Loading bootstrap scope from jar:file:///var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/extensions/[email protected]!/"
0:02.83 INFO "CONSOLE_MESSAGE: (info) 1554994796355 addons.xpi DEBUG Calling bootstrap method startup on [email protected] version 1.0"
0:02.83 INFO "CONSOLE_MESSAGE: (info) 1554994796356 addons.manager DEBUG Registering shutdown blocker for XPIProvider"
0:02.83 INFO "CONSOLE_MESSAGE: (info) 1554994796356 addons.manager DEBUG Provider finished startup: XPIProvider"
0:02.83 INFO "CONSOLE_MESSAGE: (info) 1554994796357 addons.manager DEBUG Completed startup sequence"
0:02.83 INFO "CONSOLE_MESSAGE: (info) 1554994796359 addons.xpi-utils DEBUG Starting async load of XPI database /var/folders/3x/v57whhy95m12lvkcvgclss200000gn/T/firefox/xpcshellprofile/extensions.json"
0:02.83 INFO "CONSOLE_MESSAGE: (info) addEnginesFromExtension: [email protected]"
0:02.83 INFO "CONSOLE_MESSAGE: (info) addEnginesFromExtension: User installed extension [email protected]"
0:02.83 INFO "CONSOLE_MESSAGE: (info) addEnginesFromExtension: installing locale: [email protected]:default"
0:02.83 INFO "CONSOLE_MESSAGE: (info) An engine with that name already exists!"
0:02.83 INFO "CONSOLE_MESSAGE: (info) addEnginesFromExtension: Failed to install [email protected]: "An engine with that name already exists!"
FAIL@resource://gre/modules/SearchService.jsm:223:20
addEngineWithDetails@resource://gre/modules/SearchService.jsm:3823:9
_addEngineForManifest@resource://gre/modules/SearchService.jsm:3929:17
installLocale@resource://gre/modules/SearchService.jsm:3860:21
addEnginesFromExtension@resource://gre/modules/SearchService.jsm:3870:30
addSearchEngine@chrome://browser/content/parent/ext-chrome-settings-overrides.js:353:43
processSearchProviderManifestEntry@chrome://browser/content/parent/ext-chrome-settings-overrides.js:282:16
onManifestEntry@chrome://browser/content/parent/ext-chrome-settings-overrides.js:241:16
async*asyncEmitManifestEntry@resource://gre/modules/ExtensionCommon.jsm:1421:18
async*runManifest@resource://gre/modules/Extension.jsm:1765:31
startup@resource://gre/modules/Extension.jsm:1978:14
async*startup@resource://gre/modules/Extension.jsm:1314:27
callBootstrapMethod@resource://gre/modules/addons/XPIProvider.jsm:1742:33
startup@resource://gre/modules/addons/XPIProvider.jsm:1841:32
startup@resource://gre/modules/addons/XPIProvider.jsm:2322:39
callProvider@resource://gre/modules/AddonManager.jsm:200:31
_startProvider@resource://gre/modules/AddonManager.jsm:651:5
startup@resource://gre/modules/AddonManager.jsm:807:14
startup@resource://gre/modules/AddonManager.jsm:2801:26
observe@resource://gre/modules/addonManager.js:65:29
promiseStartupManager@resource://testing-common/AddonTestUtils.jsm:747:34
promiseRestartManager@resource://testing-common/AddonTestUtils.jsm:825:16
Async*restart@/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell/test_webextensions_install.js:22:9
cont@/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell/test_webextensions_install.js:91:11
withGeoServer@/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell/head_search.js:338:11
basic_install_test@/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell/test_webextensions_install.js:90:9
async*run_next_test/_run_next_test/<@/Users/dharvey/src/gecko/testing/xpcshell/head.js:1437:22
_run_next_test@/Users/dharvey/src/gecko/testing/xpcshell/head.js:1437:38
run@/Users/dharvey/src/gecko/testing/xpcshell/head.js:688:9
_do_main@/Users/dharvey/src/gecko/testing/xpcshell/head.js:227:6
_execute_test@/Users/dharvey/src/gecko/testing/xpcshell/head.js:529:5
@-e:1:1
"
0:02.90 pid:87331 1554994796374 addons.xpi-utils DEBUG Finished async read of XPI database, parsing...
0:02.83 INFO "CONSOLE_MESSAGE: (info) 1554994796374 addons.xpi-utils DEBUG Finished async read of XPI database, parsing..."
0:02.90 pid:87331 *** Search: _reInit
0:02.90 pid:87331 *** Search: finalizing batch task
0:02.83 INFO "CONSOLE_MESSAGE: (info) _reInit"
0:02.83 INFO "CONSOLE_MESSAGE: (info) finalizing batch task"
0:02.90 pid:87331 *** Search: fetchRegionDefault starting with endpoint http://localhost:49561/lookup_defaults?
0:02.83 INFO "CONSOLE_MESSAGE: (info) fetchRegionDefault starting with endpoint http://localhost:49561/lookup_defaults?"
0:02.90 pid:87331 *** Search: received ({interval:31536000, settings:{searchDefault:"Test search engine", visibleDefaultEngines:["plainengine"]}})
0:02.90 pid:87331 *** Search: fetchRegionDefault saved searchDefault: Test search engine
0:02.90 pid:87331 *** Search: fetchRegionDefault saved visibleDefaultEngines: plainengine
0:02.90 pid:87331 *** Search: fetchRegionDefault got success response in 21ms
0:02.90 pid:87331 WTF _MAYBERELOADENGINES
0:02.90 pid:87331 WTF _MAYBERELOADENGINES RETURN
0:02.90 pid:87331 *** Search: _loadEngines: start
0:02.90 pid:87331 *** Search: _findEngines: looking for engines in JARs
0:02.86 INFO "CONSOLE_MESSAGE: (info) received ({interval:31536000, settings:{searchDefault:"Test search engine", visibleDefaultEngines:["plainengine"]}})"
0:02.86 INFO "CONSOLE_MESSAGE: (info) fetchRegionDefault saved searchDefault: Test search engine"
0:02.86 INFO "CONSOLE_MESSAGE: (info) fetchRegionDefault saved visibleDefaultEngines: plainengine"
0:02.86 INFO "CONSOLE_MESSAGE: (info) fetchRegionDefault got success response in 21ms"
0:02.86 INFO "CONSOLE_MESSAGE: (info) _loadEngines: start"
0:02.86 INFO "CONSOLE_MESSAGE: (info) _findEngines: looking for engines in JARs"
0:02.90 pid:87331 *** Search: _loadEngines: Absent or outdated cache. Loading engines from disk.
0:02.90 pid:87331 *** Search: _loadEngines: Found previously installed extension
0:02.90 pid:87331 *** Search: addEnginesFromExtension: [email protected]
0:02.90 pid:87331 *** Search: addEnginesFromExtension: installing locale: [email protected]:default
0:02.90 pid:87331 *** Search: _addEngineToStore: Adding engine: "Example"
0:02.90 pid:87331 *** Search: _loadEngines: Found previously installed extension
0:02.90 pid:87331 *** Search: addEnginesFromExtension: [email protected]
0:02.90 pid:87331 *** Search: addEnginesFromExtension: installing locale: [email protected]:default
0:02.90 pid:87331 *** Search: _setIcon: Setting icon url "moz-extension://8df0018e-6cae-814c-9248-2e117c3d669a/favicon.ico" for engine "Plain".
0:02.90 pid:87331 *** Search: _addEngineToStore: Adding engine: "Plain"
0:02.90 pid:87331 *** Search: _loadEngines: loading user-installed engines from the obsolete cache
0:02.90 pid:87331 *** Search: _loadEnginesFromCache: Loading 1 engines from cache
0:02.90 pid:87331 *** Search: _loadEnginesFromCache: skipped 1 read-only engines.
0:02.90 pid:87331 *** Search: _loadEngines: done using rebuilt cache
0:02.90 pid:87331 *** Search: _buildCache: Writing to cache file.
0:02.87 INFO "CONSOLE_MESSAGE: (error) [JavaScript Error: "parseListJSON: No searchDefault" {file: "resource://gre/modules/SearchService.jsm" line: 3485}]
SRCH_SVC_parseListJSON@resource://gre/modules/SearchService.jsm:3485:10
_findEngines@resource://gre/modules/SearchService.jsm:3342:10
async*_loadEngines@resource://gre/modules/SearchService.jsm:2801:30
_reInit/<@resource://gre/modules/SearchService.jsm:3028:20
async*_reInit@resource://gre/modules/SearchService.jsm:3045:7
reInit@resource://gre/modules/SearchService.jsm:3679:17
asyncReInit@/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell/head_search.js:430:19
restart@/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell/test_webextensions_install.js:23:9
async*cont@/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell/test_webextensions_install.js:91:11
withGeoServer@/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell/head_search.js:338:11
basic_install_test@/Users/dharvey/src/gecko/obj-x86_64-apple-darwin18.0.0/_tests/xpcshell/toolkit/components/search/tests/xpcshell/test_webextensions_install.js:90:9
async*run_next_test/_run_next_test/<@/Users/dharvey/src/gecko/testing/xpcshell/head.js:1437:22
_run_next_test@/Users/dharvey/src/gecko/testing/xpcshell/head.js:1437:38
run@/Users/dharvey/src/gecko/testing/xpcshell/head.js:688:9
_do_main@/Users/dharvey/src/gecko/testing/xpcshell/head.js:227:6
_execute_test@/Users/dharvey/src/gecko/testing/xpcshell/head.js:529:5
@-e:1:1
"
0:02.87 INFO "CONSOLE_MESSAGE: (info) _loadEngines: Absent or outdated cache. Loading engines from disk."
0:02.87 INFO "CONSOLE_MESSAGE: (info) _loadEngines: Found previously installed extension"
0:02.87 INFO "CONSOLE_MESSAGE: (info) addEnginesFromExtension: [email protected]"
0:02.87 INFO "CONSOLE_MESSAGE: (info) addEnginesFromExtension: installing locale: [email protected]:default"
0:02.87 INFO "CONSOLE_MESSAGE: (info) _addEngineToStore: Adding engine: "Example""
0:02.87 INFO "CONSOLE_MESSAGE: (info) _loadEngines: Found previously installed extension"
0:02.87 INFO "CONSOLE_MESSAGE: (info) addEnginesFromExtension: [email protected]"
0:02.87 INFO "CONSOLE_MESSAGE: (info) addEnginesFromExtension: installing locale: [email protected]:default"
0:02.87 INFO "CONSOLE_MESSAGE: (info) _setIcon: Setting icon url "moz-extension://8df0018e-6cae-814c-9248-2e117c3d669a/favicon.ico" for engine "Plain"."
0:02.87 INFO "CONSOLE_MESSAGE: (info) _addEngineToStore: Adding engine: "Plain""
0:02.87 INFO "CONSOLE_MESSAGE: (info) _loadEngines: loading user-installed engines from the obsolete cache"
0:02.87 INFO "CONSOLE_MESSAGE: (info) _loadEnginesFromCache: Loading 1 engines from cache"
0:02.87 INFO "CONSOLE_MESSAGE: (info) _loadEnginesFromCache: skipped 1 read-only engines."
0:02.87 INFO "CONSOLE_MESSAGE: (info) _loadEngines: done using rebuilt cache"
0:02.87 INFO "CONSOLE_MESSAGE: (info) _buildCache: Writing to cache file."
0:02.91 pid:87331 *** Search: _buildCache: cache file written to disk.
0:02.91 pid:87331 *** Search: SearchService.init
0:02.91 pid:87331 *** Search: getEngines: getting all engines
0:02.91 pid:87331 *** Search: _buildSortedEngineList: building list
0:02.91 pid:87331 *** Search: _buildSortedEngineList: using db for order
0:02.91 pid:87331 *** Search: SRCH_SVC_saveSortedEngineList: starting
0:02.91 pid:87331 *** Search: SRCH_SVC_saveSortedEngineList: done
0:02.87 PASS basic_install_test - [basic_install_test : 92] ["Example","Plain"] deepEqual ["Example","Plain"]
0:02.91 pid:87331 WTF UNINSTALLING
0:02.91 pid:87331 WTF UNLOAD [email protected]
0:02.87 INFO "CONSOLE_MESSAGE: (info) _buildCache: cache file written to disk."
0:02.87 INFO "CONSOLE_MESSAGE: (info) SearchService.init"
0:02.87 INFO "CONSOLE_MESSAGE: (info) getEngines: getting all engines"
0:02.87 INFO "CONSOLE_MESSAGE: (info) _buildSortedEngineList: building list"
0:02.87 INFO "CONSOLE_MESSAGE: (info) _buildSortedEngineList: using db for order"
0:02.87 INFO "CONSOLE_MESSAGE: (info) SRCH_SVC_saveSortedEngineList: starting"
0:02.87 INFO "CONSOLE_MESSAGE: (info) SRCH_SVC_saveSortedEngineList: done"
0:02.92 pid:87331 WTF DONE UNLOAD [email protected]
0:02.92 pid:87331 1554994796425 addons.xpi-utils DEBUG Successfully read XPI database
0:02.88 INFO "CONSOLE_MESSAGE: (info) 1554994796425 addons.xpi-utils DEBUG Successfully read XPI database"
^C 0:06.37 TEST_END: Test FAIL, expected PASS. Subtests passed 4/4. Unexpected 0 - xpcshell return code: -2
0:06.38 INFO INFO | Result summary:
0:06.38 INFO INFO | Passed: 0
0:06.38 INFO INFO | Failed: 1
0:06.38 INFO INFO | Todo: 0
0:06.38 INFO INFO | Retried: 0
0:06.38 SUITE_END
0:06.38 INFO Node moz-http2 server shutting down ...
0:06.38 INFO Node server moz-http2 already dead -2
0:06.38
Overall Summary
===============
xpcshell
~~~~~~~~
Ran 5 checks (4 subtests, 1 tests)
Expected results: 4
Unexpected results: 1
test: 1 (1 fail)
Unexpected Results
------------------
toolkit/components/search/tests/xpcshell/test_webextensions_install.js
FAIL toolkit/components/search/tests/xpcshell/test_webextensions_install.js - xpcshell return code: -2
~/src/gecko (webextensions)$
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment