Closed Bug 1948639 Opened 1 year ago Closed 1 year ago

Add telemetry for how soon the TRR server closes an idle connection

Categories

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

Product:
Core
Component:
Networking: DNS
Type:
task

Tracking

()

Status:
RESOLVED FIXED
Milestone:
139 Branch
Tracking Flags:
Tracking Status
firefox139 --- fixed

People

(Reporter: valentin, Assigned: valentin)

References

Details

(Whiteboard: [necko-triaged])

Attachments

(2 files)

Bug 1948639 - Add telemetry for how soon the TRR server closes an idle connection r=#necko
48 bytes, text/x-phabricator-request
Details | Review
Bug 1948639 - Add H3 TRR idle test r=#necko
48 bytes, text/x-phabricator-request
Details | Review
No description provided.
Attachment #9466604 - Attachment description: iBug 1948639 - Add telemetry for how soon the TRR server closes an idle connection r=#necko → Bug 1948639 - Add telemetry for how soon the TRR server closes an idle connection r=#necko
Attachment #9476587 - Attachment description: WIP: Bug 1948639 - Add H3 TRR test → Bug 1948639 - Add H3 TRR idle test r=#necko
Pushed by valentin.gosu@gmail.com: https://hg.mozilla.org/integration/autoland/rev/565d9cefd178 Add telemetry for how soon the TRR server closes an idle connection r=necko-reviewers,kershaw
Regressions: 1958297

https://hg.mozilla.org/mozilla-central/rev/565d9cefd178

Status: NEW → RESOLVED
Closed: 1 year ago
status-firefox139: --- → fixed
Resolution: --- → FIXED
Target Milestone: --- → 139 Branch
QA Whiteboard: [qa-triage-done-c140/b139]
Pushed by valentin.gosu@gmail.com: https://hg.mozilla.org/integration/autoland/rev/0936be37e6d1 Add H3 TRR idle test r=necko-reviewers,kershaw
Pushed by imoraru@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/e330793646d7 Revert "Bug 1948639 - Add H3 TRR idle test r=necko-reviewers,kershaw" for causing xpcshell failures on test_trr_idle_time.js

Revert for causing xpcshell failures on test_trr_idle_time.js

[task 2025-05-12T13:51:24.968Z] 13:51:24     INFO -  TEST-PASS | netwerk/test/unit/test_websocket_with_h3_active.js | took 1155ms
[task 2025-05-12T13:51:25.038Z] 13:51:25     INFO -  Cleaning up profile for /builds/worker/workspace/build/tests/xpcshell/tests/netwerk/test/unit/test_websocket_with_h3_active.js folder: /data/local/tmp/test_root/xpc/p/4a340f79-8d56-4480-995f-e0f5aebb4388
[task 2025-05-12T13:51:25.231Z] 13:51:25     INFO -  Retrying tests that failed when run in parallel.
[task 2025-05-12T13:51:25.699Z] 13:51:25     INFO -  TEST-START | netwerk/test/unit/test_trr_idle_time.js
[task 2025-05-12T13:51:25.745Z] 13:51:25     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/174f8a0b-b150-4bd7-9544-497f318501bb --es env13 HOME=/data/local/tmp/test_root/xpc/p --es env14 XPCSHELL_TEST_TEMP_DIR=/data/local/tmp/test_root/xpc/tmp/04973efd-fd9e-4677-afe7-36c37cb288a6 --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=39603 --es env19 MOZNODE_EXEC_PORT=38715 --es env20 MOZHTTP3_PORT=50733 --es env21 MOZHTTP3_PORT_FAILED=47864 --es env22 MOZHTTP3_PORT_ECH=40929 --es env23 MOZHTTP3_PORT_PROXY=48631 --es env24 MOZHTTP3_PORT_NO_RESPONSE=38078 --es env25 MOZHTTP3_ECH=AE3+DQBJBwAgACCXDO9M/s4brpVNTj4T+S5U172xzHxudVqyts8BXlEzVAAQAAEAAQABAAMAAgABAAIAA0AOcHVibGljLmV4YW1wbGUAAA== --es env26 MOZ_HTTP3_SERVER_PATH=/builds/worker/fetches/hostutils/http3server --es env27 MOZ_HTTP3_CERT_DB_PATH=/builds/worker/workspace/build/tests/xpcshell/http3server/http3serverDB --es env28 TMPDIR=/data/local/tmp/test_root/xpc/p/174f8a0b-b150-4bd7-9544-497f318501bb --es env29 XPCSHELL_MINIDUMP_DIR=/data/local/tmp/test_root/xpc/minidumps/174f8a0b-b150-4bd7-9544-497f318501bb --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/174f8a0b-b150-4bd7-9544-497f318501bb/mozinfo.json";' --es arg9 -e --es arg10 'const _PREFS_FILE = "/data/local/tmp/test_root/xpc/tmp/04973efd-fd9e-4677-afe7-36c37cb288a6/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_cache2.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/head_telemetry.js", "/data/local/tmp/test_root/xpc/netwerk/test/unit/head_websocket.js", "/data/local/tmp/test_root/xpc/netwerk/test/unit/head_webtransport.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_idle_time.js"];' --es arg23 -e --es arg24 'const _TEST_NAME = "netwerk/test/unit/test_trr_idle_time.js";' --es arg25 -e --es arg26 '_execute_test(); quit(0);' --es out_file /data/local/tmp/test_root/xpc/logs/xpcshell-f5985a4c-5345-4693-986f-333abd46c733.log
[task 2025-05-12T13:51:25.963Z] 13:51:25     INFO -  remotexpcshelltests.py | netwerk/test/unit/test_trr_idle_time.js | 13163 | Launched Test App
[task 2025-05-12T13:51:31.115Z] 13:51:31     INFO -  node moz-http2 [stdout] child process 3asEAi closing code: 1 signal: null
[task 2025-05-12T13:56:25.700Z] 13:56:25  WARNING -  TEST-UNEXPECTED-TIMEOUT | netwerk/test/unit/test_trr_idle_time.js | Test timed out
[task 2025-05-12T13:56:25.700Z] 13:56:25     INFO -  TEST-INFO took 300000ms
[task 2025-05-12T13:56:25.714Z] 13:56:25     INFO -  xpcshell return code: -1
[task 2025-05-12T13:56:25.714Z] 13:56:25     INFO -  Cleaning up profile for /builds/worker/workspace/build/tests/xpcshell/tests/netwerk/test/unit/test_trr_idle_time.js folder: /data/local/tmp/test_root/xpc/p/174f8a0b-b150-4bd7-9544-497f318501bb
[task 2025-05-12T13:56:35.957Z] 13:56:35     INFO -  remotexpcshelltests.py | netwerk/test/unit/test_trr_idle_time.js | 13163 | Timing out
[task 2025-05-12T13:56:41.020Z] 13:56:41     INFO -  remotexpcshelltests.py | netwerk/test/unit/test_trr_idle_time.js | 13163 | Application ran for: 0:05:15.320383
[task 2025-05-12T13:56:41.098Z] 13:56:41     INFO -  Cleaning up profile for /builds/worker/workspace/build/tests/xpcshell/tests/netwerk/test/unit/test_trr_idle_time.js folder: /data/local/tmp/test_root/xpc/p/174f8a0b-b150-4bd7-9544-497f318501bb
[task 2025-05-12T13:56:41.191Z] 13:56:41     INFO -  INFO | Result summary:
[task 2025-05-12T13:56:41.191Z] 13:56:41     INFO -  INFO | Passed: 461
[task 2025-05-12T13:56:41.192Z] 13:56:41  WARNING -  INFO | Failed: 1
[task 2025-05-12T13:56:41.192Z] 13:56:41  WARNING -  One or more unittests failed.
[task 2025-05-12T13:56:41.192Z] 13:56:41     INFO -  INFO | Todo: 1
[task 2025-05-12T13:56:41.192Z] 13:56:41     INFO -  INFO | Retried: 1
[task 2025-05-12T13:56:41.192Z] 13:56:41     INFO -  SUITE-END | took 1522s
[task 2025-05-12T13:56:41.192Z] 13:56:41     INFO -  Node moz-http2 server shutting down ...
[task 2025-05-12T13:56:41.199Z] 13:56:41     INFO -  cleared MOZHTTP2_PORT connection for port tcp:39603
[task 2025-05-12T13:56:41.205Z] 13:56:41     INFO -  cleared MOZNODE_EXEC_PORT connection for port tcp:38715
[task 2025-05-12T13:56:41.205Z] 13:56:41     INFO -  http3Server server shutting down ...
[task 2025-05-12T13:56:41.334Z] 13:56:41     INFO - Return code: 1
[task 2025-05-12T13:56:41.334Z] 13:56:41     INFO - TinderboxPrint: xpcshell<br/>461/<em class="testfail">1</em>/1
[task 2025-05-12T13:56:41.334Z] 13:56:41     INFO - ##### xpcshell log ends
[task 2025-05-12T13:56:41.334Z] 13:56:41  WARNING - setting return code to 1
[task 2025-05-12T13:56:41.334Z] 13:56:41     INFO - The xpcshell suite: xpcshell ran with return status: WARNING
[task 2025-05-12T13:56:41.335Z] 13:56:41     INFO - Running post-action listener: _package_coverage_data
[task 2025-05-12T13:56:41.335Z] 13:56:41     INFO - Running post-action listener: _resource_record_post_action
[task 2025-05-12T13:56:41.335Z] 13:56:41     INFO - Running post-action listener: process_java_coverage_data
[task 2025-05-12T13:56:41.335Z] 13:56:41     INFO - Running post-action listener: stop_device
[task 2025-05-12T13:56:41.624Z] 13:56:41     INFO - Killing logcat pid 1341.
[task 2025-05-12T13:56:41.624Z] 13:56:41     INFO - Killing every process called qemu-system-x86_64
[task 2025-05-12T13:56:41.630Z] 13:56:41     INFO - [mozharness: 2025-05-12 13:56:41.630151Z] Finished run-tests step (success)
[task 2025-05-12T13:56:41.630Z] 13:56:41     INFO - Running post-run listener: _resource_record_post_run
[task 2025-05-12T13:56:42.255Z] 13:56:42     INFO - Total resource usage - Wall time: 1579s; CPU: 17%; Read bytes: 0; Write bytes: 1449541632; Read time: 0; Write time: 1034196
[task 2025-05-12T13:56:42.255Z] 13:56:42     INFO - TinderboxPrint: CPU usage<br/>16.7%
[task 2025-05-12T13:56:42.255Z] 13:56:42     INFO - TinderboxPrint: I/O read bytes / time<br/>0 / 0
[task 2025-05-12T13:56:42.255Z] 13:56:42     INFO - TinderboxPrint: I/O write bytes / time<br/>1,449,541,632 / 1,034,196
[task 2025-05-12T13:56:42.255Z] 13:56:42     INFO - TinderboxPrint: CPU guest<br/>1,323.8 (9.5%)
[task 2025-05-12T13:56:42.255Z] 13:56:42     INFO - TinderboxPrint: CPU idle<br/>10,516.8 (75.1%)
[task 2025-05-12T13:56:42.255Z] 13:56:42     INFO - TinderboxPrint: CPU system<br/>638.0 (4.6%)
[task 2025-05-12T13:56:42.255Z] 13:56:42     INFO - TinderboxPrint: CPU user<br/>1,495.3 (10.7%)
[task 2025-05-12T13:56:42.255Z] 13:56:42     INFO - TinderboxPrint: Swap in / out<br/>0 / 0
[task 2025-05-12T13:56:42.257Z] 13:56:42     INFO - start-emulator - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2025-05-12T13:56:42.261Z] 13:56:42     INFO - verify-device - Wall time: 31s; CPU: 29%; Read bytes: 0; Write bytes: 13266944; Read time: 0; Write time: 5516
[task 2025-05-12T13:56:42.264Z] 13:56:42     INFO - install - Wall time: 12s; CPU: 36%; Read bytes: 0; Write bytes: 211324928; Read time: 0; Write time: 183580
[task 2025-05-12T13:56:42.389Z] 13:56:42     INFO - run-tests - Wall time: 1535s; CPU: 16%; Read bytes: 0; Write bytes: 1224933376; Read time: 0; Write time: 845096
[task 2025-05-12T13:56:45.784Z] 13:56:45  WARNING - returning nonzero exit status 1
[task 2025-05-12T13:56:46.026Z] cleanup
[task 2025-05-12T13:56:46.026Z] + cleanup
[task 2025-05-12T13:56:46.026Z] + local rv=1
[task 2025-05-12T13:56:46.026Z] + [[ -s /builds/worker/.xsession-errors ]]
[task 2025-05-12T13:56:46.026Z] + cp /builds/worker/.xsession-errors /builds/worker/artifacts/public/xsession-errors.log
[task 2025-05-12T13:56:46.028Z] + false
[task 2025-05-12T13:56:46.028Z] + '[' ']'
[task 2025-05-12T13:56:46.028Z] + true
[task 2025-05-12T13:56:46.028Z] + cleanup_xvfb
[task 2025-05-12T13:56:46.028Z] ++ pidof Xvfb
[task 2025-05-12T13:56:46.031Z] + local xvfb_pid=55
[task 2025-05-12T13:56:46.031Z] + local vnc=false
[task 2025-05-12T13:56:46.031Z] + local interactive=false
[task 2025-05-12T13:56:46.031Z] + '[' -n 55 ']'
[task 2025-05-12T13:56:46.031Z] + [[ false == false ]]
[task 2025-05-12T13:56:46.031Z] + [[ false == false ]]
[task 2025-05-12T13:56:46.031Z] + kill 55
[task 2025-05-12T13:56:46.031Z] + exit 1
[taskcluster 2025-05-12 13:56:46.637Z] === Task Finished ===
[taskcluster 2025-05-12 13:56:49.580Z] Unsuccessful task run with exit code: 1 completed in 2503.342 seconds
Flags: needinfo?(valentin.gosu)
Flags: needinfo?(valentin.gosu)
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: