Skip to content

refactor(transport/recovery): use PacketNumberSpace Debug in qdebug #2515

New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Merged
merged 1 commit into from
Mar 24, 2025

Conversation

mxinden
Copy link
Collaborator

@mxinden mxinden commented Mar 23, 2025

Simple change to improve debug level log message:

- 0.002 DEBUG [LossRecovery] PTO timer fired for in
+ 0.006 DEBUG [LossRecovery] PTO timer fired for Initial

Previously it was not obvious to me that "in" meant "Initial" packet number space.

Simple change to improve debug level log message:

``` diff
- 0.002 DEBUG [LossRecovery] PTO timer fired for in
+ 0.006 DEBUG [LossRecovery] PTO timer fired for Initial
```
Copy link

Failed Interop Tests

QUIC Interop Runner, client vs. server, differences relative to f95b6c3.

neqo-latest as client

neqo-latest as server

All results

Succeeded Interop Tests

QUIC Interop Runner, client vs. server

neqo-latest as client

neqo-latest as server

Unsupported Interop Tests

QUIC Interop Runner, client vs. server

neqo-latest as client

neqo-latest as server

Copy link

Benchmark results

Performance differences relative to f95b6c3.

decode 4096 bytes, mask ff: No change in performance detected.
       time:   [11.814 µs 11.842 µs 11.875 µs]
       change: [-0.7034% -0.2643% +0.0633%] (p = 0.20 > 0.05)

Found 6 outliers among 100 measurements (6.00%)
3 (3.00%) low mild
3 (3.00%) high severe

decode 1048576 bytes, mask ff: No change in performance detected.
       time:   [3.0680 ms 3.0773 ms 3.0884 ms]
       change: [-0.4719% +0.0100% +0.4760%] (p = 0.94 > 0.05)

Found 9 outliers among 100 measurements (9.00%)
9 (9.00%) high severe

decode 4096 bytes, mask 7f: No change in performance detected.
       time:   [19.749 µs 19.788 µs 19.834 µs]
       change: [-0.3726% -0.0174% +0.3237%] (p = 0.93 > 0.05)

Found 18 outliers among 100 measurements (18.00%)
2 (2.00%) low severe
5 (5.00%) low mild
2 (2.00%) high mild
9 (9.00%) high severe

decode 1048576 bytes, mask 7f: No change in performance detected.
       time:   [5.1522 ms 5.1646 ms 5.1779 ms]
       change: [-0.1892% +0.1580% +0.4914%] (p = 0.37 > 0.05)

Found 15 outliers among 100 measurements (15.00%)
15 (15.00%) high severe

decode 4096 bytes, mask 3f: No change in performance detected.
       time:   [6.8720 µs 6.8958 µs 6.9264 µs]
       change: [-0.2171% +0.7199% +2.2849%] (p = 0.35 > 0.05)

Found 13 outliers among 100 measurements (13.00%)
13 (13.00%) high severe

decode 1048576 bytes, mask 3f: No change in performance detected.
       time:   [1.7537 ms 1.7579 ms 1.7635 ms]
       change: [-0.6392% -0.1692% +0.3036%] (p = 0.50 > 0.05)

Found 5 outliers among 100 measurements (5.00%)
1 (1.00%) high mild
4 (4.00%) high severe

1 streams of 1 bytes/multistream: No change in performance detected.
       time:   [69.666 µs 70.231 µs 71.258 µs]
       change: [-2.0081% -0.0931% +1.9803%] (p = 0.89 > 0.05)

Found 1 outliers among 100 measurements (1.00%)
1 (1.00%) high severe

1000 streams of 1 bytes/multistream: Change within noise threshold.
       time:   [24.471 ms 24.507 ms 24.544 ms]
       change: [-0.4704% -0.2470% -0.0283%] (p = 0.03 < 0.05)

Found 3 outliers among 100 measurements (3.00%)
3 (3.00%) high mild

10000 streams of 1 bytes/multistream: Change within noise threshold.
       time:   [1.6473 s 1.6489 s 1.6506 s]
       change: [+0.1206% +0.2679% +0.4097%] (p = 0.00 < 0.05)

Found 12 outliers among 100 measurements (12.00%)
6 (6.00%) low mild
6 (6.00%) high mild

1 streams of 1000 bytes/multistream: No change in performance detected.
       time:   [71.347 µs 71.911 µs 72.930 µs]
       change: [-3.1197% -0.7141% +1.3480%] (p = 0.58 > 0.05)

Found 2 outliers among 100 measurements (2.00%)
1 (1.00%) high mild
1 (1.00%) high severe

100 streams of 1000 bytes/multistream: No change in performance detected.
       time:   [3.2515 ms 3.2578 ms 3.2646 ms]
       change: [-0.2021% +0.0875% +0.3814%] (p = 0.56 > 0.05)

Found 19 outliers among 100 measurements (19.00%)
19 (19.00%) high severe

1000 streams of 1000 bytes/multistream: No change in performance detected.
       time:   [138.20 ms 138.27 ms 138.35 ms]
       change: [-0.0585% +0.0187% +0.0995%] (p = 0.64 > 0.05)

Found 3 outliers among 100 measurements (3.00%)
3 (3.00%) high mild

coalesce_acked_from_zero 1+1 entries: No change in performance detected.
       time:   [92.479 ns 92.777 ns 93.076 ns]
       change: [-0.7955% -0.2319% +0.2901%] (p = 0.42 > 0.05)

Found 11 outliers among 100 measurements (11.00%)
9 (9.00%) high mild
2 (2.00%) high severe

coalesce_acked_from_zero 3+1 entries: No change in performance detected.
       time:   [110.31 ns 110.67 ns 111.05 ns]
       change: [-0.3543% +0.0482% +0.4322%] (p = 0.81 > 0.05)

Found 14 outliers among 100 measurements (14.00%)
3 (3.00%) low mild
1 (1.00%) high mild
10 (10.00%) high severe

coalesce_acked_from_zero 10+1 entries: No change in performance detected.
       time:   [110.26 ns 110.83 ns 111.47 ns]
       change: [+0.0005% +0.4942% +1.0897%] (p = 0.07 > 0.05)

Found 16 outliers among 100 measurements (16.00%)
6 (6.00%) low mild
10 (10.00%) high severe

coalesce_acked_from_zero 1000+1 entries: No change in performance detected.
       time:   [91.201 ns 91.253 ns 91.319 ns]
       change: [-1.3677% -0.5438% +0.2358%] (p = 0.21 > 0.05)

Found 13 outliers among 100 measurements (13.00%)
6 (6.00%) high mild
7 (7.00%) high severe

RxStreamOrderer::inbound_frame(): No change in performance detected.
       time:   [115.77 ms 115.82 ms 115.87 ms]
       change: [-0.0202% +0.0406% +0.1002%] (p = 0.19 > 0.05)

Found 17 outliers among 100 measurements (17.00%)
4 (4.00%) low severe
2 (2.00%) low mild
5 (5.00%) high mild
6 (6.00%) high severe

SentPackets::take_ranges: No change in performance detected.
       time:   [5.3423 µs 5.5566 µs 5.7969 µs]
       change: [-3.4790% -0.1832% +3.1267%] (p = 0.91 > 0.05)

Found 7 outliers among 100 measurements (7.00%)
6 (6.00%) high mild
1 (1.00%) high severe

transfer/pacing-false/varying-seeds: No change in performance detected.
       time:   [34.417 ms 34.479 ms 34.542 ms]
       change: [-0.1867% +0.0835% +0.3550%] (p = 0.55 > 0.05)
transfer/pacing-true/varying-seeds: Change within noise threshold.
       time:   [34.897 ms 34.952 ms 35.008 ms]
       change: [+0.7422% +0.9698% +1.1754%] (p = 0.00 < 0.05)

Found 2 outliers among 100 measurements (2.00%)
2 (2.00%) high mild

transfer/pacing-false/same-seed: Change within noise threshold.
       time:   [34.644 ms 34.693 ms 34.744 ms]
       change: [+0.7472% +0.9458% +1.1498%] (p = 0.00 < 0.05)

Found 1 outliers among 100 measurements (1.00%)
1 (1.00%) high mild

transfer/pacing-true/same-seed: Change within noise threshold.
       time:   [34.855 ms 34.911 ms 34.967 ms]
       change: [+0.5429% +0.7843% +1.0343%] (p = 0.00 < 0.05)
1-conn/1-100mb-resp/mtu-1504 (aka. Download)/client: No change in performance detected.
       time:   [2.2277 s 2.2346 s 2.2416 s]
       thrpt:  [44.611 MiB/s 44.750 MiB/s 44.890 MiB/s]
change:
       time:   [-0.2860% +0.1884% +0.6383%] (p = 0.42 > 0.05)
       thrpt:  [-0.6343% -0.1880% +0.2868%]
1-conn/10_000-parallel-1b-resp/mtu-1504 (aka. RPS)/client: No change in performance detected.
       time:   [389.69 ms 391.75 ms 393.82 ms]
       thrpt:  [25.393 Kelem/s 25.527 Kelem/s 25.661 Kelem/s]
change:
       time:   [-0.8579% -0.1351% +0.6214%] (p = 0.73 > 0.05)
       thrpt:  [-0.6176% +0.1353% +0.8653%]
1-conn/1-1b-resp/mtu-1504 (aka. HPS)/client: 💔 Performance has regressed.
       time:   [29.399 ms 30.211 ms 31.015 ms]
       thrpt:  [32.243  elem/s 33.100  elem/s 34.015  elem/s]
change:
       time:   [+5.4095% +9.2634% +13.154%] (p = 0.00 < 0.05)
       thrpt:  [-11.625% -8.4781% -5.1319%]
1-conn/1-100mb-resp/mtu-1504 (aka. Upload)/client: 💔 Performance has regressed.
       time:   [3.5055 s 3.5276 s 3.5503 s]
       thrpt:  [28.166 MiB/s 28.348 MiB/s 28.526 MiB/s]
change:
       time:   [+8.8893% +9.8561% +10.848%] (p = 0.00 < 0.05)
       thrpt:  [-9.7863% -8.9718% -8.1636%]

Found 1 outliers among 100 measurements (1.00%)
1 (1.00%) high mild

Client/server transfer results

Performance differences relative to f95b6c3.

Transfer of 33554432 bytes over loopback, 30 runs. All unit-less numbers are in milliseconds.

Client Server CC Pacing Mean ± σ Min Max Δ main Δ main
neqo neqo reno on 501.7 ± 63.6 444.2 712.5 -15.5 -0.8%
neqo neqo reno 547.3 ± 133.4 455.6 1048.9 24.7 1.2%
neqo neqo cubic on 502.9 ± 48.5 453.6 687.1 -11.9 -0.6%
neqo neqo cubic 514.7 ± 69.6 453.7 725.5 6.7 0.3%
google neqo reno on 922.3 ± 107.1 669.3 1106.4 17.3 0.5%
google neqo reno 908.0 ± 89.7 659.4 1041.4 1.7 0.0%
google neqo cubic on 901.3 ± 100.6 660.1 1041.1 -1.8 -0.1%
google neqo cubic 899.5 ± 90.1 660.4 1003.0 4.1 0.1%
google google 554.6 ± 27.7 530.2 660.9 1.6 0.1%
neqo msquic reno on 224.3 ± 28.6 196.1 335.1 -17.6 -1.9%
neqo msquic reno 237.2 ± 67.5 198.1 504.9 17.7 1.9%
neqo msquic cubic on 228.0 ± 38.3 200.1 371.9 8.9 1.0%
neqo msquic cubic 229.4 ± 40.3 197.3 400.6 3.1 0.3%
msquic msquic 116.1 ± 13.3 100.4 162.9 -1.4 -0.3%

⬇️ Download logs

@larseggert larseggert added this pull request to the merge queue Mar 24, 2025
Merged via the queue into mozilla:main with commit cbf1551 Mar 24, 2025
73 of 74 checks passed
mxinden added a commit to mxinden/neqo that referenced this pull request Mar 27, 2025
mxinden added a commit to mxinden/neqo that referenced this pull request Mar 27, 2025
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

Successfully merging this pull request may close these issues.

2 participants