Filed by: mlaza [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=350546102&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/CD-6pTt3SvmtO3xZbSpJQA/runs/0/artifacts/public/logs/live_backing.log
[task 2021-09-04T12:45:15.809Z] 12:45:15 INFO - TEST-START | devtools/client/performance/test/xpcshell/test_tree-model-05.js
[task 2021-09-04T12:45:16.999Z] 12:45:16 INFO - TEST-PASS | devtools/client/performance/test/xpcshell/test_tree-model-05.js | took 1190ms
[task 2021-09-04T12:45:17.010Z] 12:45:17 INFO - TEST-START | docshell/test/unit/test_URIFixup.js
[task 2021-09-04T12:45:18.112Z] 12:45:18 WARNING - TEST-UNEXPECTED-FAIL | docshell/test/unit/test_URIFixup.js | xpcshell return code: 1
[task 2021-09-04T12:45:18.112Z] 12:45:18 INFO - TEST-INFO took 1102ms
[task 2021-09-04T12:45:18.113Z] 12:45:18 INFO - >>>>>>>
[task 2021-09-04T12:45:18.113Z] 12:45:18 INFO - PID 9376 | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2021-09-04T12:45:18.113Z] 12:45:18 INFO - PID 9376 | [Parent 9376, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:119
[task 2021-09-04T12:45:18.113Z] 12:45:18 INFO - PID 9376 | [Parent 9376, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:119
[task 2021-09-04T12:45:18.114Z] 12:45:18 INFO - PID 9376 | [Parent 9376, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:119
[task 2021-09-04T12:45:18.114Z] 12:45:18 INFO - PID 9376 | [Parent 9376, Main Thread] WARNING: Couldn't get the user appdata directory. Crash events may not be produced.: file /builds/worker/checkouts/gecko/toolkit/crashreporter/nsExceptionHandler.cpp:2940
[task 2021-09-04T12:45:18.114Z] 12:45:18 INFO - (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2021-09-04T12:45:18.115Z] 12:45:18 INFO - (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2021-09-04T12:45:18.115Z] 12:45:18 INFO - (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2021-09-04T12:45:18.115Z] 12:45:18 INFO - running event loop
[task 2021-09-04T12:45:18.115Z] 12:45:18 INFO - PID 9376 | [Parent 9376, Main Thread] WARNING: Could not get the program name for a cubeb stream.: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:385
[task 2021-09-04T12:45:18.116Z] 12:45:18 INFO - docshell/test/unit/test_URIFixup.js | Starting setup
[task 2021-09-04T12:45:18.116Z] 12:45:18 INFO - (xpcshell/head.js) | test setup pending (2)
[task 2021-09-04T12:45:18.116Z] 12:45:18 INFO - PID 9376 | [Parent 9376, Main Thread] WARNING: This method is lossy. Use GetCanonicalPath !: file /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileWin.cpp:3356
[task 2021-09-04T12:45:18.117Z] 12:45:18 INFO - PID 9376 | [Parent 9376, Main Thread] WARNING: This method is lossy. Use GetCanonicalPath !: file /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileWin.cpp:3356
[task 2021-09-04T12:45:18.117Z] 12:45:18 INFO - PID 9376 | [Parent 9376, Main Thread] WARNING: This method is lossy. Use GetCanonicalPath !: file /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileWin.cpp:3356
[task 2021-09-04T12:45:18.117Z] 12:45:18 INFO - PID 9376 | [Parent 9376, Main Thread] WARNING: This method is lossy. Use GetCanonicalPath !: file /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileWin.cpp:3356
[task 2021-09-04T12:45:18.117Z] 12:45:18 INFO - (xpcshell/head.js) | test run_next_test 0 finished (2)
[task 2021-09-04T12:45:18.118Z] 12:45:18 INFO - "CONSOLE_MESSAGE: (info) 1630759517562 addons.manager DEBUG Application has been upgraded"
[task 2021-09-04T12:45:18.118Z] 12:45:18 INFO - "CONSOLE_MESSAGE: (info) 1630759517563 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/XPIProvider.jsm: ["XPIProvider", "XPIInternal"]"
[task 2021-09-04T12:45:18.118Z] 12:45:18 INFO - "CONSOLE_MESSAGE: (info) 1630759517571 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/GMPProvider.jsm"
[task 2021-09-04T12:45:18.118Z] 12:45:18 INFO - "CONSOLE_MESSAGE: (info) 1630759517572 addons.manager DEBUG Starting provider: XPIProvider"
[task 2021-09-04T12:45:18.119Z] 12:45:18 INFO - "CONSOLE_MESSAGE: (info) 1630759517572 addons.xpi DEBUG startup"
[task 2021-09-04T12:45:18.119Z] 12:45:18 INFO - "CONSOLE_MESSAGE: (info) 1630759517572 addons.xpi DEBUG List of built-in add-ons is missing or invalid.: [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 2448" data: no] Stack trace: startup()@resource://gre/modules/addons/XPIProvider.jsm:2448
[task 2021-09-04T12:45:18.120Z] 12:45:18 INFO - callProvider()@resource://gre/modules/AddonManager.jsm:222
[task 2021-09-04T12:45:18.120Z] 12:45:18 INFO - _startProvider()@resource://gre/modules/AddonManager.jsm:582
[task 2021-09-04T12:45:18.120Z] 12:45:18 INFO - startup()@resource://gre/modules/AddonManager.jsm:806
[task 2021-09-04T12:45:18.120Z] 12:45:18 INFO - startup()@resource://gre/modules/AddonManager.jsm:3499
[task 2021-09-04T12:45:18.120Z] 12:45:18 INFO - observe()@resource://gre/modules/addonManager.js:81
[task 2021-09-04T12:45:18.121Z] 12:45:18 INFO - promiseStartupManager()@resource://testing-common/AddonTestUtils.jsm:890
[task 2021-09-04T12:45:18.121Z] 12:45:18 INFO - setupSearchService()@Z:/task_1630757936/build/tests/xpcshell/tests/docshell/test/unit/head_docshell.js:60
[task 2021-09-04T12:45:18.121Z] 12:45:18 INFO - _do_main()@Z:\\task_1630757936\\build\\tests\\xpcshell\\head.js:240
[task 2021-09-04T12:45:18.121Z] 12:45:18 INFO - _execute_test()@Z:\\task_1630757936\\build\\tests\\xpcshell\\head.js:597
[task 2021-09-04T12:45:18.121Z] 12:45:18 INFO - -e:1"
[task 2021-09-04T12:45:18.122Z] 12:45:18 INFO - "CONSOLE_MESSAGE: (info) 1630759517575 addons.xpi INFO SystemAddonLocation directory is missing"
[task 2021-09-04T12:45:18.122Z] 12:45:18 INFO - "CONSOLE_MESSAGE: (info) 1630759517591 addons.xpi INFO Removing all system add-on upgrades."
[task 2021-09-04T12:45:18.122Z] 12:45:18 INFO - "CONSOLE_MESSAGE: (info) 1630759517592 addons.xpi DEBUG checkForChanges"
[task 2021-09-04T12:45:18.122Z] 12:45:18 INFO - "CONSOLE_MESSAGE: (info) 1630759517592 addons.xpi DEBUG Loaded add-on state: ${}"
[task 2021-09-04T12:45:18.123Z] 12:45:18 INFO - "CONSOLE_MESSAGE: (info) 1630759517594 addons.xpi DEBUG scanForChanges changed: false, state: {}"
[task 2021-09-04T12:45:18.123Z] 12:45:18 INFO - "CONSOLE_MESSAGE: (info) 1630759517595 addons.xpi DEBUG Empty XPI database, setting schema version preference to 33"
[task 2021-09-04T12:45:18.123Z] 12:45:18 INFO - "CONSOLE_MESSAGE: (info) 1630759517595 addons.xpi DEBUG No changes found"
[task 2021-09-04T12:45:18.123Z] 12:45:18 INFO - "CONSOLE_MESSAGE: (info) 1630759517612 addons.xpi-utils DEBUG Synchronously loading the add-ons database"
[task 2021-09-04T12:45:18.124Z] 12:45:18 INFO - "CONSOLE_MESSAGE: (info) 1630759517612 addons.xpi-utils DEBUG Starting async load of XPI database C:\\Users\\task_1630757936\\AppData\\Local\\Temp\\xpc-profile-sn23ogtj\\extensions.json"
[task 2021-09-04T12:45:18.124Z] 12:45:18 INFO - "CONSOLE_MESSAGE: (info) 1630759517622 addons.xpi-utils DEBUG Rebuilding XPI database with no extensions"
[task 2021-09-04T12:45:18.124Z] 12:45:18 INFO - "CONSOLE_MESSAGE: (info) 1630759517625 addons.manager DEBUG Registering shutdown blocker for XPIProvider"
[task 2021-09-04T12:45:18.124Z] 12:45:18 INFO - "CONSOLE_MESSAGE: (info) 1630759517626 addons.manager DEBUG Provider finished startup: XPIProvider"
[task 2021-09-04T12:45:18.125Z] 12:45:18 INFO - "CONSOLE_MESSAGE: (info) 1630759517626 addons.manager DEBUG Completed startup sequence"
[task 2021-09-04T12:45:18.125Z] 12:45:18 INFO - PID 9376 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_1630757936\\AppData\\Local\\Temp\\xpc-profile-sn23ogtj\\search.json.mozlz4", (void 0), 240))
[task 2021-09-04T12:45:18.125Z] 12:45:18 INFO - PID 9376 | console.error: "Could not load engine fixup_search@search.mozilla.org: Error: File resource://search-extensions/fixup_search/ does not contain a valid manifest"
[task 2021-09-04T12:45:18.126Z] 12:45:18 INFO - (xpcshell/head.js) | test run_next_test 1 pending (2)
[task 2021-09-04T12:45:18.126Z] 12:45:18 INFO - (xpcshell/head.js) | test setup finished (2)
[task 2021-09-04T12:45:18.126Z] 12:45:18 INFO - docshell/test/unit/test_URIFixup.js | Starting test_unset_pref_fixes_typos
[task 2021-09-04T12:45:18.126Z] 12:45:18 INFO - (xpcshell/head.js) | test test_unset_pref_fixes_typos pending (2)
[task 2021-09-04T12:45:18.127Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_unset_pref_fixes_typos - [test_unset_pref_fixes_typos : 93] "http://www.example.com/" == "http://www.example.com/"
[task 2021-09-04T12:45:18.127Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_unset_pref_fixes_typos - [test_unset_pref_fixes_typos : 93] "http://www.example.com/" == "http://www.example.com/"
[task 2021-09-04T12:45:18.127Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_unset_pref_fixes_typos - [test_unset_pref_fixes_typos : 93] "https://www.example.com/" == "https://www.example.com/"
[task 2021-09-04T12:45:18.128Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_unset_pref_fixes_typos - [test_unset_pref_fixes_typos : 93] "https://www.example.com/" == "https://www.example.com/"
[task 2021-09-04T12:45:18.128Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_unset_pref_fixes_typos - [test_unset_pref_fixes_typos : 93] "https://www.example.com/" == "https://www.example.com/"
[task 2021-09-04T12:45:18.128Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_unset_pref_fixes_typos - [test_unset_pref_fixes_typos : 93] "https://www.example.com/" == "https://www.example.com/"
[task 2021-09-04T12:45:18.129Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_unset_pref_fixes_typos - [test_unset_pref_fixes_typos : 93] "file:///this/is/a/test.html" == "file:///this/is/a/test.html"
[task 2021-09-04T12:45:18.129Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_unset_pref_fixes_typos - [test_unset_pref_fixes_typos : 93] "file:///this/is/a/test.html" == "file:///this/is/a/test.html"
[task 2021-09-04T12:45:18.129Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_unset_pref_fixes_typos - [test_unset_pref_fixes_typos : 93] "http://www.example.com/" == "http://www.example.com/"
[task 2021-09-04T12:45:18.130Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_unset_pref_fixes_typos - [test_unset_pref_fixes_typos : 93] "https://www.example.com/" == "https://www.example.com/"
[task 2021-09-04T12:45:18.130Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_unset_pref_fixes_typos - [test_unset_pref_fixes_typos : 93] "file:///this/is/a/test.html" == "file:///this/is/a/test.html"
[task 2021-09-04T12:45:18.130Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_unset_pref_fixes_typos - [test_unset_pref_fixes_typos : 93] "https://example.com/this/is/a/test.html" == "https://example.com/this/is/a/test.html"
[task 2021-09-04T12:45:18.130Z] 12:45:18 INFO - PID 9376 | console.error: SearchService: "Could not find a replacement default engine."
[task 2021-09-04T12:45:18.131Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_unset_pref_fixes_typos - [test_unset_pref_fixes_typos : 93] "whatever://this/is/a/test.html" == "whatever://this/is/a/test.html"
[task 2021-09-04T12:45:18.131Z] 12:45:18 INFO - (xpcshell/head.js) | test run_next_test 1 finished (2)
[task 2021-09-04T12:45:18.131Z] 12:45:18 INFO - (xpcshell/head.js) | test run_next_test 2 pending (2)
[task 2021-09-04T12:45:18.132Z] 12:45:18 INFO - (xpcshell/head.js) | test test_unset_pref_fixes_typos finished (2)
[task 2021-09-04T12:45:18.132Z] 12:45:18 INFO - docshell/test/unit/test_URIFixup.js | Starting test_false_pref_keeps_typos
[task 2021-09-04T12:45:18.132Z] 12:45:18 INFO - (xpcshell/head.js) | test test_false_pref_keeps_typos pending (2)
[task 2021-09-04T12:45:18.132Z] 12:45:18 INFO - PID 9376 | console.error: SearchService: "Could not find a replacement default engine."
[task 2021-09-04T12:45:18.132Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_false_pref_keeps_typos - [test_false_pref_keeps_typos : 107] "ttp://www.example.com/" == "ttp://www.example.com/"
[task 2021-09-04T12:45:18.133Z] 12:45:18 INFO - PID 9376 | console.error: SearchService: "Could not find a replacement default engine."
[task 2021-09-04T12:45:18.133Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_false_pref_keeps_typos - [test_false_pref_keeps_typos : 107] "htp://www.example.com/" == "htp://www.example.com/"
[task 2021-09-04T12:45:18.133Z] 12:45:18 INFO - PID 9376 | console.error: SearchService: "Could not find a replacement default engine."
[task 2021-09-04T12:45:18.134Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_false_pref_keeps_typos - [test_false_pref_keeps_typos : 107] "ttps://www.example.com/" == "ttps://www.example.com/"
[task 2021-09-04T12:45:18.134Z] 12:45:18 INFO - PID 9376 | console.error: SearchService: "Could not find a replacement default engine."
[task 2021-09-04T12:45:18.134Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_false_pref_keeps_typos - [test_false_pref_keeps_typos : 107] "tps://www.example.com/" == "tps://www.example.com/"
[task 2021-09-04T12:45:18.134Z] 12:45:18 INFO - PID 9376 | console.error: SearchService: "Could not find a replacement default engine."
[task 2021-09-04T12:45:18.135Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_false_pref_keeps_typos - [test_false_pref_keeps_typos : 107] "ps://www.example.com/" == "ps://www.example.com/"
[task 2021-09-04T12:45:18.135Z] 12:45:18 INFO - PID 9376 | console.error: SearchService: "Could not find a replacement default engine."
[task 2021-09-04T12:45:18.135Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_false_pref_keeps_typos - [test_false_pref_keeps_typos : 107] "htps://www.example.com/" == "htps://www.example.com/"
[task 2021-09-04T12:45:18.136Z] 12:45:18 INFO - PID 9376 | console.error: SearchService: "Could not find a replacement default engine."
[task 2021-09-04T12:45:18.136Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_false_pref_keeps_typos - [test_false_pref_keeps_typos : 107] "ile:///this/is/a/test.html" == "ile:///this/is/a/test.html"
[task 2021-09-04T12:45:18.136Z] 12:45:18 INFO - PID 9376 | console.error: SearchService: "Could not find a replacement default engine."
[task 2021-09-04T12:45:18.136Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_false_pref_keeps_typos - [test_false_pref_keeps_typos : 107] "le:///this/is/a/test.html" == "le:///this/is/a/test.html"
[task 2021-09-04T12:45:18.137Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_false_pref_keeps_typos - [test_false_pref_keeps_typos : 107] "http://http;//www.example.com/" == "http://http;//www.example.com/"
[task 2021-09-04T12:45:18.137Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_false_pref_keeps_typos - [test_false_pref_keeps_typos : 107] "http://https//www.example.com/" == "http://https//www.example.com/"
[task 2021-09-04T12:45:18.137Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_false_pref_keeps_typos - [test_false_pref_keeps_typos : 107] "http://file///this/is/a/test.html" == "http://file///this/is/a/test.html"
[task 2021-09-04T12:45:18.138Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_false_pref_keeps_typos - [test_false_pref_keeps_typos : 107] "https://example.com/this/is/a/test.html" == "https://example.com/this/is/a/test.html"
[task 2021-09-04T12:45:18.138Z] 12:45:18 INFO - PID 9376 | console.error: SearchService: "Could not find a replacement default engine."
[task 2021-09-04T12:45:18.138Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_false_pref_keeps_typos - [test_false_pref_keeps_typos : 107] "whatever://this/is/a/test.html" == "whatever://this/is/a/test.html"
[task 2021-09-04T12:45:18.138Z] 12:45:18 INFO - (xpcshell/head.js) | test run_next_test 2 finished (2)
[task 2021-09-04T12:45:18.139Z] 12:45:18 INFO - (xpcshell/head.js) | test run_next_test 3 pending (2)
[task 2021-09-04T12:45:18.139Z] 12:45:18 INFO - (xpcshell/head.js) | test test_false_pref_keeps_typos finished (2)
[task 2021-09-04T12:45:18.139Z] 12:45:18 INFO - docshell/test/unit/test_URIFixup.js | Starting test_true_pref_fixes_typos
[task 2021-09-04T12:45:18.139Z] 12:45:18 INFO - (xpcshell/head.js) | test test_true_pref_fixes_typos pending (2)
[task 2021-09-04T12:45:18.140Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_true_pref_fixes_typos - [test_true_pref_fixes_typos : 121] "http://www.example.com/" == "http://www.example.com/"
[task 2021-09-04T12:45:18.140Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_true_pref_fixes_typos - [test_true_pref_fixes_typos : 121] "http://www.example.com/" == "http://www.example.com/"
[task 2021-09-04T12:45:18.140Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_true_pref_fixes_typos - [test_true_pref_fixes_typos : 121] "https://www.example.com/" == "https://www.example.com/"
[task 2021-09-04T12:45:18.141Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_true_pref_fixes_typos - [test_true_pref_fixes_typos : 121] "https://www.example.com/" == "https://www.example.com/"
[task 2021-09-04T12:45:18.141Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_true_pref_fixes_typos - [test_true_pref_fixes_typos : 121] "https://www.example.com/" == "https://www.example.com/"
[task 2021-09-04T12:45:18.141Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_true_pref_fixes_typos - [test_true_pref_fixes_typos : 121] "https://www.example.com/" == "https://www.example.com/"
[task 2021-09-04T12:45:18.142Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_true_pref_fixes_typos - [test_true_pref_fixes_typos : 121] "file:///this/is/a/test.html" == "file:///this/is/a/test.html"
[task 2021-09-04T12:45:18.142Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_true_pref_fixes_typos - [test_true_pref_fixes_typos : 121] "file:///this/is/a/test.html" == "file:///this/is/a/test.html"
[task 2021-09-04T12:45:18.142Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_true_pref_fixes_typos - [test_true_pref_fixes_typos : 121] "http://www.example.com/" == "http://www.example.com/"
[task 2021-09-04T12:45:18.142Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_true_pref_fixes_typos - [test_true_pref_fixes_typos : 121] "https://www.example.com/" == "https://www.example.com/"
[task 2021-09-04T12:45:18.143Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_true_pref_fixes_typos - [test_true_pref_fixes_typos : 121] "file:///this/is/a/test.html" == "file:///this/is/a/test.html"
[task 2021-09-04T12:45:18.143Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_true_pref_fixes_typos - [test_true_pref_fixes_typos : 121] "https://example.com/this/is/a/test.html" == "https://example.com/this/is/a/test.html"
[task 2021-09-04T12:45:18.143Z] 12:45:18 INFO - PID 9376 | console.error: SearchService: "Could not find a replacement default engine."
[task 2021-09-04T12:45:18.144Z] 12:45:18 INFO - TEST-PASS | docshell/test/unit/test_URIFixup.js | test_true_pref_fixes_typos - [test_true_pref_fixes_typos : 121] "whatever://this/is/a/test.html" == "whatever://this/is/a/test.html"
[task 2021-09-04T12:45:18.144Z] 12:45:18 INFO - (xpcshell/head.js) | test run_next_test 3 finished (2)
[task 2021-09-04T12:45:18.144Z] 12:45:18 INFO - (xpcshell/head.js) | test run_next_test 4 pending (2)
[task 2021-09-04T12:45:18.144Z] 12:45:18 INFO - (xpcshell/head.js) | test test_true_pref_fixes_typos finished (2)
[task 2021-09-04T12:45:18.145Z] 12:45:18 INFO - (xpcshell/head.js) | test run_next_test 4 finished (1)
[task 2021-09-04T12:45:18.145Z] 12:45:18 INFO - exiting test
[task 2021-09-04T12:45:18.145Z] 12:45:18 INFO - "CONSOLE_MESSAGE: (info) 1630759517732 addons.manager DEBUG before shutdown"
[task 2021-09-04T12:45:18.145Z] 12:45:18 INFO - "CONSOLE_MESSAGE: (info) 1630759517733 addons.manager DEBUG shutdown"
[task 2021-09-04T12:45:18.145Z] 12:45:18 INFO - "CONSOLE_MESSAGE: (info) 1630759517733 addons.manager DEBUG Calling shutdown blocker for XPIProvider"
[task 2021-09-04T12:45:18.146Z] 12:45:18 INFO - "CONSOLE_MESSAGE: (info) 1630759517733 addons.xpi DEBUG shutdown"
[task 2021-09-04T12:45:18.146Z] 12:45:18 INFO - "CONSOLE_MESSAGE: (info) 1630759517733 addons.xpi-utils DEBUG shutdown"
[task 2021-09-04T12:45:18.146Z] 12:45:18 INFO - "CONSOLE_MESSAGE: (info) 1630759517742 addons.manager DEBUG Async provider shutdown done"
[task 2021-09-04T12:45:18.147Z] 12:45:18 INFO - PID 9376 | console.error: SearchSettings: "_write: Could not write to settings file:" (new Error("cannot write without any engine.", "resource://gre/modules/SearchSettings.jsm", 216))
[task 2021-09-04T12:45:18.147Z] 12:45:18 INFO - PID 9376 | [Parent 9376, IPDL Background] WARNING: idb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3713
[task 2021-09-04T12:45:18.147Z] 12:45:18 INFO - PID 9376 | [Parent 9376, IPDL Background] WARNING: cache: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3713
[task 2021-09-04T12:45:18.147Z] 12:45:18 INFO - PID 9376 | [Parent 9376, IPDL Background] WARNING: sdb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3713
[task 2021-09-04T12:45:18.148Z] 12:45:18 INFO - PID 9376 | [Parent 9376, IPDL Background] WARNING: ls: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3713
[task 2021-09-04T12:45:18.148Z] 12:45:18 INFO - PID 9376 | [Parent 9376, IPDL Background] WARNING: idb: '0.016000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3713
[task 2021-09-04T12:45:18.148Z] 12:45:18 INFO - PID 9376 | [Parent 9376, IPDL Background] WARNING: cache: '0.016000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3713
[task 2021-09-04T12:45:18.149Z] 12:45:18 INFO - PID 9376 | [Parent 9376, IPDL Background] WARNING: sdb: '0.016000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3713
[task 2021-09-04T12:45:18.149Z] 12:45:18 INFO - PID 9376 | [Parent 9376, IPDL Background] WARNING: ls: '0.016000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3713
[task 2021-09-04T12:45:18.149Z] 12:45:18 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "NetworkError: Network request failed" {file: "resource://services-settings/Utils.jsm" line: 117}]
[task 2021-09-04T12:45:18.149Z] 12:45:18 INFO - fetch/</request.onerror@resource://services-settings/Utils.jsm:117:16
[task 2021-09-04T12:45:18.150Z] 12:45:18 INFO - _execute_test@Z:\\task_1630757936\\build\\tests\\xpcshell\\head.js:701:19
[task 2021-09-04T12:45:18.150Z] 12:45:18 INFO - @-e:1:1
[task 2021-09-04T12:45:18.150Z] 12:45:18 INFO - "
[task 2021-09-04T12:45:18.150Z] 12:45:18 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "IndexedDB: main/hijack-blocklists list() IndexedDB: execute() The application is shutting down" {file: "resource://services-settings/IDBHelpers.jsm" line: 24}]
[task 2021-09-04T12:45:18.151Z] 12:45:18 INFO - IndexedDBError@resource://services-settings/IDBHelpers.jsm:24:5
[task 2021-09-04T12:45:18.151Z] 12:45:18 INFO - list@resource://services-settings/Database.jsm:70:13
[task 2021-09-04T12:45:18.151Z] 12:45:18 INFO - _execute_test@Z:\\task_1630757936\\build\\tests\\xpcshell\\head.js:701:19
[task 2021-09-04T12:45:18.151Z] 12:45:18 INFO - @-e:1:1
[task 2021-09-04T12:45:18.151Z] 12:45:18 INFO - "
[task 2021-09-04T12:45:18.151Z] 12:45:18 INFO - PID 9376 | [Parent 9376, Main Thread] WARNING: unable to post SHUTDOWN message: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:153
[task 2021-09-04T12:45:18.152Z] 12:45:18 INFO - PID 9376 | Storage connection not closed: cookies.sqliteHit MOZ_CRASH() at /builds/worker/checkouts/gecko/storage/mozStorageService.cpp:728
[task 2021-09-04T12:45:18.152Z] 12:45:18 INFO - Initializing stack-fixing for the first stack frame, this may take a while...
[task 2021-09-04T12:45:27.837Z] 12:45:27 INFO - PID 9376 | #01: mozilla::storage::Service::Observe(nsISupports*, char const*, char16_t const*) [storage/mozStorageService.cpp:728]
[task 2021-09-04T12:45:27.837Z] 12:45:27 INFO - PID 9376 | #02: nsObserverList::NotifyObservers(nsISupports*, char const*, char16_t const*) [xpcom/ds/nsObserverList.cpp:69]
[task 2021-09-04T12:45:27.837Z] 12:45:27 INFO - PID 9376 | #03: nsObserverService::NotifyObservers(nsISupports*, char const*, char16_t const*) [xpcom/ds/nsObserverService.cpp:291]
[task 2021-09-04T12:45:27.838Z] 12:45:27 INFO - PID 9376 | #04: mozilla::AdvanceShutdownPhaseInternal(mozilla::ShutdownPhase, bool, char16_t const*, nsCOMPtr<nsISupports> const&) [xpcom/base/AppShutdown.cpp:333]
[task 2021-09-04T12:45:27.838Z] 12:45:27 INFO - PID 9376 | #05: static mozilla::AppShutdown::AdvanceShutdownPhase(mozilla::ShutdownPhase, char16_t const*, nsCOMPtr<nsISupports> const&) [xpcom/base/AppShutdown.cpp:349]
[task 2021-09-04T12:45:27.838Z] 12:45:27 INFO - PID 9376 | #06: mozilla::ShutdownXPCOM(nsIServiceManager*) [xpcom/build/XPCOMInit.cpp:624]
[task 2021-09-04T12:45:27.838Z] 12:45:27 INFO - PID 9376 | #07: XRE_XPCShellMain(int, char**, char**, XREShellData const*) [js/xpconnect/src/XPCShellImpl.cpp:1432]
[task 2021-09-04T12:45:27.839Z] 12:45:27 INFO - PID 9376 | #08: mozilla::BootstrapImpl::XRE_XPCShellMain(int, char**, char**, XREShellData const*) [toolkit/xre/Bootstrap.cpp:54]
[task 2021-09-04T12:45:27.883Z] 12:45:27 INFO - PID 9376 | #09: NS_internal_main(int, char**, char**) [js/xpconnect/shell/xpcshell.cpp:82]
[task 2021-09-04T12:45:27.883Z] 12:45:27 INFO - PID 9376 | #10: wmain(int, wchar_t**) [toolkit/xre/nsWindowsWMain.cpp:131]
[task 2021-09-04T12:45:27.884Z] 12:45:27 INFO - PID 9376 | #11: __scrt_common_main_seh() [/builds/worker/workspace/obj-build/js/xpconnect/shell/d:/agent/_work/3/s/src/vctools/crt/vcstartup/src/startup/exe_common.inl:288]
[task 2021-09-04T12:45:27.886Z] 12:45:27 INFO - fix-stacks: error: failed to read debug info file `wkernel32.pdb` for `C:\Windows\System32\KERNEL32.DLL`
[task 2021-09-04T12:45:27.886Z] 12:45:27 INFO - fix-stacks: note: this is expected and harmless for all PDB files on opt automation runs
[task 2021-09-04T12:45:27.886Z] 12:45:27 INFO - fix-stacks: The system cannot find the file specified. (os error 2)
[task 2021-09-04T12:45:27.887Z] 12:45:27 INFO - PID 9376 | #12: BaseThreadInitThunk [C:\Windows\System32\KERNEL32.DLL + 0x18484]
[task 2021-09-04T12:45:27.890Z] 12:45:27 INFO - fix-stacks: error: failed to read debug info file `wntdll.pdb` for `C:\Windows\SYSTEM32\ntdll.dll`
[task 2021-09-04T12:45:27.890Z] 12:45:27 INFO - fix-stacks: note: this is expected and harmless for all PDB files on opt automation runs
[task 2021-09-04T12:45:27.890Z] 12:45:27 INFO - fix-stacks: The system cannot find the file specified. (os error 2)
[task 2021-09-04T12:45:27.891Z] 12:45:27 INFO - PID 9376 | #13: RtlValidSecurityDescriptor [C:\Windows\SYSTEM32\ntdll.dll + 0x6305a]
[task 2021-09-04T12:45:27.891Z] 12:45:27 INFO - PID 9376 | #14: RtlValidSecurityDescriptor [C:\Windows\SYSTEM32\ntdll.dll + 0x6302a]
[task 2021-09-04T12:45:27.891Z] 12:45:27 INFO - <<<<<<<
[task 2021-09-04T12:45:38.747Z] 12:45:38 WARNING - PROCESS-CRASH | docshell/test/unit/test_URIFixup.js | application crashed [@ nsObserverList::NotifyObservers(nsISupports*, char const*, char16_t const*)]
[task 2021-09-04T12:45:38.747Z] 12:45:38 INFO - Mozilla crash reason: MOZ_CRASH()
[task 2021-09-04T12:45:38.748Z] 12:45:38 INFO - Crash dump filename: C:\Users\task_1630757936\AppData\Local\Temp\xpc-other-jftf3x2n\f6a1b2f7-a6f4-40ae-93e8-4c13dfe97cad.dmp
[task 2021-09-04T12:45:38.748Z] 12:45:38 INFO - Operating system: Windows NT
[task 2021-09-04T12:45:38.748Z] 12:45:38 INFO - 10.0.17134
[task 2021-09-04T12:45:38.748Z] 12:45:38 INFO - CPU: x86
[task 2021-09-04T12:45:38.748Z] 12:45:38 INFO - GenuineIntel family 6 model 85 stepping 4
[task 2021-09-04T12:45:38.749Z] 12:45:38 INFO - 8 CPUs
[task 2021-09-04T12:45:38.749Z] 12:45:38 INFO - GPU: UNKNOWN
[task 2021-09-04T12:45:38.749Z] 12:45:38 INFO - Crash reason: EXCEPTION_BREAKPOINT
[task 2021-09-04T12:45:38.749Z] 12:45:38 INFO - Crash address: 0x6030829a
[task 2021-09-04T12:45:38.749Z] 12:45:38 INFO - Process uptime: 1 seconds
[task 2021-09-04T12:45:38.750Z] 12:45:38 INFO - Thread 0 tid 7340 (crashed) - GeckoMain 0 xul.dll!mozilla::storage::Service::Observe(nsISupports*, char const*, char16_t const*) [mozStorageService.cpp:4927dda5a1a3f464e4465ec0e40d21bad5affab9 : 728 + 0x0]
[task 2021-09-04T12:45:38.750Z] 12:45:38 INFO - eip = 0x6030829a esp = 0x0083f1d0 ebp = 0x0083f200 ebx = 0x00000000
[task 2021-09-04T12:45:38.750Z] 12:45:38 INFO - esi = 0x0083f1e8 edi = 0x0083f1dc eax = 0x6a05c62c ecx = 0x00000000
[task 2021-09-04T12:45:38.750Z] 12:45:38 INFO - edx = 0x00000048 efl = 0x00000212
[task 2021-09-04T12:45:38.751Z] 12:45:38 INFO - Found by: given as instruction pointer in context
[task 2021-09-04T12:45:38.751Z] 12:45:38 INFO - 1 xul.dll!nsObserverList::NotifyObservers(nsISupports*, char const*, char16_t const*) [nsObserverList.cpp:4927dda5a1a3f464e4465ec0e40d21bad5affab9 : 70 + 0x15]
[task 2021-09-04T12:45:38.751Z] 12:45:38 INFO - eip = 0x5f3c084d esp = 0x0083f208 ebp = 0x0083f22c ebx = 0x05785944
[task 2021-09-04T12:45:38.751Z] 12:45:38 INFO - esi = 0x00000007 edi = 0x00000000
[task 2021-09-04T12:45:38.751Z] 12:45:38 INFO - Found by: call frame info
[task 2021-09-04T12:45:38.752Z] 12:45:38 INFO - 2 xul.dll!nsObserverService::NotifyObservers(nsISupports*, char const*, char16_t const*) [nsObserverService.cpp:4927dda5a1a3f464e4465ec0e40d21bad5affab9 : 288 + 0xe]
[task 2021-09-04T12:45:38.752Z] 12:45:38 INFO - eip = 0x5f3caa21 esp = 0x0083f234 ebp = 0x0083f374 ebx = 0x66c74be7
[task 2021-09-04T12:45:38.752Z] 12:45:38 INFO - esi = 0x00000000 edi = 0x057017d4
[task 2021-09-04T12:45:38.752Z] 12:45:38 INFO - Found by: call frame info
[task 2021-09-04T12:45:38.753Z] 12:45:38 INFO - 3 xul.dll!mozilla::AdvanceShutdownPhaseInternal(mozilla::ShutdownPhase, bool, char16_t const*, nsCOMPtr<nsISupports> const&) [AppShutdown.cpp:4927dda5a1a3f464e4465ec0e40d21bad5affab9 : 330 + 0x16]
[task 2021-09-04T12:45:38.753Z] 12:45:38 INFO - eip = 0x5f34a841 esp = 0x0083f37c ebp = 0x0083f3a4 ebx = 0x057017c0
[task 2021-09-04T12:45:38.753Z] 12:45:38 INFO - esi = 0x0083f38c edi = 0x00000000
[task 2021-09-04T12:45:38.753Z] 12:45:38 INFO - Found by: call frame info
[task 2021-09-04T12:45:38.753Z] 12:45:38 INFO - 4 xul.dll!static mozilla::AppShutdown::AdvanceShutdownPhase(mozilla::ShutdownPhase, char16_t const*, nsCOMPtr<nsISupports> const&) [AppShutdown.cpp:4927dda5a1a3f464e4465ec0e40d21bad5affab9 : 349 + 0x10]
[task 2021-09-04T12:45:38.755Z] 12:45:38 INFO - eip = 0x5f34abf3 esp = 0x0083f3ac ebp = 0x0083f3bc ebx = 0x0083f3f0
[task 2021-09-04T12:45:38.755Z] 12:45:38 INFO - esi = 0x0083f3d0 edi = 0x057845b0
[task 2021-09-04T12:45:38.755Z] 12:45:38 INFO - Found by: call frame info
[task 2021-09-04T12:45:38.755Z] 12:45:38 INFO - 5 xul.dll!mozilla::ShutdownXPCOM(nsIServiceManager*) [XPCOMInit.cpp:4927dda5a1a3f464e4465ec0e40d21bad5affab9 : 624 + 0xa]
[task 2021-09-04T12:45:38.755Z] 12:45:38 INFO - eip = 0x5f4b7a20 esp = 0x0083f3c4 ebp = 0x0083f464
[task 2021-09-04T12:45:38.756Z] 12:45:38 INFO - Found by: call frame info
[task 2021-09-04T12:45:38.756Z] 12:45:38 INFO - 6 xul.dll!XRE_XPCShellMain(int, char**, char**, XREShellData const*) [XPCShellImpl.cpp:4927dda5a1a3f464e4465ec0e40d21bad5affab9 : 1432 + 0x7]
[task 2021-09-04T12:45:38.756Z] 12:45:38 INFO - eip = 0x60299d3a esp = 0x0083f46c ebp = 0x0083f728 ebx = 0x057169a0
[task 2021-09-04T12:45:38.756Z] 12:45:38 INFO - esi = 0x0083f470 edi = 0x00000001
[task 2021-09-04T12:45:38.756Z] 12:45:38 INFO - Found by: call frame info
[task 2021-09-04T12:45:38.757Z] 12:45:38 INFO - 7 xul.dll!mozilla::BootstrapImpl::XRE_XPCShellMain(int, char**, char**, XREShellData const*) [Bootstrap.cpp:4927dda5a1a3f464e4465ec0e40d21bad5affab9 : 54 + 0x9]
[task 2021-09-04T12:45:38.757Z] 12:45:38 INFO - eip = 0x6498d723 esp = 0x0083f730 ebp = 0x0083f740 ebx = 0x04d2f0f0
[task 2021-09-04T12:45:38.757Z] 12:45:38 INFO - esi = 0x05706098 edi = 0x6498d710
[task 2021-09-04T12:45:38.757Z] 12:45:38 INFO - Found by: call frame info
[task 2021-09-04T12:45:38.757Z] 12:45:38 INFO - 8 xpcshell.exe!NS_internal_main(int, char**, char**) [xpcshell.cpp:4927dda5a1a3f464e4465ec0e40d21bad5affab9 : 82 + 0x1f]
[task 2021-09-04T12:45:38.757Z] 12:45:38 INFO - eip = 0x00ac127e esp = 0x0083f748 ebp = 0x0083f774
[task 2021-09-04T12:45:38.757Z] 12:45:38 INFO - Found by: call frame info
[task 2021-09-04T12:45:38.757Z] 12:45:38 INFO - 9 xpcshell.exe!wmain(int, wchar_t**) [nsWindowsWMain.cpp:4927dda5a1a3f464e4465ec0e40d21bad5affab9 : 131 + 0xf]
[task 2021-09-04T12:45:38.757Z] 12:45:38 INFO - eip = 0x00ac119b esp = 0x0083f77c ebp = 0x0083f7a0 ebx = 0x05703180
[task 2021-09-04T12:45:38.758Z] 12:45:38 INFO - esi = 0x0000001c edi = 0x05703700
[task 2021-09-04T12:45:38.758Z] 12:45:38 INFO - Found by: call frame info
[task 2021-09-04T12:45:38.758Z] 12:45:38 INFO - 10 xpcshell.exe!__scrt_common_main_seh() [exe_common.inl : 288 + 0x1c]
[task 2021-09-04T12:45:38.758Z] 12:45:38 INFO - eip = 0x00b317d9 esp = 0x0083f7a8 ebp = 0x0083f7e8 ebx = 0x0053a000
[task 2021-09-04T12:45:38.758Z] 12:45:38 INFO - esi = 0x7463f0b0 edi = 0x04d2c890
[task 2021-09-04T12:45:38.759Z] 12:45:38 INFO - Found by: call frame info
[task 2021-09-04T12:45:38.759Z] 12:45:38 INFO - 11 kernel32.dll!BaseThreadInitThunk + 0x24
[task 2021-09-04T12:45:38.759Z] 12:45:38 INFO - eip = 0x76cb8484 esp = 0x0083f7f0 ebp = 0x0083f7fc
[task 2021-09-04T12:45:38.759Z] 12:45:38 INFO - Found by: call frame info
[task 2021-09-04T12:45:38.759Z] 12:45:38 INFO - 12 ntdll.dll!AslPathWildcardFindFirst + 0x218
[task 2021-09-04T12:45:38.760Z] 12:45:38 INFO - eip = 0x76f6305a esp = 0x0083f804 ebp = 0x0083f844
[task 2021-09-04T12:45:38.760Z] 12:45:38 INFO - Found by: call frame info
[task 2021-09-04T12:45:38.760Z] 12:45:38 INFO - 13 ntdll.dll!AslPathWildcardFindFirst + 0x1e8
[task 2021-09-04T12:45:38.760Z] 12:45:38 INFO - eip = 0x76f6302a esp = 0x0083f84c ebp = 0x0083f854
[task 2021-09-04T12:45:38.760Z] 12:45:38 INFO - Found by: call frame info
Description
•