Intermittent toolkit/components/search/tests/xpcshell/test_defaultEngine_fallback.js | xpcshell return code: 0
Categories
(Firefox :: Search, defect, P5)
Tracking
()
Tracking | Status | |
---|---|---|
firefox-esr78 | --- | unaffected |
firefox87 | --- | unaffected |
firefox88 | --- | unaffected |
firefox89 | --- | affected |
People
(Reporter: intermittent-bug-filer, Unassigned)
References
(Regression)
Details
(Keywords: intermittent-failure, regression)
Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=335161122&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/BH4pXH7gTm-DkW3Pmh9azA/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/BH4pXH7gTm-DkW3Pmh9azA/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1
[task 2021-04-01T15:01:51.298Z] 15:01:51 INFO - TEST-PASS | xpcshell.ini:toolkit/components/search/tests/xpcshell/test_defaultEngine_fallback.js | test_default_fallback_non_builtin_private - [test_default_fallback_non_builtin_private : 376] Should have notified the correct default engine - "engine-pref" == "engine-pref"
[task 2021-04-01T15:01:51.298Z] 15:01:51 INFO - (xpcshell/head.js) | test run_next_test 10 pending (2)
[task 2021-04-01T15:01:51.298Z] 15:01:51 INFO - (xpcshell/head.js) | test test_default_fallback_non_builtin_private finished (2)
[task 2021-04-01T15:01:51.298Z] 15:01:51 INFO - (xpcshell/head.js) | test run_next_test 10 finished (1)
[task 2021-04-01T15:01:51.298Z] 15:01:51 INFO - exiting test
[task 2021-04-01T15:01:51.298Z] 15:01:51 INFO - PID 2708 | console.debug: SearchService: "init"
[task 2021-04-01T15:01:51.298Z] 15:01:51 INFO - PID 2708 | console.debug: SearchService: "init"
[task 2021-04-01T15:01:51.298Z] 15:01:51 INFO - PID 2708 | console.debug: SearchSettings: "batchTask: Invalidating engine settings"
[task 2021-04-01T15:01:51.298Z] 15:01:51 INFO - PID 2708 | console.debug: SearchSettings: "_write: Writing to settings file."
[task 2021-04-01T15:01:51.298Z] 15:01:51 INFO - PID 2708 | WARNING: A blocker encountered an error while we were waiting.
[task 2021-04-01T15:01:51.298Z] 15:01:51 INFO - PID 2708 | Blocker: Flush WebExtension StartupCache
[task 2021-04-01T15:01:51.298Z] 15:01:51 INFO - PID 2708 | Phase: IOUtils: waiting for profileBeforeChange IO to complete
[task 2021-04-01T15:01:51.298Z] 15:01:51 INFO - PID 2708 | State: (none)
[task 2021-04-01T15:01:51.299Z] 15:01:51 INFO - PID 2708 | WARNING: Error: The object has been already finalized.
[task 2021-04-01T15:01:51.299Z] 15:01:51 INFO - PID 2708 | WARNING: finalize@resource://gre/modules/DeferredTask.jsm:267:13
[task 2021-04-01T15:01:51.299Z] 15:01:51 INFO - PID 2708 | save/<@resource://gre/modules/ExtensionParent.jsm:1725:32
[task 2021-04-01T15:01:51.299Z] 15:01:51 INFO - PID 2708 | trigger@resource://gre/modules/AsyncShutdown.jsm:747:23
[task 2021-04-01T15:01:51.299Z] 15:01:51 INFO - PID 2708 | _wait@resource://gre/modules/AsyncShutdown.jsm:899:15
[task 2021-04-01T15:01:51.299Z] 15:01:51 INFO - PID 2708 | wait@resource://gre/modules/AsyncShutdown.jsm:880:34
[task 2021-04-01T15:01:51.299Z] 15:01:51 INFO - PID 2708 | wait@resource://gre/modules/nsAsyncShutdown.jsm:209:25
[task 2021-04-01T15:01:51.299Z] 15:01:51 INFO - PID 2708 | addBlocker/moduleBlocker/<@resource://gre/modules/nsAsyncShutdown.jsm:156:30
[task 2021-04-01T15:01:51.299Z] 15:01:51 INFO - PID 2708 | moduleBlocker@resource://gre/modules/nsAsyncShutdown.jsm:152:9
[task 2021-04-01T15:01:51.299Z] 15:01:51 INFO - PID 2708 | trigger@resource://gre/modules/AsyncShutdown.jsm:747:23
[task 2021-04-01T15:01:51.299Z] 15:01:51 INFO - PID 2708 | _wait@resource://gre/modules/AsyncShutdown.jsm:899:15
[task 2021-04-01T15:01:51.299Z] 15:01:51 INFO - PID 2708 | wait@resource://gre/modules/AsyncShutdown.jsm:880:34
[task 2021-04-01T15:01:51.299Z] 15:01:51 INFO - PID 2708 | observe@resource://gre/modules/AsyncShutdown.jsm:557:10
[task 2021-04-01T15:01:51.299Z] 15:01:51 INFO - PID 2708 | _execute_test@Z:\\task_1617288083\\build\\tests\\xpcshell\\head.js:701:19
[task 2021-04-01T15:01:51.299Z] 15:01:51 INFO - PID 2708 | @-e:1:1
[task 2021-04-01T15:01:51.299Z] 15:01:51 INFO - PID 2708 | console.debug: SearchSettings: "_write: settings file written to disk."
[task 2021-04-01T15:01:51.300Z] 15:01:51 INFO - PID 2708 | JavaScript error: resource://gre/modules/DeferredTask.jsm, line 267: Error: The object has been already finalized.
[task 2021-04-01T15:01:51.300Z] 15:01:51 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "Error: The object has been already finalized." {file: "resource://gre/modules/DeferredTask.jsm" line: 267}]"
[task 2021-04-01T15:01:51.300Z] 15:01:51 WARNING - TEST-UNEXPECTED-FAIL | xpcshell.ini:toolkit/components/search/tests/xpcshell/test_defaultEngine_fallback.js | test_default_fallback_non_builtin_private - [test_default_fallback_non_builtin_private : 285] A promise chain failed to handle a rejection: The object has been already finalized. - stack: finalize@resource://gre/modules/DeferredTask.jsm:267:13
[task 2021-04-01T15:01:51.300Z] 15:01:51 INFO - save/<@resource://gre/modules/ExtensionParent.jsm:1725:32
[task 2021-04-01T15:01:51.300Z] 15:01:51 INFO - trigger@resource://gre/modules/AsyncShutdown.jsm:747:23
[task 2021-04-01T15:01:51.300Z] 15:01:51 INFO - _wait@resource://gre/modules/AsyncShutdown.jsm:899:15
[task 2021-04-01T15:01:51.300Z] 15:01:51 INFO - wait@resource://gre/modules/AsyncShutdown.jsm:880:34
[task 2021-04-01T15:01:51.300Z] 15:01:51 INFO - wait@resource://gre/modules/nsAsyncShutdown.jsm:209:25
[task 2021-04-01T15:01:51.300Z] 15:01:51 INFO - addBlocker/moduleBlocker/<@resource://gre/modules/nsAsyncShutdown.jsm:156:30
[task 2021-04-01T15:01:51.300Z] 15:01:51 INFO - moduleBlocker@resource://gre/modules/nsAsyncShutdown.jsm:152:9
[task 2021-04-01T15:01:51.300Z] 15:01:51 INFO - trigger@resource://gre/modules/AsyncShutdown.jsm:747:23
[task 2021-04-01T15:01:51.300Z] 15:01:51 INFO - _wait@resource://gre/modules/AsyncShutdown.jsm:899:15
[task 2021-04-01T15:01:51.300Z] 15:01:51 INFO - wait@resource://gre/modules/AsyncShutdown.jsm:880:34
[task 2021-04-01T15:01:51.301Z] 15:01:51 INFO - observe@resource://gre/modules/AsyncShutdown.jsm:557:10
[task 2021-04-01T15:01:51.301Z] 15:01:51 INFO - _execute_test@Z:\\task_1617288083\\build\\tests\\xpcshell\\head.js:701:19
[task 2021-04-01T15:01:51.301Z] 15:01:51 INFO - @-e:1:1
[task 2021-04-01T15:01:51.301Z] 15:01:51 INFO - Rejection date: Thu Apr 01 2021 15:01:51 GMT+0000 (Greenwich Mean Time) - false == true
[task 2021-04-01T15:01:51.301Z] 15:01:51 INFO - resource://testing-common/PromiseTestUtils.jsm:assertNoUncaughtRejections:285
[task 2021-04-01T15:01:51.301Z] 15:01:51 INFO - Z:\task_1617288083\build\tests\xpcshell\head.js:_execute_test:709
[task 2021-04-01T15:01:51.301Z] 15:01:51 INFO - -e:null:1
[task 2021-04-01T15:01:51.301Z] 15:01:51 INFO - exiting test
[task 2021-04-01T15:01:51.301Z] 15:01:51 INFO - PID 2708 | JavaScript error: Z:\\task_1617288083\\build\\tests\\xpcshell\\head.js, line 861: NS_ERROR_ABORT:
[task 2021-04-01T15:01:51.301Z] 15:01:51 INFO - <<<<<<<
[task 2021-04-01T15:01:51.316Z] 15:01:51 INFO - TEST-START | xpcshell.ini:toolkit/components/search/tests/xpcshell/test_defaultEngine_fallback.js
[task 2021-04-01T15:01:52.119Z] 15:01:52 INFO - TEST-PASS | xpcshell.ini:toolkit/components/search/tests/xpcshell/test_defaultEngine_fallback.js | took 807ms
[task 2021-04-01T15:01:52.146Z] 15:01:52 INFO - TEST-START | xpcshell.ini:toolkit/components/search/tests/xpcshell/test_defaultEngine_fallback.js
[task 2021-04-01T15:01:52.949Z] 15:01:52 INFO - TEST-PASS | xpcshell.ini:toolkit/components/search/tests/xpcshell/test_defaultEngine_fallback.js | took 802ms
[task 2021-04-01T15:01:52.968Z] 15:01:52 INFO - INFO | Result summary:
[task 2021-04-01T15:01:52.968Z] 15:01:52 INFO - INFO | Passed: 19
[task 2021-04-01T15:01:52.968Z] 15:01:52 WARNING - INFO | Failed: 1
[task 2021-04-01T15:01:52.968Z] 15:01:52 WARNING - One or more unittests failed.
[task 2021-04-01T15:01:52.968Z] 15:01:52 INFO - INFO | Todo: 0
[task 2021-04-01T15:01:52.969Z] 15:01:52 INFO - INFO | Retried: 0
[task 2021-04-01T15:01:52.969Z] 15:01:52 INFO - SUITE-END | took 8s
[task 2021-04-01T15:01:52.969Z] 15:01:52 INFO - :::
[task 2021-04-01T15:01:52.969Z] 15:01:52 INFO - ::: Test verification summary for: Z:\task_1617288083\build\tests\xpcshell\tests\toolkit\components\search\tests\xpcshell\test_defaultEngine_fallback.js
[task 2021-04-01T15:01:52.969Z] 15:01:52 INFO - :::
[task 2021-04-01T15:01:52.969Z] 15:01:52 INFO - ::: 1. Run each test 10 times, sequentially. : Pass
[task 2021-04-01T15:01:52.969Z] 15:01:52 INFO - ::: 2. Run each test 10 times, sequentially, in chaos mode. : FAIL
[task 2021-04-01T15:01:52.969Z] 15:01:52 INFO - :::
[task 2021-04-01T15:01:52.970Z] 15:01:52 INFO - ::: Test verification FAILED!
[task 2021-04-01T15:01:52.970Z] 15:01:52 INFO - :::
[task 2021-04-01T15:01:52.970Z] 15:01:52 INFO - Node moz-http2 server shutting down ...
[task 2021-04-01T15:01:53.014Z] 15:01:53 ERROR - Return code: 1
[task 2021-04-01T15:01:53.014Z] 15:01:53 INFO - TinderboxPrint: xpcshell-xpcshell<br/>19/<em class="testfail">1</em>/0
[task 2021-04-01T15:01:53.014Z] 15:01:53 WARNING - # TBPL FAILURE #
[task 2021-04-01T15:01:53.014Z] 15:01:53 WARNING - setting return code to 2
[task 2021-04-01T15:01:53.014Z] 15:01:53 WARNING - TinderboxPrint: Per-test run of ...\test_defaultEngine_fallback.js<br/>: FAILURE
[task 2021-04-01T15:01:53.014Z] 15:01:53 INFO - Running command: ['Z:\\task_1617288083\\build\\venv\\Scripts\\python', '-u', 'Z:\\task_1617288083\\build\\tests\\xpcshell\\runxpcshelltests.py', '--setpref=media.peerconnection.mtransport_process=false', '--setpref=network.process.enabled=false', '--self-test', u'--symbols-path=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Rkq_79HzTcG1WCOixBnjuA/artifacts/public/build/target.crashreporter-symbols.zip', '--test-plugin-path=Z:\\task_1617288083\\build\\application\\firefox\\plugins', '--log-raw=Z:\\task_1617288083\\build\\blobber_upload_dir\\xpcshell-test5_raw.log', '--log-errorsummary=Z:\\task_1617288083\\build\\blobber_upload_dir\\xpcshell-test5_errorsummary.log', '--utility-path=tests/bin', '--xpcshell=Z:\\task_1617288083\\build\\application\\firefox/xpcshell.exe', '--manifest=tests/xpcshell/tests/xpcshell.ini', '--verify-max-time=900', '--verify', u'toolkit/components/search/tests/xpcshell/test_searchSuggest_cookies.js'] in Z:\task_1617288083\build
[task 2021-04-01T15:01:53.015Z] 15:01:53 INFO - Copy/paste: Z:\task_1617288083\build\venv\Scripts\python -u Z:\task_1617288083\build\tests\xpcshell\runxpcshelltests.py --setpref=media.peerconnection.mtransport_process=false --setpref=network.process.enabled=false --self-test --symbols-path=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Rkq_79HzTcG1WCOixBnjuA/artifacts/public/build/target.crashreporter-symbols.zip --test-plugin-path=Z:\task_1617288083\build\application\firefox\plugins --log-raw=Z:\task_1617288083\build\blobber_upload_dir\xpcshell-test5_raw.log --log-errorsummary=Z:\task_1617288083\build\blobber_upload_dir\xpcshell-test5_errorsummary.log --utility-path=tests/bin --xpcshell=Z:\task_1617288083\build\application\firefox/xpcshell.exe --manifest=tests/xpcshell/tests/xpcshell.ini --verify-max-time=900 --verify toolkit/components/search/tests/xpcshell/test_searchSuggest_cookies.js
[task 2021-04-01T15:01:53.015Z] 15:01:53 INFO - Using env: (same as previous command)
[task 2021-04-01T15:01:53.015Z] 15:01:53 INFO - Calling ['Z:\\task_1617288083\\build\\venv\\Scripts\\python', '-u', 'Z:\\task_1617288083\\build\\tests\\xpcshell\\runxpcshelltests.py', '--setpref=media.peerconnection.mtransport_process=false', '--setpref=network.process.enabled=false', '--self-test', u'--symbols-path=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Rkq_79HzTcG1WCOixBnjuA/artifacts/public/build/target.crashreporter-symbols.zip', '--test-plugin-path=Z:\\task_1617288083\\build\\application\\firefox\\plugins', '--log-raw=Z:\\task_1617288083\\build\\blobber_upload_dir\\xpcshell-test5_raw.log', '--log-errorsummary=Z:\\task_1617288083\\build\\blobber_upload_dir\\xpcshell-test5_errorsummary.log', '--utility-path=tests/bin', '--xpcshell=Z:\\task_1617288083\\build\\application\\firefox/xpcshell.exe', '--manifest=tests/xpcshell/tests/xpcshell.ini', '--verify-max-time=900', '--verify', u'toolkit/components/search/tests/xpcshell/test_searchSuggest_cookies.js'] with output_timeout 1000```
Comment 1•4 years ago
|
||
This failure has started appearing on tier 1 jobs:
https://treeherder.mozilla.org/logviewer?job_id=335374876&repo=mozilla-central&lineNumber=6363
Updated•4 years ago
|
Updated•4 years ago
|
Comment hidden (Intermittent Failures Robot) |
Comment 3•4 years ago
|
||
Set release status flags based on info from the regressing bug 1701950
Comment 4•4 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Description
•