Closed Bug 1818419 Opened 2 years ago Closed 1 year ago

Intermittent netwerk/test/unit/test_dooh.js | single tracking bug

Categories

(Core :: Networking: DNS, defect, P2)

defect

Tracking

()

RESOLVED FIXED
121 Branch
Tracking Status
firefox-esr102 --- unaffected
firefox-esr115 --- fixed
firefox110 --- unaffected
firefox111 --- unaffected
firefox112 --- wontfix
firefox113 --- wontfix
firefox119 --- wontfix
firefox120 --- fixed
firefox121 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: valentin)

References

(Regression)

Details

(Keywords: intermittent-failure, intermittent-testcase, regression, Whiteboard: [stockwell unknown][necko-triaged])

Attachments

(1 file)

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


[task 2023-02-23T03:51:45.566Z] 03:51:45     INFO -  TEST-START | netwerk/test/unit/test_dooh.js
[task 2023-02-23T03:51:45.601Z] 03:51:45     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/1ba51c5c-db5e-4a08-86a5-3105754e90b9 --es env12 HOME=/data/local/tmp/test_root/xpc/p --es env13 XPCSHELL_TEST_TEMP_DIR=/data/local/tmp/test_root/xpc/tmp/d3726434-a8ef-400b-b029-a8b1c8497794 --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=35204 --es env18 MOZNODE_EXEC_PORT=32991 --es env19 MOZ_CHAOSMODE=0xfb --es env20 TMPDIR=/data/local/tmp/test_root/xpc/p/1ba51c5c-db5e-4a08-86a5-3105754e90b9 --es env21 XPCSHELL_MINIDUMP_DIR=/data/local/tmp/test_root/xpc/minidumps/1ba51c5c-db5e-4a08-86a5-3105754e90b9 --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/1ba51c5c-db5e-4a08-86a5-3105754e90b9/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/netwerk/test/unit/head_channels.js", "/data/local/tmp/test_root/xpc/netwerk/test/unit/head_cache.js", "/data/local/tmp/test_root/xpc/netwerk/test/unit/head_cookies.js", "/data/local/tmp/test_root/xpc/netwerk/test/unit/head_trr.js", "/data/local/tmp/test_root/xpc/netwerk/test/unit/head_http3.js", "/data/local/tmp/test_root/xpc/netwerk/test/unit/trr_common.js"];' --es arg17 -e --es arg18 'const _JSDEBUGGER_PORT = 0;' --es arg19 -e --es arg20 'const _TEST_CWD = "/data/local/tmp/test_root/xpc/netwerk/test/unit";' --es arg21 -e --es arg22 'const _TEST_FILE = ["test_dooh.js"];' --es arg23 -e --es arg24 'const _TEST_NAME = "netwerk/test/unit/test_dooh.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-fc35cf75-1482-4a6e-a0da-3a02aec134e9.log
[task 2023-02-23T03:51:45.804Z] 03:51:45     INFO -  remotexpcshelltests.py | netwerk/test/unit/test_dooh.js | 14282 | Launched Test App
[task 2023-02-23T03:52:03.222Z] 03:52:03     INFO -  remotexpcshelltests.py | netwerk/test/unit/test_dooh.js | 14282 | Application ran for: 0:00:17.655609
[task 2023-02-23T03:52:03.273Z] 03:52:03  WARNING -  TEST-UNEXPECTED-FAIL | netwerk/test/unit/test_dooh.js | xpcshell return code: 0
[task 2023-02-23T03:52:03.273Z] 03:52:03     INFO -  TEST-INFO took 17707ms
[task 2023-02-23T03:52:03.273Z] 03:52:03     INFO -  >>>>>>>
[task 2023-02-23T03:52:03.273Z] 03:52:03     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2023-02-23T03:52:03.273Z] 03:52:03     INFO -  (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2023-02-23T03:52:03.273Z] 03:52:03     INFO -  (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2023-02-23T03:52:03.273Z] 03:52:03     INFO -  running event loop
[task 2023-02-23T03:52:03.274Z] 03:52:03     INFO -  netwerk/test/unit/test_dooh.js | Starting setup setup
[task 2023-02-23T03:52:03.274Z] 03:52:03     INFO -  (xpcshell/head.js) | test setup pending (2)
[task 2023-02-23T03:52:03.274Z] 03:52:03     INFO -  netwerk/test/unit/test_dooh.js | start!
[task 2023-02-23T03:52:03.274Z] 03:52:03     INFO -  TEST-PASS | netwerk/test/unit/test_dooh.js | setup - [setup : 91] "35204" != null
[task 2023-02-23T03:52:03.274Z] 03:52:03     INFO -  TEST-PASS | netwerk/test/unit/test_dooh.js | setup - [setup : 91] "35204" != ""
[task 2023-02-23T03:52:03.274Z] 03:52:03     INFO -  (xpcshell/head.js) | test run_next_test 0 finished (2)
[task 2023-02-23T03:52:03.274Z] 03:52:03     INFO -  (xpcshell/head.js) | test run_next_test 1 pending (2)
[task 2023-02-23T03:52:03.274Z] 03:52:03     INFO -  (xpcshell/head.js) | test setup finished (2)
[task 2023-02-23T03:52:03.274Z] 03:52:03     INFO -  netwerk/test/unit/test_dooh.js | Starting test_ohttp_not_configured
[task 2023-02-23T03:52:03.274Z] 03:52:03     INFO -  (xpcshell/head.js) | test test_ohttp_not_configured pending (2)
[task 2023-02-23T03:52:03.274Z] 03:52:03     INFO -  TEST-PASS | netwerk/test/unit/test_dooh.js | test_ohttp_not_configured - [test_ohttp_not_configured : 137] asyncResolve ok - true == true
[task 2023-02-23T03:52:03.274Z] 03:52:03     INFO -  (xpcshell/head.js) | test run_next_test 1 finished (2)
[task 2023-02-23T03:52:03.274Z] 03:52:03     INFO -  TEST-PASS | netwerk/test/unit/test_dooh.js | test_ohttp_not_configured - [test_ohttp_not_configured : 1] Checking that this is the correct callback - true == true
[task 2023-02-23T03:52:03.275Z] 03:52:03     INFO -  TEST-PASS | netwerk/test/unit/test_dooh.js | test_ohttp_not_configured - [test_ohttp_not_configured : 1] Checking status - 0 == 0
[task 2023-02-23T03:52:03.275Z] 03:52:03     INFO -  TEST-PASS | netwerk/test/unit/test_dooh.js | test_ohttp_not_configured - [test_ohttp_not_configured : 1] Checking result for example.com - "127.0.0.1" == "127.0.0.1"
[task 2023-02-23T03:52:03.275Z] 03:52:03     INFO -  (xpcshell/head.js) | test run_next_test 2 pending (2)
[task 2023-02-23T03:52:03.275Z] 03:52:03     INFO -  (xpcshell/head.js) | test test_ohttp_not_configured finished (2)
[task 2023-02-23T03:52:03.275Z] 03:52:03     INFO -  netwerk/test/unit/test_dooh.js | Starting set_ohttp_invalid_prefs
[task 2023-02-23T03:52:03.275Z] 03:52:03     INFO -  (xpcshell/head.js) | test set_ohttp_invalid_prefs pending (2)
[task 2023-02-23T03:52:03.275Z] 03:52:03     INFO -  (xpcshell/head.js) | test run_next_test 2 finished (2)
[task 2023-02-23T03:52:03.275Z] 03:52:03     INFO -  (xpcshell/head.js) | test run_next_test 3 pending (2)
[task 2023-02-23T03:52:03.275Z] 03:52:03     INFO -  (xpcshell/head.js) | test set_ohttp_invalid_prefs finished (2)
[task 2023-02-23T03:52:03.276Z] 03:52:03     INFO -  netwerk/test/unit/test_dooh.js | Starting test_ohttp_invalid_prefs_fallback
[task 2023-02-23T03:52:03.276Z] 03:52:03     INFO -  (xpcshell/head.js) | test test_ohttp_invalid_prefs_fallback pending (2)
[task 2023-02-23T03:52:03.276Z] 03:52:03     INFO -  TEST-PASS | netwerk/test/unit/test_dooh.js | test_ohttp_invalid_prefs_fallback - [test_ohttp_invalid_prefs_fallback : 161] asyncResolve ok - true == true
[task 2023-02-23T03:52:03.276Z] 03:52:03     INFO -  (xpcshell/head.js) | test run_next_test 3 finished (2)
[task 2023-02-23T03:52:03.276Z] 03:52:03     INFO -  TEST-PASS | netwerk/test/unit/test_dooh.js | test_ohttp_invalid_prefs_fallback - [test_ohttp_invalid_prefs_fallback : 1] Checking that this is the correct callback - true == true
[task 2023-02-23T03:52:03.276Z] 03:52:03     INFO -  TEST-PASS | netwerk/test/unit/test_dooh.js | test_ohttp_invalid_prefs_fallback - [test_ohttp_invalid_prefs_fallback : 1] Checking status - 0 == 0
[task 2023-02-23T03:52:03.276Z] 03:52:03     INFO -  TEST-PASS | netwerk/test/unit/test_dooh.js | test_ohttp_invalid_prefs_fallback - [test_ohttp_invalid_prefs_fallback : 1] Checking result for example.com - "127.0.0.1" == "127.0.0.1"
[task 2023-02-23T03:52:03.277Z] 03:52:03     INFO -  (xpcshell/head.js) | test run_next_test 4 pending (2)
[task 2023-02-23T03:52:03.277Z] 03:52:03     INFO -  (xpcshell/head.js) | test test_ohttp_invalid_prefs_fallback finished (2)
[task 2023-02-23T03:52:03.277Z] 03:52:03     INFO -  netwerk/test/unit/test_dooh.js | Starting set_ohttp_prefs_500_error
[task 2023-02-23T03:52:03.277Z] 03:52:03     INFO -  (xpcshell/head.js) | test set_ohttp_prefs_500_error pending (2)
[task 2023-02-23T03:52:03.277Z] 03:52:03     INFO -  (xpcshell/head.js) | test run_next_test 4 finished (2)
[task 2023-02-23T03:52:03.277Z] 03:52:03     INFO -  (xpcshell/head.js) | test run_next_test 5 pending (2)
[task 2023-02-23T03:52:03.277Z] 03:52:03     INFO -  (xpcshell/head.js) | test set_ohttp_prefs_500_error finished (2)
[task 2023-02-23T03:52:03.277Z] 03:52:03     INFO -  netwerk/test/unit/test_dooh.js | Starting test_ohttp_500_error_fallback
[task 2023-02-23T03:52:03.277Z] 03:52:03     INFO -  (xpcshell/head.js) | test test_ohttp_500_error_fallback pending (2)
[task 2023-02-23T03:52:03.278Z] 03:52:03     INFO -  TEST-PASS | netwerk/test/unit/test_dooh.js | test_ohttp_500_error_fallback - [test_ohttp_500_error_fallback : 181] asyncResolve ok - true == true
[task 2023-02-23T03:52:03.278Z] 03:52:03     INFO -  (xpcshell/head.js) | test run_next_test 5 finished (2)
[task 2023-02-23T03:52:03.278Z] 03:52:03     INFO -  TEST-PASS | netwerk/test/unit/test_dooh.js | test_ohttp_500_error_fallback - [test_ohttp_500_error_fallback : 1] Checking that this is the correct callback - true == true
[task 2023-02-23T03:52:03.278Z] 03:52:03     INFO -  TEST-PASS | netwerk/test/unit/test_dooh.js | test_ohttp_500_error_fallback - [test_ohttp_500_error_fallback : 1] Checking status - 0 == 0
[task 2023-02-23T03:52:03.278Z] 03:52:03     INFO -  TEST-PASS | netwerk/test/unit/test_dooh.js | test_ohttp_500_error_fallback - [test_ohttp_500_error_fallback : 1] Checking result for example.com - "127.0.0.1" == "127.0.0.1"
[task 2023-02-23T03:52:03.278Z] 03:52:03     INFO -  (xpcshell/head.js) | test run_next_test 6 pending (2)
[task 2023-02-23T03:52:03.278Z] 03:52:03     INFO -  (xpcshell/head.js) | test test_ohttp_500_error_fallback finished (2)
[task 2023-02-23T03:52:03.278Z] 03:52:03     INFO -  netwerk/test/unit/test_dooh.js | Starting set_ohttp_prefs_valid
[task 2023-02-23T03:52:03.278Z] 03:52:03     INFO -  (xpcshell/head.js) | test set_ohttp_prefs_valid pending (2)
[task 2023-02-23T03:52:03.278Z] 03:52:03     INFO -  (xpcshell/head.js) | test run_next_test 6 finished (2)
[task 2023-02-23T03:52:03.278Z] 03:52:03     INFO -  (xpcshell/head.js) | test run_next_test 7 pending (2)
[task 2023-02-23T03:52:03.278Z] 03:52:03     INFO -  (xpcshell/head.js) | test set_ohttp_prefs_valid finished (2)
[task 2023-02-23T03:52:03.279Z] 03:52:03     INFO -  netwerk/test/unit/test_dooh.js | Starting test_A_record
[task 2023-02-23T03:52:03.279Z] 03:52:03     INFO -  (xpcshell/head.js) | test test_A_record pending (2)
[task 2023-02-23T03:52:03.279Z] 03:52:03     INFO -  "Verifying a basic A record"
[task 2023-02-23T03:52:03.279Z] 03:52:03     INFO -  TEST-PASS | netwerk/test/unit/test_dooh.js | test_A_record - [test_A_record : 76] asyncResolve ok - true == true
[task 2023-02-23T03:52:03.279Z] 03:52:03     INFO -  (xpcshell/head.js) | test run_next_test 7 finished (2)
[task 2023-02-23T03:52:03.279Z] 03:52:03     INFO -  TEST-PASS | netwerk/test/unit/test_dooh.js | test_A_record - [test_A_record : 1] Checking that this is the correct callback - true == true
[task 2023-02-23T03:52:03.279Z] 03:52:03     INFO -  TEST-PASS | netwerk/test/unit/test_dooh.js | test_A_record - [test_A_record : 1] Checking status - 0 == 0
[task 2023-02-23T03:52:03.279Z] 03:52:03  WARNING -  TEST-UNEXPECTED-FAIL | netwerk/test/unit/test_dooh.js | test_A_record - [test_A_record : 1] Checking result for bar.example.com - "127.0.0.1" == "2.2.2.2"
[task 2023-02-23T03:52:03.279Z] 03:52:03     INFO -  -e:null:1
[task 2023-02-23T03:52:03.279Z] 03:52:03     INFO -  exiting test
[task 2023-02-23T03:52:03.279Z] 03:52:03     INFO -  "CONSOLE_MESSAGE: (error) [JavaScript Error: "NS_ERROR_ABORT: " {file: "/data/local/tmp/test_root/xpc/head.js" line: 863}]
[task 2023-02-23T03:52:03.279Z] 03:52:03     INFO -  _abort_failed_test@/data/local/tmp/test_root/xpc/head.js:863:20
[task 2023-02-23T03:52:03.279Z] 03:52:03     INFO -  do_report_result@/data/local/tmp/test_root/xpc/head.js:964:5
[task 2023-02-23T03:52:03.280Z] 03:52:03     INFO -  Assert<@/data/local/tmp/test_root/xpc/head.js:71:21
[task 2023-02-23T03:52:03.280Z] 03:52:03     INFO -  Assert.prototype.report@resource://testing-common/Assert.sys.mjs:240:10
[task 2023-02-23T03:52:03.280Z] 03:52:03     INFO -  equal@resource://testing-common/Assert.sys.mjs:282:8
[task 2023-02-23T03:52:03.280Z] 03:52:03     INFO -  onLookupComplete@/data/local/tmp/test_root/xpc/netwerk/test/unit/head_trr.js:184:12
[task 2023-02-23T03:52:03.280Z] 03:52:03     INFO -  _do_main@/data/local/tmp/test_root/xpc/head.js:238:6
[task 2023-02-23T03:52:03.280Z] 03:52:03     INFO -  _execute_test@/data/local/tmp/test_root/xpc/head.js:585:5
[task 2023-02-23T03:52:03.280Z] 03:52:03     INFO -  @-e:1:1
[task 2023-02-23T03:52:03.280Z] 03:52:03     INFO -  "
[task 2023-02-23T03:52:03.280Z] 03:52:03     INFO -  "Force a GC"
[task 2023-02-23T03:52:03.280Z] 03:52:03     INFO -  <<<<<<<
[task 2023-02-23T03:52:03.336Z] 03:52:03     INFO -  Cleaning up profile for /builds/worker/workspace/build/tests/xpcshell/tests/netwerk/test/unit/test_dooh.js folder: /data/local/tmp/test_root/xpc/p/1ba51c5c-db5e-4a08-86a5-3105754e90b9
[task 2023-02-23T03:52:03.773Z] 03:52:03     INFO -  TEST-START | netwerk/test/unit/test_dooh.js

:keeler, since you are the author of the regressor, bug 1815741, could you take a look?

For more information, please visit auto_nag documentation.

Flags: needinfo?(dkeeler)

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

Summary: Intermittent TV-nofis netwerk/test/unit/test_dooh.js | test_A_record - [test_A_record : 1] Checking result for bar.example.com - "127.0.0.1" == "2.2.2.2" → Intermittent TV-nofis netwerk/test/unit/test_dooh.js | single tracking bug
Summary: Intermittent TV-nofis netwerk/test/unit/test_dooh.js | single tracking bug → Intermittent netwerk/test/unit/test_dooh.js | single tracking bug

My guess is this has the same root cause as bug 1818475, in which case this is a preexisting issue. In any case, this is low volume for now.

Flags: needinfo?(dkeeler)

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

My guess is this has the same root cause as bug 1818475, in which case this is a preexisting issue. In any case, this is low volume for now.

Hi Dana, not sure if this new volume warrants a closer look to verify the preexisting issue. Any thoughts?

Flags: needinfo?(dkeeler)

I still suspect it's the same root cause, but I'll keep an eye on it.

Flags: needinfo?(dkeeler)

The TTL refresh is done asynchronously with low priority on windows.
When clearing the cache between tests, this callback may still exist
so it may refresh the cache entry with the native value instead of the TRR one.

Assignee: nobody → valentin.gosu
Status: NEW → ASSIGNED
Priority: -- → P2
Whiteboard: [stockwell unknown] → [stockwell unknown][necko-triaged]
Pushed by valentin.gosu@gmail.com: https://hg.mozilla.org/integration/autoland/rev/6ba15e798a70 Intermittent netwerk/test/unit/test_dooh.js caused by TTL refresh on windows r=necko-reviewers,jesup
Status: ASSIGNED → RESOLVED
Closed: 1 year ago
Resolution: --- → FIXED
Target Milestone: --- → 121 Branch
See Also: → 1760121
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: