Closed Bug 1770825 Opened 3 years ago Closed 3 months ago

Intermittent TV-nofis TEST-UNEXPECTED-TIMEOUT | services/settings/test/unit/test_remote_settings.js | Test timed out

Categories

(Firefox :: Remote Settings Client, defect, P5)

defect

Tracking

()

RESOLVED WORKSFORME
Tracking Status
firefox-esr91 --- unaffected
firefox100 --- unaffected
firefox101 --- unaffected
firefox102 --- wontfix
firefox103 --- wontfix

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure, regression)

Filed by: imoraru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=378995950&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/exzJOSqgSJCgOJDCr5gkJQ/runs/1/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/exzJOSqgSJCgOJDCr5gkJQ/runs/1/artifacts/public/logs/live_backing.log&only_show_unexpected=1


[task 2022-05-23T18:00:33.828Z] 18:00:33     INFO -  TEST-START | services/settings/test/unit/test_remote_settings.js
[task 2022-05-23T18:00:33.912Z] 18:00:33     INFO -  adb launch_application: am startservice -W -n 'org.mozilla.geckoview.test_runner/org.mozilla.geckoview.test_runner.XpcshellTestRunnerService$i0' -a android.intent.action.MAIN --es env0 XPCOM_DEBUG_BREAK=stack-and-abort --es env1 MOZ_CRASHREPORTER=1 --es env2 MOZ_CRASHREPORTER_NO_REPORT=1 --es env3 MOZ_DISABLE_NONLOCAL_CONNECTIONS=1 --es env4 MOZ_DEVELOPER_REPO_DIR=/builds/worker/checkouts/gecko --es env5 MOZ_DISABLE_CONTENT_SANDBOX=1 --es env6 MOZ_FETCHES_DIR=/builds/worker/fetches --es env7 MOZ_DISABLE_SOCKET_PROCESS=1 --es env8 LD_LIBRARY_PATH=/data/local/tmp/test_root/xpcb --es env9 MOZ_LINKER_CACHE=/data/local/tmp/test_root/xpcb --es env10 GRE_HOME=/data/local/tmp/test_root/xpcb --es env11 XPCSHELL_TEST_PROFILE_DIR=/data/local/tmp/test_root/xpc/p/a9af4018-a693-476d-825f-cf853fb8e043 --es env12 HOME=/data/local/tmp/test_root/xpc/p --es env13 XPCSHELL_TEST_TEMP_DIR=/data/local/tmp/test_root/xpc/tmp/670cea5c-89b1-47ad-8508-f457ddc08931 --es env14 MOZ_ANDROID_DATA_DIR=/data/local/tmp/test_root/xpcb --es env15 MOZ_IN_AUTOMATION=1 --es env16 MOZ_ANDROID_CPU_ABI=x86_64 --es env17 MOZHTTP2_PORT=43249 --es env18 MOZNODE_EXEC_PORT=42615 --es env19 TMPDIR=/data/local/tmp/test_root/xpc/p/a9af4018-a693-476d-825f-cf853fb8e043 --es env20 XPCSHELL_MINIDUMP_DIR=/data/local/tmp/test_root/xpc/minidumps/a9af4018-a693-476d-825f-cf853fb8e043 --es arg0 -g --es arg1 /data/local/tmp/test_root/xpcb --es arg2 --greomni --es arg3 /data/local/tmp/test_root/xpcb/geckoview-test_runner.apk --es arg4 -m --es arg5 -e --es arg6 'const _HEAD_JS_PATH = "/data/local/tmp/test_root/xpc/head.js";' --es arg7 -e --es arg8 'const _MOZINFO_JS_PATH = "/data/local/tmp/test_root/xpc/p/a9af4018-a693-476d-825f-cf853fb8e043/mozinfo.json";' --es arg9 -e --es arg10 'const _PREFS_FILE = "/data/local/tmp/test_root/xpc/user.js";' --es arg11 -e --es arg12 'const _TESTING_MODULES_DIR = "/data/local/tmp/test_root/xpc/m";' --es arg13 -f --es arg14 /data/local/tmp/test_root/xpc/head.js --es arg15 -e --es arg16 'const _HEAD_FILES = ["/data/local/tmp/test_root/xpc/services/settings/test/unit/../../../common/tests/unit/head_global.js", "/data/local/tmp/test_root/xpc/services/settings/test/unit/../../../common/tests/unit/head_helpers.js"];' --es arg17 -e --es arg18 'const _JSDEBUGGER_PORT = 0;' --es arg19 -e --es arg20 'const _TEST_CWD = "/data/local/tmp/test_root/xpc/services/settings/test/unit";' --es arg21 -e --es arg22 'const _TEST_FILE = ["test_remote_settings.js"];' --es arg23 -e --es arg24 'const _TEST_NAME = "services/settings/test/unit/test_remote_settings.js";' --es arg25 -e --es arg26 '_execute_test(); quit(0);' --ez use_multiprocess True --es out_file /data/local/tmp/test_root/xpc/logs/xpcshell-9d5c6a74-0ae9-4946-9f4a-9f5d127c27ef.log
[task 2022-05-23T18:00:34.201Z] 18:00:34     INFO -  remotexpcshelltests.py | services/settings/test/unit/test_remote_settings.js | 25598 | Launched Test App
[task 2022-05-23T18:00:35.749Z] 18:00:35     INFO -  remotexpcshelltests.py | services/settings/test/unit/test_remote_settings.js | 25598 | Application ran for: 0:00:01.919973
[task 2022-05-23T18:00:35.855Z] 18:00:35     INFO -  TEST-PASS | services/settings/test/unit/test_remote_settings.js | took 2027ms
[task 2022-05-23T18:00:35.967Z] 18:00:35     INFO -  Cleaning up profile for /builds/worker/workspace/build/tests/xpcshell/tests/services/settings/test/unit/test_remote_settings.js folder: /data/local/tmp/test_root/xpc/p/a9af4018-a693-476d-825f-cf853fb8e043
[task 2022-05-23T18:00:36.288Z] 18:00:36     INFO -  INFO | Result summary:
[task 2022-05-23T18:00:36.288Z] 18:00:36     INFO -  INFO | Passed: 10
[task 2022-05-23T18:00:36.288Z] 18:00:36     INFO -  INFO | Failed: 0
[task 2022-05-23T18:00:36.288Z] 18:00:36     INFO -  INFO | Todo: 0
[task 2022-05-23T18:00:36.288Z] 18:00:36     INFO -  INFO | Retried: 0
[task 2022-05-23T18:00:36.289Z] 18:00:36     INFO -  SUITE-END | took 32s
[task 2022-05-23T18:00:36.304Z] 18:00:36     INFO -  :::
[task 2022-05-23T18:00:36.305Z] 18:00:36     INFO -  ::: Running test verification step "2. Run each test 10 times, sequentially, in chaos mode."...
[task 2022-05-23T18:00:36.305Z] 18:00:36     INFO -  :::
[task 2022-05-23T18:00:36.306Z] 18:00:36     INFO -  Using at most 4 threads.
[task 2022-05-23T18:00:36.306Z] 18:00:36     INFO -  SUITE-START | Running 1 tests
[task 2022-05-23T18:00:36.869Z] 18:00:36     INFO -  TEST-START | services/settings/test/unit/test_remote_settings.js
[task 2022-05-23T18:00:36.952Z] 18:00:36     INFO -  adb launch_application: am startservice -W -n 'org.mozilla.geckoview.test_runner/org.mozilla.geckoview.test_runner.XpcshellTestRunnerService$i0' -a android.intent.action.MAIN --es env0 XPCOM_DEBUG_BREAK=stack-and-abort --es env1 MOZ_CRASHREPORTER=1 --es env2 MOZ_CRASHREPORTER_NO_REPORT=1 --es env3 MOZ_DISABLE_NONLOCAL_CONNECTIONS=1 --es env4 MOZ_DEVELOPER_REPO_DIR=/builds/worker/checkouts/gecko --es env5 MOZ_DISABLE_CONTENT_SANDBOX=1 --es env6 MOZ_FETCHES_DIR=/builds/worker/fetches --es env7 MOZ_DISABLE_SOCKET_PROCESS=1 --es env8 LD_LIBRARY_PATH=/data/local/tmp/test_root/xpcb --es env9 MOZ_LINKER_CACHE=/data/local/tmp/test_root/xpcb --es env10 GRE_HOME=/data/local/tmp/test_root/xpcb --es env11 XPCSHELL_TEST_PROFILE_DIR=/data/local/tmp/test_root/xpc/p/68714923-501c-4f90-a1be-395bdfcaf849 --es env12 HOME=/data/local/tmp/test_root/xpc/p --es env13 XPCSHELL_TEST_TEMP_DIR=/data/local/tmp/test_root/xpc/tmp/4e5feaea-dc23-4c75-a7b5-d0f13d4c01e6 --es env14 MOZ_ANDROID_DATA_DIR=/data/local/tmp/test_root/xpcb --es env15 MOZ_IN_AUTOMATION=1 --es env16 MOZ_ANDROID_CPU_ABI=x86_64 --es env17 MOZHTTP2_PORT=43249 --es env18 MOZNODE_EXEC_PORT=42615 --es env19 MOZ_CHAOSMODE=0xfb --es env20 TMPDIR=/data/local/tmp/test_root/xpc/p/68714923-501c-4f90-a1be-395bdfcaf849 --es env21 XPCSHELL_MINIDUMP_DIR=/data/local/tmp/test_root/xpc/minidumps/68714923-501c-4f90-a1be-395bdfcaf849 --es arg0 -g --es arg1 /data/local/tmp/test_root/xpcb --es arg2 --greomni --es arg3 /data/local/tmp/test_root/xpcb/geckoview-test_runner.apk --es arg4 -m --es arg5 -e --es arg6 'const _HEAD_JS_PATH = "/data/local/tmp/test_root/xpc/head.js";' --es arg7 -e --es arg8 'const _MOZINFO_JS_PATH = "/data/local/tmp/test_root/xpc/p/68714923-501c-4f90-a1be-395bdfcaf849/mozinfo.json";' --es arg9 -e --es arg10 'const _PREFS_FILE = "/data/local/tmp/test_root/xpc/user.js";' --es arg11 -e --es arg12 'const _TESTING_MODULES_DIR = "/data/local/tmp/test_root/xpc/m";' --es arg13 -f --es arg14 /data/local/tmp/test_root/xpc/head.js --es arg15 -e --es arg16 'const _HEAD_FILES = ["/data/local/tmp/test_root/xpc/services/settings/test/unit/../../../common/tests/unit/head_global.js", "/data/local/tmp/test_root/xpc/services/settings/test/unit/../../../common/tests/unit/head_helpers.js"];' --es arg17 -e --es arg18 'const _JSDEBUGGER_PORT = 0;' --es arg19 -e --es arg20 'const _TEST_CWD = "/data/local/tmp/test_root/xpc/services/settings/test/unit";' --es arg21 -e --es arg22 'const _TEST_FILE = ["test_remote_settings.js"];' --es arg23 -e --es arg24 'const _TEST_NAME = "services/settings/test/unit/test_remote_settings.js";' --es arg25 -e --es arg26 '_execute_test(); quit(0);' --ez use_multiprocess True --es out_file /data/local/tmp/test_root/xpc/logs/xpcshell-3ac277f1-23c1-4c43-9f93-bec596ba3ff4.log
[task 2022-05-23T18:00:37.247Z] 18:00:37     INFO -  remotexpcshelltests.py | services/settings/test/unit/test_remote_settings.js | 25851 | Launched Test App
[task 2022-05-23T18:05:36.869Z] 18:05:36  WARNING -  TEST-UNEXPECTED-TIMEOUT | services/settings/test/unit/test_remote_settings.js | Test timed out
[task 2022-05-23T18:05:36.870Z] 18:05:36     INFO -  TEST-INFO took 300000ms
[task 2022-05-23T18:05:36.894Z] 18:05:36     INFO -  xpcshell return code: -1
[task 2022-05-23T18:05:36.894Z] 18:05:36     INFO -  Cleaning up profile for /builds/worker/workspace/build/tests/xpcshell/tests/services/settings/test/unit/test_remote_settings.js folder: /data/local/tmp/test_root/xpc/p/68714923-501c-4f90-a1be-395bdfcaf849
[task 2022-05-23T18:08:01.321Z] 18:08:01     INFO -  remotexpcshelltests.py | services/settings/test/unit/test_remote_settings.js | 0 | Timing out
[task 2022-05-23T18:08:01.346Z] 18:08:01     INFO -  remotexpcshelltests.py | services/settings/test/unit/test_remote_settings.js | 25851 | Application ran for: 0:07:24.477138
[task 2022-05-23T18:08:01.479Z] 18:08:01     INFO -  Cleaning up profile for /builds/worker/workspace/build/tests/xpcshell/tests/services/settings/test/unit/test_remote_settings.js folder: /data/local/tmp/test_root/xpc/p/68714923-501c-4f90-a1be-395bdfcaf849
[task 2022-05-23T18:08:01.625Z] 18:08:01  WARNING -  TEST-UNEXPECTED-FAIL | Received SIGINT (control-C), so stopped run. (Use --keep-going to keep running tests after killing one with SIGINT)
[task 2022-05-23T18:08:01.625Z] 18:08:01     INFO -  INFO | Result summary:
[task 2022-05-23T18:08:01.625Z] 18:08:01     INFO -  INFO | Passed: 10
[task 2022-05-23T18:08:01.625Z] 18:08:01  WARNING -  INFO | Failed: 1
[task 2022-05-23T18:08:01.625Z] 18:08:01  WARNING -  One or more unittests failed.
[task 2022-05-23T18:08:01.625Z] 18:08:01     INFO -  INFO | Todo: 0
[task 2022-05-23T18:08:01.626Z] 18:08:01     INFO -  INFO | Retried: 0
[task 2022-05-23T18:08:01.626Z] 18:08:01     INFO -  SUITE-END | took 445s
[task 2022-05-23T18:08:01.627Z] 18:08:01     INFO -  :::
[task 2022-05-23T18:08:01.628Z] 18:08:01     INFO -  ::: Test verification summary for: /builds/worker/workspace/build/tests/xpcshell/tests/services/settings/test/unit/test_remote_settings.js
[task 2022-05-23T18:08:01.628Z] 18:08:01     INFO -  :::
[task 2022-05-23T18:08:01.628Z] 18:08:01     INFO -  ::: 1. Run each test 10 times, sequentially. : Pass
[task 2022-05-23T18:08:01.628Z] 18:08:01     INFO -  ::: 2. Run each test 10 times, sequentially, in chaos mode. : FAIL
[task 2022-05-23T18:08:01.628Z] 18:08:01     INFO -  :::
[task 2022-05-23T18:08:01.628Z] 18:08:01     INFO -  ::: Test verification FAILED!
[task 2022-05-23T18:08:01.628Z] 18:08:01     INFO -  :::
[task 2022-05-23T18:08:01.628Z] 18:08:01     INFO -  Node moz-http2 server shutting down ...
[task 2022-05-23T18:08:01.682Z] 18:08:01    ERROR - Return code: 1
[task 2022-05-23T18:08:01.682Z] 18:08:01     INFO - TinderboxPrint: xpcshell<br/>10/<em class="testfail">1</em>/0
[task 2022-05-23T18:08:01.682Z] 18:08:01     INFO - ##### xpcshell log ends
[task 2022-05-23T18:08:01.682Z] 18:08:01  WARNING - # TBPL WARNING #
[task 2022-05-23T18:08:01.682Z] 18:08:01  WARNING - setting return code to 1

:leplatrem, since you are the author of the regressor, bug 1702759, could you take a look?
For more information, please visit auto_nag documentation.

Flags: needinfo?(mathieu)
Has Regression Range: --- → yes

Hasn't happened for 6 months

Status: NEW → RESOLVED
Closed: 3 months ago
Flags: needinfo?(mathieu)
Resolution: --- → FIXED
Resolution: FIXED → WORKSFORME
You need to log in before you can comment on or make changes to this bug.