1867365 - Intermittent TV netwerk/test/unit/test_trr.js | single tracking bug

Status: RESOLVED DUPLICATE of bug 1866059

(Core :: Networking, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2023-11-29T18:10:05.327Z] 18:10:05     INFO -  TEST-START | netwerk/test/unit/test_trr.js
[task 2023-11-29T18:10:05.364Z] 18:10:05     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_DEVELOPER_OBJ_DIR=/builds/worker/workspace/obj-build --es env6 MOZ_DISABLE_CONTENT_SANDBOX=1 --es env7 MOZ_FETCHES_DIR=/builds/worker/fetches --es env8 MOZ_DISABLE_SOCKET_PROCESS=1 --es env9 LD_LIBRARY_PATH=/data/local/tmp/test_root/xpcb --es env10 MOZ_LINKER_CACHE=/data/local/tmp/test_root/xpcb --es env11 GRE_HOME=/data/local/tmp/test_root/xpcb --es env12 XPCSHELL_TEST_PROFILE_DIR=/data/local/tmp/test_root/xpc/p/8572bf33-0ef7-4662-97a8-b113d2990352 --es env13 HOME=/data/local/tmp/test_root/xpc/p --es env14 XPCSHELL_TEST_TEMP_DIR=/data/local/tmp/test_root/xpc/tmp/a47793a4-dc0b-45f4-aa68-abc3078cdb11 --es env15 MOZ_ANDROID_DATA_DIR=/data/local/tmp/test_root/xpcb --es env16 MOZ_IN_AUTOMATION=1 --es env17 MOZ_ANDROID_CPU_ABI=x86_64 --es env18 MOZHTTP2_PORT=37637 --es env19 MOZNODE_EXEC_PORT=40690 --es env20 MOZHTTP3_ECH= --es env21 MOZ_CHAOSMODE=0xfb --es env22 TMPDIR=/data/local/tmp/test_root/xpc/p/8572bf33-0ef7-4662-97a8-b113d2990352 --es env23 XPCSHELL_MINIDUMP_DIR=/data/local/tmp/test_root/xpc/minidumps/8572bf33-0ef7-4662-97a8-b113d2990352 --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/8572bf33-0ef7-4662-97a8-b113d2990352/mozinfo.json";' --es arg9 -e --es arg10 'const _PREFS_FILE = "/data/local/tmp/test_root/xpc/tmp/a47793a4-dc0b-45f4-aa68-abc3078cdb11/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_servers.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_trr.js"];' --es arg23 -e --es arg24 'const _TEST_NAME = "netwerk/test/unit/test_trr.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-856ba6c8-3c77-4b09-a8f5-1da6986e368f.log
[task 2023-11-29T18:10:05.568Z] 18:10:05     INFO -  remotexpcshelltests.py | netwerk/test/unit/test_trr.js | 10185 | Launched Test App
[task 2023-11-29T18:15:01.501Z] 18:15:01     INFO -  remotexpcshelltests.py | netwerk/test/unit/test_trr.js | 10185 | Application ran for: 0:04:56.173366
[task 2023-11-29T18:15:01.609Z] 18:15:01  WARNING -  TEST-UNEXPECTED-FAIL | netwerk/test/unit/test_trr.js | xpcshell return code: 0
[task 2023-11-29T18:15:01.609Z] 18:15:01     INFO -  TEST-INFO took 296281ms
[task 2023-11-29T18:15:01.609Z] 18:15:01     INFO -  >>>>>>>
[task 2023-11-29T18:15:01.609Z] 18:15:01     INFO -  netwerk/test/unit/test_trr.js | start!
[task 2023-11-29T18:15:01.609Z] 18:15:01     INFO -  TEST-PASS | netwerk/test/unit/test_trr.js | setup - [setup : 11] "37637" != null
[task 2023-11-29T18:15:01.609Z] 18:15:01     INFO -  TEST-PASS | netwerk/test/unit/test_trr.js | setup - [setup : 11] "37637" != ""
[task 2023-11-29T18:15:01.609Z] 18:15:01     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2023-11-29T18:15:01.609Z] 18:15:01     INFO -  (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2023-11-29T18:15:01.609Z] 18:15:01     INFO -  (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2023-11-29T18:15:01.609Z] 18:15:01     INFO -  running event loop
[task 2023-11-29T18:15:01.610Z] 18:15:01     INFO -  netwerk/test/unit/test_trr.js | Starting test_server_up
[task 2023-11-29T18:15:01.610Z] 18:15:01     INFO -  (xpcshell/head.js) | test test_server_up pending (2)
[task 2023-11-29T18:15:01.610Z] 18:15:01     INFO -  (xpcshell/head.js) | test run_next_test 0 finished (2)
[task 2023-11-29T18:15:01.610Z] 18:15:01     INFO -  TEST-PASS | netwerk/test/unit/test_trr.js | test_server_up - [test_server_up : 78] "Error: could not find id" == "Error: could not find id"
[task 2023-11-29T18:15:01.610Z] 18:15:01     INFO -  (xpcshell/head.js) | test run_next_test 1 pending (2)
[task 2023-11-29T18:15:01.610Z] 18:15:01     INFO -  (xpcshell/head.js) | test test_server_up finished (2)
[task 2023-11-29T18:15:01.610Z] 18:15:01     INFO -  netwerk/test/unit/test_trr.js | Starting test_trr_flags
[task 2023-11-29T18:15:01.610Z] 18:15:01     INFO -  (xpcshell/head.js) | test test_trr_flags pending (2)
[task 2023-11-29T18:15:01.610Z] 18:15:01     INFO -  (xpcshell/head.js) | test run_next_test 1 finished (2)
[task 2023-11-29T18:15:01.610Z] 18:15:01     INFO -  TEST-PASS | netwerk/test/unit/test_trr.js | test_trr_flags - [test_trr_flags : 1] 12 == 12
[task 2023-11-29T18:15:01.610Z] 18:15:01     INFO -  TEST-PASS | netwerk/test/unit/test_trr.js | test_trr_flags - [test_trr_flags : 120] 0 == 0
<...>
[task 2023-11-29T18:15:01.669Z] 18:15:01     INFO -  TEST-PASS | netwerk/test/unit/test_trr.js | test_builtin_excluded_domains_mode3 - [test_builtin_excluded_domains_mode3 : 1] Checking result for domain.other - "127.0.0.1" == "127.0.0.1"
[task 2023-11-29T18:15:01.669Z] 18:15:01     INFO -  (xpcshell/head.js) | test run_next_test 24 pending (2)
[task 2023-11-29T18:15:01.669Z] 18:15:01     INFO -  (xpcshell/head.js) | test test_builtin_excluded_domains_mode3 finished (2)
[task 2023-11-29T18:15:01.669Z] 18:15:01     INFO -  netwerk/test/unit/test_trr.js | Starting count_cookies
[task 2023-11-29T18:15:01.669Z] 18:15:01     INFO -  (xpcshell/head.js) | test count_cookies pending (2)
[task 2023-11-29T18:15:01.669Z] 18:15:01     INFO -  "Check that none of the requests have set any cookies."
[task 2023-11-29T18:15:01.669Z] 18:15:01     INFO -  TEST-PASS | netwerk/test/unit/test_trr.js | count_cookies - [count_cookies : 815] 0 == 0
[task 2023-11-29T18:15:01.669Z] 18:15:01     INFO -  TEST-PASS | netwerk/test/unit/test_trr.js | count_cookies - [count_cookies : 816] 0 == 0
[task 2023-11-29T18:15:01.669Z] 18:15:01     INFO -  (xpcshell/head.js) | test run_next_test 24 finished (2)
[task 2023-11-29T18:15:01.670Z] 18:15:01     INFO -  (xpcshell/head.js) | test run_next_test 25 pending (2)
[task 2023-11-29T18:15:01.670Z] 18:15:01     INFO -  (xpcshell/head.js) | test count_cookies finished (2)
[task 2023-11-29T18:15:01.670Z] 18:15:01     INFO -  netwerk/test/unit/test_trr.js | Starting test_connection_closed
[task 2023-11-29T18:15:01.670Z] 18:15:01     INFO -  (xpcshell/head.js) | test test_connection_closed pending (2)
[task 2023-11-29T18:15:01.670Z] 18:15:01     INFO -  "Check we handle it correctly when the connection is closed"
[task 2023-11-29T18:15:01.670Z] 18:15:01     INFO -  TEST-PASS | netwerk/test/unit/test_trr.js | test_connection_closed - [test_connection_closed : 830] asyncResolve ok - true == true
[task 2023-11-29T18:15:01.670Z] 18:15:01     INFO -  (xpcshell/head.js) | test run_next_test 25 finished (2)
[task 2023-11-29T18:15:01.670Z] 18:15:01     INFO -  TEST-PASS | netwerk/test/unit/test_trr.js | test_connection_closed - [test_connection_closed : 1] Checking that this is the correct callback - true == true
[task 2023-11-29T18:15:01.670Z] 18:15:01  WARNING -  TEST-UNEXPECTED-FAIL | netwerk/test/unit/test_trr.js | test_connection_closed - [test_connection_closed : 1] Checking status - 2152398878 == 0
[task 2023-11-29T18:15:01.670Z] 18:15:01     INFO -  -e:null:1
[task 2023-11-29T18:15:01.670Z] 18:15:01     INFO -  exiting test
[task 2023-11-29T18:15:01.671Z] 18:15:01     INFO -  "CONSOLE_MESSAGE: (error) [JavaScript Error: "NS_ERROR_ABORT: " {file: "/data/local/tmp/test_root/xpc/head.js" line: 865}]
[task 2023-11-29T18:15:01.671Z] 18:15:01     INFO -  _abort_failed_test@/data/local/tmp/test_root/xpc/head.js:865:20
[task 2023-11-29T18:15:01.671Z] 18:15:01     INFO -  do_report_result@/data/local/tmp/test_root/xpc/head.js:977:5
[task 2023-11-29T18:15:01.671Z] 18:15:01     INFO -  Assert<@/data/local/tmp/test_root/xpc/head.js:70:21
[task 2023-11-29T18:15:01.671Z] 18:15:01     INFO -  Assert.prototype.report@resource://testing-common/Assert.sys.mjs:244:10
[task 2023-11-29T18:15:01.671Z] 18:15:01     INFO -  equal@resource://testing-common/Assert.sys.mjs:286:8
[task 2023-11-29T18:15:01.671Z] 18:15:01     INFO -  onLookupComplete@/data/local/tmp/test_root/xpc/netwerk/test/unit/head_trr.js:171:12
[task 2023-11-29T18:15:01.671Z] 18:15:01     INFO -  _do_main@/data/local/tmp/test_root/xpc/head.js:245:6
[task 2023-11-29T18:15:01.671Z] 18:15:01     INFO -  _execute_test@/data/local/tmp/test_root/xpc/head.js:592:5
[task 2023-11-29T18:15:01.671Z] 18:15:01     INFO -  @-e:1:1
[task 2023-11-29T18:15:01.671Z] 18:15:01     INFO -  "
[task 2023-11-29T18:15:01.671Z] 18:15:01     INFO -  "Force a GC"
[task 2023-11-29T18:15:01.671Z] 18:15:01     INFO -  <<<<<<<
[task 2023-11-29T18:15:01.695Z] 18:15:01     INFO -  Cleaning up profile for /builds/worker/workspace/build/tests/xpcshell/tests/netwerk/test/unit/test_trr.js folder: /data/local/tmp/test_root/xpc/p/8572bf33-0ef7-4662-97a8-b113d2990352
[task 2023-11-29T18:15:02.270Z] 18:15:02     INFO -  TEST-START | netwerk/test/unit/test_trr.js

Comment 1

[BugBot [:suhaib / :marco/ :calixte]]

:valentin, since you are the author of the regressor, bug 1866245, could you take a look?

For more information, please visit BugBot documentation.

Comment 2

[BugBot [:suhaib / :marco/ :calixte]]

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