[Profiler] Download profiler debug symbols for integration tests#6070
Conversation
Execution-Time Benchmarks Report ⏱️Execution-time results for samples comparing the following branches/commits: Execution-time benchmarks measure the whole time it takes to execute a program. And are intended to measure the one-off costs. Cases where the execution time results for the PR are worse than latest master results are shown in red. The following thresholds were used for comparing the execution times:
Note that these results are based on a single point-in-time result for each branch. For full results, see the dashboard. Graphs show the p99 interval based on the mean and StdDev of the test run, as well as the mean value of the run (shown as a diamond below the graph). gantt
title Execution time (ms) FakeDbCommand (.NET Framework 4.6.2)
dateFormat X
axisFormat %s
todayMarker off
section Baseline
This PR (6070) - mean (71ms) : 67, 75
. : milestone, 71,
master - mean (70ms) : 67, 73
. : milestone, 70,
section CallTarget+Inlining+NGEN
This PR (6070) - mean (1,109ms) : 1086, 1132
. : milestone, 1109,
master - mean (1,105ms) : 1067, 1143
. : milestone, 1105,
gantt
title Execution time (ms) FakeDbCommand (.NET Core 3.1)
dateFormat X
axisFormat %s
todayMarker off
section Baseline
This PR (6070) - mean (113ms) : 106, 120
. : milestone, 113,
master - mean (109ms) : 105, 112
. : milestone, 109,
section CallTarget+Inlining+NGEN
This PR (6070) - mean (789ms) : 742, 835
. : milestone, 789,
master - mean (766ms) : 751, 782
. : milestone, 766,
gantt
title Execution time (ms) FakeDbCommand (.NET 6)
dateFormat X
axisFormat %s
todayMarker off
section Baseline
This PR (6070) - mean (92ms) : 89, 95
. : milestone, 92,
master - mean (92ms) : 90, 94
. : milestone, 92,
section CallTarget+Inlining+NGEN
This PR (6070) - mean (726ms) : 710, 742
. : milestone, 726,
master - mean (727ms) : 707, 747
. : milestone, 727,
gantt
title Execution time (ms) HttpMessageHandler (.NET Framework 4.6.2)
dateFormat X
axisFormat %s
todayMarker off
section Baseline
This PR (6070) - mean (192ms) : 178, 207
. : milestone, 192,
master - mean (191ms) : 187, 195
. : milestone, 191,
section CallTarget+Inlining+NGEN
This PR (6070) - mean (1,197ms) : 1172, 1222
. : milestone, 1197,
master - mean (1,202ms) : 1165, 1240
. : milestone, 1202,
gantt
title Execution time (ms) HttpMessageHandler (.NET Core 3.1)
dateFormat X
axisFormat %s
todayMarker off
section Baseline
This PR (6070) - mean (275ms) : 271, 280
. : milestone, 275,
master - mean (276ms) : 270, 281
. : milestone, 276,
section CallTarget+Inlining+NGEN
This PR (6070) - mean (939ms) : 919, 960
. : milestone, 939,
master - mean (938ms) : 918, 958
. : milestone, 938,
gantt
title Execution time (ms) HttpMessageHandler (.NET 6)
dateFormat X
axisFormat %s
todayMarker off
section Baseline
This PR (6070) - mean (265ms) : 262, 269
. : milestone, 265,
master - mean (265ms) : 261, 269
. : milestone, 265,
section CallTarget+Inlining+NGEN
This PR (6070) - mean (934ms) : 910, 958
. : milestone, 934,
master - mean (922ms) : 906, 939
. : milestone, 922,
|
Datadog ReportBranch report: ✅ 0 Failed, 361089 Passed, 2048 Skipped, 15h 31m 25.11s Total Time ⌛ Performance Regressions vs Default Branch (1)
|
Benchmarks Report for tracer 🐌Benchmarks for #6070 compared to master:
The following thresholds were used for comparing the benchmark speeds:
Allocation changes below 0.5% are ignored. Benchmark detailsBenchmarks.Trace.ActivityBenchmark - Same speed ✔️ Same allocations ✔️Raw results
Benchmarks.Trace.AgentWriterBenchmark - Same speed ✔️ Same allocations ✔️Raw results
Benchmarks.Trace.AspNetCoreBenchmark - Same speed ✔️ Same allocations ✔️Raw results
Benchmarks.Trace.CIVisibilityProtocolWriterBenchmark - Same speed ✔️ Same allocations ✔️Raw results
Benchmarks.Trace.DbCommandBenchmark - Same speed ✔️ Same allocations ✔️Raw results
Benchmarks.Trace.ElasticsearchBenchmark - Same speed ✔️ Same allocations ✔️Raw results
Benchmarks.Trace.GraphQLBenchmark - Slower
|
| Benchmark | diff/base | Base Median (ns) | Diff Median (ns) | Modality |
|---|---|---|---|---|
| Benchmarks.Trace.GraphQLBenchmark.ExecuteAsync‑net6.0 | 1.120 | 1,225.47 | 1,372.35 |
Raw results
| Branch | Method | Toolchain | Mean | StdError | StdDev | Gen 0 | Gen 1 | Gen 2 | Allocated |
|---|---|---|---|---|---|---|---|---|---|
| master | ExecuteAsync |
net6.0 | 1.23μs | 0.572ns | 2.14ns | 0.0134 | 0 | 0 | 952 B |
| master | ExecuteAsync |
netcoreapp3.1 | 1.61μs | 2.86ns | 11.1ns | 0.0127 | 0 | 0 | 952 B |
| master | ExecuteAsync |
net472 | 1.81μs | 0.978ns | 3.79ns | 0.145 | 0 | 0 | 915 B |
| #6070 | ExecuteAsync |
net6.0 | 1.37μs | 2.34ns | 9.06ns | 0.0131 | 0 | 0 | 952 B |
| #6070 | ExecuteAsync |
netcoreapp3.1 | 1.62μs | 0.533ns | 1.92ns | 0.0122 | 0 | 0 | 952 B |
| #6070 | ExecuteAsync |
net472 | 1.85μs | 0.427ns | 1.65ns | 0.145 | 0 | 0 | 915 B |
Benchmarks.Trace.HttpClientBenchmark - Same speed ✔️ Same allocations ✔️
Raw results
| Branch | Method | Toolchain | Mean | StdError | StdDev | Gen 0 | Gen 1 | Gen 2 | Allocated |
|---|---|---|---|---|---|---|---|---|---|
| master | SendAsync |
net6.0 | 4.26μs | 1.23ns | 4.6ns | 0.0298 | 0 | 0 | 2.22 KB |
| master | SendAsync |
netcoreapp3.1 | 5.07μs | 4.13ns | 16ns | 0.0354 | 0 | 0 | 2.76 KB |
| master | SendAsync |
net472 | 7.85μs | 1.34ns | 5.2ns | 0.497 | 0 | 0 | 3.15 KB |
| #6070 | SendAsync |
net6.0 | 4.23μs | 1.34ns | 5.03ns | 0.0318 | 0 | 0 | 2.22 KB |
| #6070 | SendAsync |
netcoreapp3.1 | 5.04μs | 2.06ns | 7.71ns | 0.0353 | 0 | 0 | 2.76 KB |
| #6070 | SendAsync |
net472 | 7.8μs | 1.46ns | 5.64ns | 0.5 | 0 | 0 | 3.15 KB |
Benchmarks.Trace.ILoggerBenchmark - Same speed ✔️ Same allocations ✔️
Raw results
| Branch | Method | Toolchain | Mean | StdError | StdDev | Gen 0 | Gen 1 | Gen 2 | Allocated |
|---|---|---|---|---|---|---|---|---|---|
| master | EnrichedLog |
net6.0 | 1.56μs | 0.743ns | 2.88ns | 0.0227 | 0 | 0 | 1.64 KB |
| master | EnrichedLog |
netcoreapp3.1 | 2.2μs | 1.15ns | 4.46ns | 0.022 | 0 | 0 | 1.64 KB |
| master | EnrichedLog |
net472 | 2.6μs | 1.54ns | 5.96ns | 0.249 | 0 | 0 | 1.57 KB |
| #6070 | EnrichedLog |
net6.0 | 1.59μs | 0.98ns | 3.67ns | 0.0229 | 0 | 0 | 1.64 KB |
| #6070 | EnrichedLog |
netcoreapp3.1 | 2.32μs | 0.95ns | 3.43ns | 0.0221 | 0 | 0 | 1.64 KB |
| #6070 | EnrichedLog |
net472 | 2.66μs | 2.25ns | 8.7ns | 0.249 | 0 | 0 | 1.57 KB |
Benchmarks.Trace.Log4netBenchmark - Same speed ✔️ Same allocations ✔️
Raw results
| Branch | Method | Toolchain | Mean | StdError | StdDev | Gen 0 | Gen 1 | Gen 2 | Allocated |
|---|---|---|---|---|---|---|---|---|---|
| master | EnrichedLog |
net6.0 | 113μs | 120ns | 464ns | 0.0566 | 0 | 0 | 4.28 KB |
| master | EnrichedLog |
netcoreapp3.1 | 119μs | 106ns | 411ns | 0.0598 | 0 | 0 | 4.28 KB |
| master | EnrichedLog |
net472 | 145μs | 67.6ns | 244ns | 0.656 | 0.219 | 0 | 4.46 KB |
| #6070 | EnrichedLog |
net6.0 | 115μs | 151ns | 585ns | 0 | 0 | 0 | 4.28 KB |
| #6070 | EnrichedLog |
netcoreapp3.1 | 121μs | 154ns | 595ns | 0.0602 | 0 | 0 | 4.28 KB |
| #6070 | EnrichedLog |
net472 | 149μs | 221ns | 856ns | 0.667 | 0.222 | 0 | 4.46 KB |
Benchmarks.Trace.NLogBenchmark - Same speed ✔️ Same allocations ✔️
Raw results
| Branch | Method | Toolchain | Mean | StdError | StdDev | Gen 0 | Gen 1 | Gen 2 | Allocated |
|---|---|---|---|---|---|---|---|---|---|
| master | EnrichedLog |
net6.0 | 3.09μs | 0.928ns | 3.59ns | 0.0308 | 0 | 0 | 2.2 KB |
| master | EnrichedLog |
netcoreapp3.1 | 4.41μs | 1.9ns | 7.37ns | 0.0287 | 0 | 0 | 2.2 KB |
| master | EnrichedLog |
net472 | 4.81μs | 1.3ns | 4.85ns | 0.32 | 0 | 0 | 2.02 KB |
| #6070 | EnrichedLog |
net6.0 | 3.15μs | 1.06ns | 3.96ns | 0.0301 | 0 | 0 | 2.2 KB |
| #6070 | EnrichedLog |
netcoreapp3.1 | 4.18μs | 1.67ns | 6.45ns | 0.0292 | 0 | 0 | 2.2 KB |
| #6070 | EnrichedLog |
net472 | 4.75μs | 1.46ns | 5.64ns | 0.321 | 0 | 0 | 2.02 KB |
Benchmarks.Trace.RedisBenchmark - Same speed ✔️ Same allocations ✔️
Raw results
| Branch | Method | Toolchain | Mean | StdError | StdDev | Gen 0 | Gen 1 | Gen 2 | Allocated |
|---|---|---|---|---|---|---|---|---|---|
| master | SendReceive |
net6.0 | 1.36μs | 0.7ns | 2.62ns | 0.0156 | 0 | 0 | 1.14 KB |
| master | SendReceive |
netcoreapp3.1 | 1.73μs | 1.22ns | 4.58ns | 0.0155 | 0 | 0 | 1.14 KB |
| master | SendReceive |
net472 | 2.17μs | 1.16ns | 4.32ns | 0.183 | 0.00109 | 0 | 1.16 KB |
| #6070 | SendReceive |
net6.0 | 1.38μs | 0.731ns | 2.83ns | 0.0159 | 0 | 0 | 1.14 KB |
| #6070 | SendReceive |
netcoreapp3.1 | 1.73μs | 0.767ns | 2.97ns | 0.0157 | 0 | 0 | 1.14 KB |
| #6070 | SendReceive |
net472 | 2.11μs | 0.985ns | 3.41ns | 0.183 | 0.00104 | 0 | 1.16 KB |
Benchmarks.Trace.SerilogBenchmark - Same speed ✔️ Same allocations ✔️
Raw results
| Branch | Method | Toolchain | Mean | StdError | StdDev | Gen 0 | Gen 1 | Gen 2 | Allocated |
|---|---|---|---|---|---|---|---|---|---|
| master | EnrichedLog |
net6.0 | 2.77μs | 10.3ns | 40.1ns | 0.022 | 0 | 0 | 1.6 KB |
| master | EnrichedLog |
netcoreapp3.1 | 3.77μs | 1.36ns | 5.08ns | 0.0209 | 0 | 0 | 1.65 KB |
| master | EnrichedLog |
net472 | 4.33μs | 1.67ns | 6.46ns | 0.323 | 0 | 0 | 2.04 KB |
| #6070 | EnrichedLog |
net6.0 | 2.7μs | 0.753ns | 2.92ns | 0.0216 | 0 | 0 | 1.6 KB |
| #6070 | EnrichedLog |
netcoreapp3.1 | 3.99μs | 1.85ns | 6.93ns | 0.022 | 0 | 0 | 1.65 KB |
| #6070 | EnrichedLog |
net472 | 4.37μs | 2.59ns | 9.68ns | 0.322 | 0 | 0 | 2.04 KB |
Benchmarks.Trace.SpanBenchmark - Slower ⚠️ Same allocations ✔️
Slower ⚠️ in #6070
Benchmark
diff/base
Base Median (ns)
Diff Median (ns)
Modality
Benchmarks.Trace.SpanBenchmark.StartFinishSpan‑net6.0
1.150
407.09
468.29
Faster 🎉 in #6070
Benchmark
base/diff
Base Median (ns)
Diff Median (ns)
Modality
Benchmarks.Trace.SpanBenchmark.StartFinishScope‑netcoreapp3.1
1.140
758.39
665.05
| Benchmark | diff/base | Base Median (ns) | Diff Median (ns) | Modality |
|---|---|---|---|---|
| Benchmarks.Trace.SpanBenchmark.StartFinishSpan‑net6.0 | 1.150 | 407.09 | 468.29 |
| Benchmark | base/diff | Base Median (ns) | Diff Median (ns) | Modality |
|---|---|---|---|---|
| Benchmarks.Trace.SpanBenchmark.StartFinishScope‑netcoreapp3.1 | 1.140 | 758.39 | 665.05 |
Raw results
| Branch | Method | Toolchain | Mean | StdError | StdDev | Gen 0 | Gen 1 | Gen 2 | Allocated |
|---|---|---|---|---|---|---|---|---|---|
| master | StartFinishSpan |
net6.0 | 407ns | 0.361ns | 1.4ns | 0.00818 | 0 | 0 | 576 B |
| master | StartFinishSpan |
netcoreapp3.1 | 594ns | 1.01ns | 3.93ns | 0.00798 | 0 | 0 | 576 B |
| master | StartFinishSpan |
net472 | 654ns | 1.56ns | 5.84ns | 0.0917 | 0 | 0 | 578 B |
| master | StartFinishScope |
net6.0 | 477ns | 0.235ns | 0.911ns | 0.00966 | 0 | 0 | 696 B |
| master | StartFinishScope |
netcoreapp3.1 | 759ns | 1.06ns | 4.09ns | 0.00938 | 0 | 0 | 696 B |
| master | StartFinishScope |
net472 | 888ns | 1.04ns | 4.01ns | 0.105 | 0 | 0 | 658 B |
| #6070 | StartFinishSpan |
net6.0 | 469ns | 0.677ns | 2.62ns | 0.0081 | 0 | 0 | 576 B |
| #6070 | StartFinishSpan |
netcoreapp3.1 | 557ns | 1.2ns | 4.64ns | 0.00794 | 0 | 0 | 576 B |
| #6070 | StartFinishSpan |
net472 | 680ns | 0.42ns | 1.63ns | 0.0917 | 0 | 0 | 578 B |
| #6070 | StartFinishScope |
net6.0 | 490ns | 1.06ns | 3.82ns | 0.00987 | 0 | 0 | 696 B |
| #6070 | StartFinishScope |
netcoreapp3.1 | 665ns | 0.82ns | 3.07ns | 0.00958 | 0 | 0 | 696 B |
| #6070 | StartFinishScope |
net472 | 881ns | 0.182ns | 0.705ns | 0.104 | 0 | 0 | 658 B |
Benchmarks.Trace.TraceAnnotationsBenchmark - Faster 🎉 Same allocations ✔️
Faster 🎉 in #6070
Benchmark
base/diff
Base Median (ns)
Diff Median (ns)
Modality
Benchmarks.Trace.TraceAnnotationsBenchmark.RunOnMethodBegin‑net6.0
1.149
675.74
588.02
| Benchmark | base/diff | Base Median (ns) | Diff Median (ns) | Modality |
|---|---|---|---|---|
| Benchmarks.Trace.TraceAnnotationsBenchmark.RunOnMethodBegin‑net6.0 | 1.149 | 675.74 | 588.02 |
Raw results
| Branch | Method | Toolchain | Mean | StdError | StdDev | Gen 0 | Gen 1 | Gen 2 | Allocated |
|---|---|---|---|---|---|---|---|---|---|
| master | RunOnMethodBegin |
net6.0 | 676ns | 0.514ns | 1.99ns | 0.00988 | 0 | 0 | 696 B |
| master | RunOnMethodBegin |
netcoreapp3.1 | 982ns | 0.697ns | 2.61ns | 0.00936 | 0 | 0 | 696 B |
| master | RunOnMethodBegin |
net472 | 1.11μs | 0.875ns | 3.39ns | 0.105 | 0 | 0 | 658 B |
| #6070 | RunOnMethodBegin |
net6.0 | 588ns | 0.268ns | 1.04ns | 0.00977 | 0 | 0 | 696 B |
| #6070 | RunOnMethodBegin |
netcoreapp3.1 | 923ns | 0.582ns | 2.26ns | 0.00922 | 0 | 0 | 696 B |
| #6070 | RunOnMethodBegin |
net472 | 1.14μs | 0.59ns | 2.28ns | 0.104 | 0 | 0 | 658 B |
Summary of changes
Download profiler debug symbols for integration tests jobs.
Reason for change
When a test takes too much time, the profiler test infrastructure calls gdb to collect all threads callstack and print it in the console. (and generate a coredump)
Since binaries are stripped, the callstack are difficult to read. For the jobs that runs on CentOS 7, we cannot create a new image and install gdb (and tools) because of deprecation and mainly because all the CentOS 7 packages are gone.
Implementation details
Download the profiler native debug symbols when setting up the tests.
Test coverage
Other details