[linus:master] [NFSD] 39d432fc76: fsmark.files_per_sec -100.0% regression

From: kernel test robot
Date: Thu Aug 24 2023 - 02:01:14 EST




hi, Chuck Lever,

Fengwei (CCed) helped us review this astonishing finding by fsmark tests,
and doubt below part:
- nfsd4_end_grace(nn);
+ trace_nfsd_end_grace(netns(file));

and confirmed if adding back:
nfsd4_end_grace(nn);

the regression is gone and files_per_sec restore to 61.93.

FYI.


Hello,

kernel test robot noticed a -100.0% regression of fsmark.files_per_sec on:


commit: 39d432fc76301cf0a0c454022117601994ca9397 ("NFSD: trace nfsctl operations")
https://git.kernel.org/cgit/linux/kernel/git/torvalds/linux.git master

testcase: fsmark
test machine: 64 threads 2 sockets Intel(R) Xeon(R) Gold 6346 CPU @ 3.10GHz (Ice Lake) with 256G memory
parameters:

iterations: 1x
nr_threads: 32t
disk: 1SSD
fs: btrfs
fs2: nfsv4
filesize: 16MB
test_size: 20G
sync_method: NoSync
nr_directories: 16d
nr_files_per_directory: 256fpd
cpufreq_governor: performance


In addition to that, the commit also has significant impact on the following tests:

+------------------+-------------------------------------------------------------------------------------------+
| testcase: change | fsmark: fsmark.files_per_sec -100.0% regression |
| test machine | 64 threads 2 sockets Intel(R) Xeon(R) Gold 6346 CPU @ 3.10GHz (Ice Lake) with 256G memory |
| test parameters | cpufreq_governor=performance |
| | debug-setup=no-monitor |
| | disk=1SSD |
| | filesize=16MB |
| | fs2=nfsv4 |
| | fs=btrfs |
| | iterations=1x |
| | nr_directories=16d |
| | nr_files_per_directory=256fpd |
| | nr_threads=32t |
| | sync_method=NoSync |
| | test_size=20G |
+------------------+-------------------------------------------------------------------------------------------+


If you fix the issue in a separate patch/commit (i.e. not just a new version of
the same patch/commit), kindly add following tags
| Reported-by: kernel test robot <oliver.sang@xxxxxxxxx>
| Closes: https://lore.kernel.org/oe-lkp/202308241229.68396422-oliver.sang@xxxxxxxxx


Details are as below:
-------------------------------------------------------------------------------------------------->


The kernel config and materials to reproduce are available at:
https://download.01.org/0day-ci/archive/20230824/202308241229.68396422-oliver.sang@xxxxxxxxx

=========================================================================================
compiler/cpufreq_governor/disk/filesize/fs2/fs/iterations/kconfig/nr_directories/nr_files_per_directory/nr_threads/rootfs/sync_method/tbox_group/test_size/testcase:
gcc-12/performance/1SSD/16MB/nfsv4/btrfs/1x/x86_64-rhel-8.3/16d/256fpd/32t/debian-11.1-x86_64-20220510.cgz/NoSync/lkp-icl-2sp7/20G/fsmark

commit:
3434d7aa77 ("NFSD: Clean up nfsctl_transaction_write()")
39d432fc76 ("NFSD: trace nfsctl operations")

3434d7aa77d24c5c 39d432fc76301cf0a0c45402211
---------------- ---------------------------
%stddev %change %stddev
\ | \
51.67 ± 25% -90.8% 4.75 ± 45% perf-c2c.DRAM.local
35.36 ± 16% +35.2% 47.81 ± 16% boot-time.dhcp
1.81 ± 41% +90.9% 3.46 ± 31% boot-time.smp_boot
1.455e+09 ± 6% +12542.8% 1.839e+11 ± 20% cpuidle..time
3013365 ± 2% +6257.5% 1.916e+08 ± 20% cpuidle..usage
81.11 ± 7% +3758.6% 3129 ± 18% uptime.boot
4349 ± 8% +4266.2% 189897 ± 19% uptime.idle
71.95 +31.7% 94.75 iostat.cpu.idle
18.35 ± 3% -84.5% 2.85 ± 56% iostat.cpu.iowait
7.98 ± 5% -70.3% 2.37 iostat.cpu.system
1.73 ± 3% -98.0% 0.03 ± 15% iostat.cpu.user
7176382 ± 6% +37.6% 9873307 ± 6% numa-numastat.node0.local_node
7210227 ± 6% +37.5% 9914765 ± 6% numa-numastat.node0.numa_hit
6958614 ± 6% +29.1% 8981736 ± 4% numa-numastat.node1.local_node
6990967 ± 6% +28.8% 9006618 ± 4% numa-numastat.node1.numa_hit
8749533 ± 4% +7050.4% 6.256e+08 ± 18% fsmark.app_overhead
61.92 ± 5% -100.0% 0.00 fsmark.files_per_sec
22.77 ± 4% +12864.1% 2951 ± 20% fsmark.time.elapsed_time
22.77 ± 4% +12864.1% 2951 ± 20% fsmark.time.elapsed_time.max
189.33 ± 7% -99.5% 1.00 fsmark.time.percent_of_cpu_this_job_got
70.09 +24.7 94.75 mpstat.cpu.all.idle%
19.80 ± 4% -16.9 2.86 ± 56% mpstat.cpu.all.iowait%
1.18 ± 4% +1.0 2.20 mpstat.cpu.all.irq%
0.13 ± 2% -0.1 0.07 ± 3% mpstat.cpu.all.soft%
7.10 ± 7% -7.0 0.09 ± 12% mpstat.cpu.all.sys%
1.70 ± 5% -1.7 0.03 ± 15% mpstat.cpu.all.usr%
71.33 +32.5% 94.50 vmstat.cpu.id
18.00 ± 3% -87.5% 2.25 ± 79% vmstat.cpu.wa
784537 ± 5% -99.0% 7461 ± 21% vmstat.io.bo
10.17 ± 23% -85.2% 1.50 ± 74% vmstat.procs.b
6.00 ± 25% -100.0% 0.00 vmstat.procs.r
101767 ± 7% -91.5% 8688 ± 8% vmstat.system.cs
70688 -9.1% 64225 vmstat.system.in
43996 ± 5% +121.0% 97246 ± 9% meminfo.Active
4516 ± 14% +1588.6% 76266 ± 6% meminfo.Active(anon)
39479 ± 4% -46.9% 20978 ± 24% meminfo.Active(file)
34922 ± 23% +551.6% 227542 meminfo.AnonHugePages
397478 -24.1% 301733 meminfo.AnonPages
653045 -19.5% 525838 meminfo.Committed_AS
154701 ± 15% -42.0% 89789 ± 4% meminfo.Dirty
401768 -23.5% 307192 meminfo.Inactive(anon)
33307 -11.3% 29548 meminfo.Mapped
6837 ± 7% -27.6% 4950 meminfo.PageTables
9016 ± 7% +814.1% 82421 ± 6% meminfo.Shmem
324959 ± 7% +25.1% 406488 ± 2% meminfo.Writeback
1940 ± 58% +683.0% 15195 ± 36% numa-meminfo.node0.Active(anon)
18757 ± 14% -33.6% 12450 ± 30% numa-meminfo.node0.Active(file)
18975 ± 47% +872.5% 184526 ± 25% numa-meminfo.node0.AnonHugePages
79966 ± 22% -38.8% 48963 ± 8% numa-meminfo.node0.Dirty
5833 ± 21% +235.6% 19573 ± 31% numa-meminfo.node0.Shmem
23325 ± 16% +198.4% 69601 ± 9% numa-meminfo.node1.Active
2576 ± 43% +2270.7% 61072 ± 5% numa-meminfo.node1.Active(anon)
20749 ± 15% -58.9% 8528 ± 46% numa-meminfo.node1.Active(file)
183812 ± 19% -67.5% 59692 ± 75% numa-meminfo.node1.AnonPages
67153 ± 14% -39.2% 40835 ± 14% numa-meminfo.node1.Dirty
184305 ± 19% -66.8% 61274 ± 73% numa-meminfo.node1.Inactive(anon)
3143 ± 25% -37.2% 1973 ± 21% numa-meminfo.node1.PageTables
3184 ± 36% +1873.9% 62849 ± 5% numa-meminfo.node1.Shmem
339.17 ± 5% -91.4% 29.25 turbostat.Avg_MHz
11.43 ± 5% -8.2 3.23 turbostat.Busy%
3004 -69.7% 909.25 turbostat.Bzy_MHz
52538 ± 7% +834.8% 491105 ± 18% turbostat.C1
0.41 ± 7% -0.4 0.01 ± 57% turbostat.C1%
849439 ± 2% +679.9% 6624934 ± 48% turbostat.C1E
23.10 ± 3% -20.1 3.02 ± 53% turbostat.C1E%
1067521 ± 8% +17084.8% 1.835e+08 ± 20% turbostat.C6
64.56 ± 2% +29.6 94.19 turbostat.C6%
87.74 +10.3% 96.76 turbostat.CPU%c1
0.83 ± 45% -98.5% 0.01 ± 34% turbostat.CPU%c6
128.85 ±152% -99.9% 0.09 turbostat.IPC
1858116 ± 5% +10114.2% 1.898e+08 ± 20% turbostat.IRQ
0.64 ± 6% -0.6 0.01 ±100% turbostat.POLL%
154.16 -23.3% 118.17 turbostat.PkgWatt
63.10 -15.5% 53.29 turbostat.RAMWatt
484.83 ± 58% +683.5% 3798 ± 36% numa-vmstat.node0.nr_active_anon
4690 ± 14% -33.6% 3112 ± 30% numa-vmstat.node0.nr_active_file
8.83 ± 51% +916.0% 89.75 ± 25% numa-vmstat.node0.nr_anon_transparent_hugepages
19775 ± 24% -38.3% 12194 ± 8% numa-vmstat.node0.nr_dirty
1457 ± 21% +235.7% 4893 ± 31% numa-vmstat.node0.nr_shmem
484.83 ± 58% +683.5% 3798 ± 36% numa-vmstat.node0.nr_zone_active_anon
4690 ± 14% -33.6% 3112 ± 30% numa-vmstat.node0.nr_zone_active_file
36457 ± 22% -59.5% 14783 ± 10% numa-vmstat.node0.nr_zone_write_pending
7210556 ± 6% +37.5% 9914914 ± 6% numa-vmstat.node0.numa_hit
7176711 ± 6% +37.6% 9873455 ± 6% numa-vmstat.node0.numa_local
643.83 ± 43% +2271.4% 15268 ± 5% numa-vmstat.node1.nr_active_anon
5189 ± 15% -58.9% 2132 ± 46% numa-vmstat.node1.nr_active_file
45952 ± 19% -67.5% 14923 ± 75% numa-vmstat.node1.nr_anon_pages
16398 ± 15% -37.8% 10204 ± 14% numa-vmstat.node1.nr_dirty
46074 ± 19% -66.8% 15318 ± 73% numa-vmstat.node1.nr_inactive_anon
785.33 ± 25% -37.3% 492.75 ± 21% numa-vmstat.node1.nr_page_table_pages
795.83 ± 36% +1874.3% 15712 ± 5% numa-vmstat.node1.nr_shmem
643.83 ± 43% +2271.4% 15268 ± 5% numa-vmstat.node1.nr_zone_active_anon
5189 ± 15% -58.9% 2132 ± 46% numa-vmstat.node1.nr_zone_active_file
46074 ± 19% -66.8% 15318 ± 73% numa-vmstat.node1.nr_zone_inactive_anon
34148 ± 11% -62.3% 12864 ± 19% numa-vmstat.node1.nr_zone_write_pending
6991051 ± 6% +28.8% 9006790 ± 4% numa-vmstat.node1.numa_hit
6958699 ± 6% +29.1% 8981908 ± 4% numa-vmstat.node1.numa_local
1128 ± 14% +1589.1% 19066 ± 6% proc-vmstat.nr_active_anon
9870 ± 4% -46.9% 5244 ± 24% proc-vmstat.nr_active_file
99377 -24.1% 75431 proc-vmstat.nr_anon_pages
16.67 ± 23% +564.5% 110.75 proc-vmstat.nr_anon_transparent_hugepages
38368 ± 15% -41.6% 22410 ± 4% proc-vmstat.nr_dirty
100443 -23.5% 76796 proc-vmstat.nr_inactive_anon
15586 -6.6% 14560 proc-vmstat.nr_kernel_stack
8326 -11.3% 7386 proc-vmstat.nr_mapped
1709 ± 7% -27.6% 1236 proc-vmstat.nr_page_table_pages
2254 ± 7% +814.2% 20605 ± 6% proc-vmstat.nr_shmem
81381 ± 7% +24.9% 101605 ± 2% proc-vmstat.nr_writeback
1128 ± 14% +1589.1% 19066 ± 6% proc-vmstat.nr_zone_active_anon
9870 ± 4% -46.9% 5244 ± 24% proc-vmstat.nr_zone_active_file
100443 -23.5% 76796 proc-vmstat.nr_zone_inactive_anon
71853 ± 11% -61.5% 27641 ± 12% proc-vmstat.nr_zone_write_pending
404.67 ± 6% +1276.3% 5569 ± 13% proc-vmstat.numa_hint_faults
69.67 ± 27% +4299.9% 3065 ± 77% proc-vmstat.numa_hint_faults_local
14202988 +33.2% 18923999 ± 5% proc-vmstat.numa_hit
14136791 +33.4% 18857658 ± 5% proc-vmstat.numa_local
335.00 ± 10% +1203.3% 4366 ± 82% proc-vmstat.numa_pages_migrated
698.50 ± 8% +5553.1% 39487 ± 15% proc-vmstat.numa_pte_updates
11408 ± 2% +84.5% 21048 ± 5% proc-vmstat.pgactivate
18935279 +26.2% 23897622 ± 4% proc-vmstat.pgalloc_normal
225757 +2641.0% 6188094 ± 19% proc-vmstat.pgfault
13477492 +37.7% 18558535 ± 5% proc-vmstat.pgfree
335.00 ± 10% +1203.3% 4366 ± 82% proc-vmstat.pgmigrate_success
8658 ± 2% +3345.5% 298318 ± 19% proc-vmstat.pgreuse
637184 ± 40% +3336.2% 21894720 ± 20% proc-vmstat.unevictable_pgs_scanned
0.23 ± 10% -76.3% 0.05 sched_debug.cfs_rq:/.h_nr_running.avg
0.43 ± 5% -48.4% 0.22 sched_debug.cfs_rq:/.h_nr_running.stddev
13720 ± 57% +127.8% 31261 ± 2% sched_debug.cfs_rq:/.load.avg
109.64 ± 26% -72.0% 30.67 ± 14% sched_debug.cfs_rq:/.load_avg.avg
1053 ± 2% -20.8% 834.32 ± 3% sched_debug.cfs_rq:/.load_avg.max
274.91 ± 14% -48.8% 140.77 ± 7% sched_debug.cfs_rq:/.load_avg.stddev
13475 ± 20% +63.8% 22072 ± 17% sched_debug.cfs_rq:/.min_vruntime.avg
39896 ± 14% +40.9% 56195 ± 15% sched_debug.cfs_rq:/.min_vruntime.max
5378 ± 23% +108.5% 11216 ± 14% sched_debug.cfs_rq:/.min_vruntime.min
5503 ± 11% +57.4% 8663 ± 13% sched_debug.cfs_rq:/.min_vruntime.stddev
0.23 ± 10% -76.3% 0.05 sched_debug.cfs_rq:/.nr_running.avg
0.43 ± 5% -48.4% 0.22 sched_debug.cfs_rq:/.nr_running.stddev
65.15 ± 36% -93.0% 4.53 ± 76% sched_debug.cfs_rq:/.removed.load_avg.avg
1024 -87.2% 131.45 ±119% sched_debug.cfs_rq:/.removed.load_avg.max
242.33 ± 17% -90.8% 22.40 ±101% sched_debug.cfs_rq:/.removed.load_avg.stddev
23.97 ± 36% -91.6% 2.00 ± 84% sched_debug.cfs_rq:/.removed.runnable_avg.avg
504.33 ± 3% -86.8% 66.39 ±118% sched_debug.cfs_rq:/.removed.runnable_avg.max
93.28 ± 17% -88.6% 10.66 ±106% sched_debug.cfs_rq:/.removed.runnable_avg.stddev
23.97 ± 36% -91.6% 2.00 ± 84% sched_debug.cfs_rq:/.removed.util_avg.avg
504.33 ± 3% -86.8% 66.39 ±118% sched_debug.cfs_rq:/.removed.util_avg.max
93.28 ± 17% -88.6% 10.66 ±106% sched_debug.cfs_rq:/.removed.util_avg.stddev
447.97 ± 3% -93.2% 30.55 ± 10% sched_debug.cfs_rq:/.runnable_avg.avg
1652 ± 21% -65.3% 573.91 sched_debug.cfs_rq:/.runnable_avg.max
361.15 ± 10% -72.7% 98.67 ± 4% sched_debug.cfs_rq:/.runnable_avg.stddev
5515 ± 10% +57.1% 8663 ± 13% sched_debug.cfs_rq:/.spread0.stddev
447.20 ± 3% -93.2% 30.54 ± 10% sched_debug.cfs_rq:/.util_avg.avg
1651 ± 21% -65.2% 573.90 sched_debug.cfs_rq:/.util_avg.max
360.75 ± 10% -72.7% 98.65 ± 4% sched_debug.cfs_rq:/.util_avg.stddev
35.87 ± 32% -92.8% 2.57 ± 23% sched_debug.cfs_rq:/.util_est_enqueued.avg
627.00 ± 32% -88.2% 73.92 ± 11% sched_debug.cfs_rq:/.util_est_enqueued.max
125.31 ± 21% -90.6% 11.82 ± 13% sched_debug.cfs_rq:/.util_est_enqueued.stddev
773027 ± 4% +25.1% 966950 sched_debug.cpu.avg_idle.avg
3844 ± 16% +9867.6% 383238 ± 36% sched_debug.cpu.avg_idle.min
284379 ± 22% -62.7% 106134 ± 15% sched_debug.cpu.avg_idle.stddev
56501 ± 11% +2798.3% 1637565 ± 18% sched_debug.cpu.clock.avg
56505 ± 11% +2798.1% 1637568 ± 18% sched_debug.cpu.clock.max
56493 ± 11% +2798.7% 1637561 ± 18% sched_debug.cpu.clock.min
2.84 ± 14% -30.1% 1.98 ± 3% sched_debug.cpu.clock.stddev
55933 ± 11% +2766.0% 1603047 ± 18% sched_debug.cpu.clock_task.avg
56388 ± 11% +2747.3% 1605532 ± 18% sched_debug.cpu.clock_task.max
43272 ± 5% +3548.5% 1578791 ± 18% sched_debug.cpu.clock_task.min
1611 ± 45% +102.9% 3269 ± 31% sched_debug.cpu.clock_task.stddev
3920 +950.0% 41168 ± 18% sched_debug.cpu.curr->pid.max
1512 ± 2% +241.7% 5166 ± 17% sched_debug.cpu.curr->pid.stddev
0.00 ± 28% -66.7% 0.00 ± 4% sched_debug.cpu.next_balance.stddev
0.22 ± 7% -87.6% 0.03 ± 4% sched_debug.cpu.nr_running.avg
0.42 ± 5% -63.9% 0.15 ± 2% sched_debug.cpu.nr_running.stddev
6681 +2922.6% 201964 ± 20% sched_debug.cpu.nr_switches.avg
203120 +243.0% 696661 ± 25% sched_debug.cpu.nr_switches.max
347.50 ± 19% +12362.1% 43305 ± 29% sched_debug.cpu.nr_switches.min
26003 ± 2% +367.6% 121580 ± 15% sched_debug.cpu.nr_switches.stddev
56496 ± 11% +2798.5% 1637561 ± 18% sched_debug.cpu_clk
55771 ± 11% +2834.9% 1636836 ± 18% sched_debug.ktime
51473 ± 5% +3047.3% 1620032 ± 18% sched_debug.sched_clk
17.02 ± 3% -78.0% 3.74 perf-stat.i.MPKI
1.839e+09 ± 6% -94.8% 96458674 ± 5% perf-stat.i.branch-instructions
1.20 ± 7% -0.4 0.80 perf-stat.i.branch-miss-rate%
22120063 ± 10% -95.7% 961876 ± 9% perf-stat.i.branch-misses
59.22 ± 3% -50.6 8.66 perf-stat.i.cache-miss-rate%
91195048 ± 7% -99.1% 801389 ± 18% perf-stat.i.cache-misses
1.507e+08 ± 7% -98.3% 2538095 ± 10% perf-stat.i.cache-references
119037 ± 8% -92.7% 8685 ± 8% perf-stat.i.context-switches
2.52 ± 3% -7.7% 2.33 perf-stat.i.cpi
2.236e+10 ± 6% -95.2% 1.081e+09 ± 3% perf-stat.i.cpu-cycles
264.18 ± 12% -71.9% 74.16 perf-stat.i.cpu-migrations
331.37 ± 29% +2939.3% 10071 ± 2% perf-stat.i.cycles-between-cache-misses
0.03 ± 14% +0.1 0.09 ± 23% perf-stat.i.dTLB-load-miss-rate%
734621 ± 10% -86.2% 101019 ± 22% perf-stat.i.dTLB-load-misses
2.249e+09 ± 5% -94.3% 1.281e+08 ± 4% perf-stat.i.dTLB-loads
0.01 ± 10% +0.0 0.04 ± 13% perf-stat.i.dTLB-store-miss-rate%
122554 ± 8% -80.7% 23688 ± 11% perf-stat.i.dTLB-store-misses
9.999e+08 ± 5% -93.2% 67811239 ± 3% perf-stat.i.dTLB-stores
9.066e+09 ± 6% -94.6% 4.866e+08 ± 4% perf-stat.i.instructions
0.47 ± 64% -97.2% 0.01 ± 42% perf-stat.i.major-faults
0.35 ± 6% -95.2% 0.02 ± 3% perf-stat.i.metric.GHz
1229 ± 6% -34.7% 802.68 perf-stat.i.metric.K/sec
81.72 ± 5% -95.3% 3.81 ± 5% perf-stat.i.metric.M/sec
3477 ± 6% -42.5% 1998 perf-stat.i.minor-faults
52.18 ± 4% +41.9 94.08 perf-stat.i.node-load-miss-rate%
18404682 ± 7% -99.0% 176907 ± 14% perf-stat.i.node-load-misses
16742387 ± 9% -99.1% 144408 ± 22% perf-stat.i.node-loads
12.77 ± 9% +18.7 31.45 ± 27% perf-stat.i.node-store-miss-rate%
4850218 ± 12% -99.0% 49478 ± 9% perf-stat.i.node-store-misses
36531812 ± 6% -99.1% 320605 ± 19% perf-stat.i.node-stores
3478 ± 6% -42.5% 1998 perf-stat.i.page-faults
16.63 ± 3% -68.7% 5.21 ± 5% perf-stat.overall.MPKI
1.20 ± 7% -0.2 1.00 ± 4% perf-stat.overall.branch-miss-rate%
60.51 ± 2% -29.2 31.34 ± 8% perf-stat.overall.cache-miss-rate%
2.47 ± 3% -9.9% 2.22 perf-stat.overall.cpi
245.31 +464.9% 1385 ± 15% perf-stat.overall.cycles-between-cache-misses
0.03 ± 13% +0.0 0.08 ± 22% perf-stat.overall.dTLB-load-miss-rate%
0.01 ± 9% +0.0 0.03 ± 12% perf-stat.overall.dTLB-store-miss-rate%
0.41 ± 3% +10.9% 0.45 perf-stat.overall.ipc
1.76e+09 ± 5% -94.5% 96442481 ± 5% perf-stat.ps.branch-instructions
21170965 ± 10% -95.5% 961854 ± 9% perf-stat.ps.branch-misses
87299736 ± 6% -99.1% 801893 ± 18% perf-stat.ps.cache-misses
1.443e+08 ± 6% -98.2% 2538635 ± 10% perf-stat.ps.cache-references
113951 ± 8% -92.4% 8683 ± 8% perf-stat.ps.context-switches
61339 +4.3% 63977 perf-stat.ps.cpu-clock
2.141e+10 ± 6% -95.0% 1.081e+09 ± 3% perf-stat.ps.cpu-cycles
252.91 ± 12% -70.7% 74.14 perf-stat.ps.cpu-migrations
703511 ± 10% -85.6% 100987 ± 22% perf-stat.ps.dTLB-load-misses
2.153e+09 ± 5% -94.1% 1.281e+08 ± 4% perf-stat.ps.dTLB-loads
117341 ± 8% -79.8% 23681 ± 11% perf-stat.ps.dTLB-store-misses
9.573e+08 ± 5% -92.9% 67795911 ± 3% perf-stat.ps.dTLB-stores
8.678e+09 ± 5% -94.4% 4.866e+08 ± 4% perf-stat.ps.instructions
0.45 ± 64% -97.1% 0.01 ± 42% perf-stat.ps.major-faults
3324 ± 5% -39.9% 1998 perf-stat.ps.minor-faults
17622482 ± 7% -99.0% 177005 ± 14% perf-stat.ps.node-load-misses
16025463 ± 9% -99.1% 144476 ± 22% perf-stat.ps.node-loads
4642492 ± 12% -98.9% 49511 ± 10% perf-stat.ps.node-store-misses
34969456 ± 6% -99.1% 320794 ± 19% perf-stat.ps.node-stores
3324 ± 5% -39.9% 1998 perf-stat.ps.page-faults
61339 +4.3% 63977 perf-stat.ps.task-clock
2.065e+11 ± 3% +589.5% 1.424e+12 ± 16% perf-stat.total.instructions
0.01 ± 60% +113.6% 0.02 ± 20% perf-sched.sch_delay.avg.ms.devkmsg_read.vfs_read.ksys_read.do_syscall_64
0.00 ±157% +425.0% 0.01 ± 14% perf-sched.sch_delay.avg.ms.do_nanosleep.hrtimer_nanosleep.common_nsleep.__x64_sys_clock_nanosleep
0.03 ± 16% +71.9% 0.05 ± 3% perf-sched.sch_delay.avg.ms.irq_thread.kthread.ret_from_fork
0.01 ± 38% -65.4% 0.00 ± 19% perf-sched.sch_delay.avg.ms.pipe_read.vfs_read.ksys_read.do_syscall_64
0.58 ±151% -96.5% 0.02 ± 7% perf-sched.sch_delay.avg.ms.worker_thread.kthread.ret_from_fork
0.00 ±108% +218.0% 0.01 ± 13% perf-sched.sch_delay.max.ms.__x64_sys_pause.do_syscall_64.entry_SYSCALL_64_after_hwframe.[unknown]
0.03 ± 21% +92.0% 0.07 perf-sched.sch_delay.max.ms.irq_thread.kthread.ret_from_fork
0.00 ±120% +267.2% 0.02 ± 12% perf-sched.sch_delay.max.ms.schedule_hrtimeout_range_clock.do_poll.constprop.0.do_sys_poll
7.44 ±112% -99.6% 0.03 ± 25% perf-sched.sch_delay.max.ms.smpboot_thread_fn.kthread.ret_from_fork
0.04 ± 91% -81.6% 0.01 perf-sched.total_sch_delay.average.ms
55.05 ±169% -94.2% 3.20 ± 12% perf-sched.total_sch_delay.max.ms
9.41 ± 27% +263.5% 34.20 ± 5% perf-sched.total_wait_and_delay.average.ms
2099 ± 13% +875.6% 20478 ± 4% perf-sched.total_wait_and_delay.count.ms
309.85 ± 2% +1511.3% 4992 perf-sched.total_wait_and_delay.max.ms
9.37 ± 28% +265.1% 34.20 ± 5% perf-sched.total_wait_time.average.ms
309.85 ± 2% +1511.3% 4992 perf-sched.total_wait_time.max.ms
1.83 ± 24% +1179.9% 23.43 perf-sched.wait_and_delay.avg.ms.__cond_resched.__wait_for_common.affine_move_task.__set_cpus_allowed_ptr.__sched_setaffinity
68.28 ± 38% -100.0% 0.00 perf-sched.wait_and_delay.avg.ms.devkmsg_read.vfs_read.ksys_read.do_syscall_64
0.93 ± 30% +26685.0% 250.13 ± 3% perf-sched.wait_and_delay.avg.ms.do_task_dead.do_exit.do_group_exit.__x64_sys_exit_group.do_syscall_64
69.46 +46.7% 101.87 perf-sched.wait_and_delay.avg.ms.irq_thread.kthread.ret_from_fork
1.64 ± 6% +2380.5% 40.63 ± 2% perf-sched.wait_and_delay.avg.ms.pipe_read.vfs_read.ksys_read.do_syscall_64
39.18 ± 14% +863.6% 377.57 perf-sched.wait_and_delay.avg.ms.schedule_hrtimeout_range_clock.ep_poll.do_epoll_wait.__x64_sys_epoll_wait
4.35 ± 6% +576.4% 29.42 ± 6% perf-sched.wait_and_delay.avg.ms.schedule_timeout.rcu_gp_fqs_loop.rcu_gp_kthread.kthread
32.32 ± 47% +1966.0% 667.82 perf-sched.wait_and_delay.avg.ms.smpboot_thread_fn.kthread.ret_from_fork
49.09 ± 11% +739.5% 412.11 ± 4% perf-sched.wait_and_delay.avg.ms.worker_thread.kthread.ret_from_fork
245.00 ± 39% +56.7% 384.00 perf-sched.wait_and_delay.count.__cond_resched.__wait_for_common.affine_move_task.__set_cpus_allowed_ptr.__sched_setaffinity
3.83 ± 23% -100.0% 0.00 perf-sched.wait_and_delay.count.devkmsg_read.vfs_read.ksys_read.do_syscall_64
507.67 ± 9% -75.6% 124.00 perf-sched.wait_and_delay.count.do_task_dead.do_exit.do_group_exit.__x64_sys_exit_group.do_syscall_64
449.83 ± 9% -72.7% 123.00 perf-sched.wait_and_delay.count.do_wait.kernel_wait4.__do_sys_wait4.do_syscall_64
3.00 +1500.0% 48.00 perf-sched.wait_and_delay.count.irq_thread.kthread.ret_from_fork
343.83 ± 9% +416.7% 1776 ± 2% perf-sched.wait_and_delay.count.pipe_read.vfs_read.ksys_read.do_syscall_64
73.33 ± 8% +121.9% 162.75 ± 6% perf-sched.wait_and_delay.count.schedule_timeout.rcu_gp_fqs_loop.rcu_gp_kthread.kthread
302.83 ± 30% +71.9% 520.50 perf-sched.wait_and_delay.count.smpboot_thread_fn.kthread.ret_from_fork
117.50 ± 18% +212.1% 366.75 ± 4% perf-sched.wait_and_delay.count.worker_thread.kthread.ret_from_fork
299.23 ± 3% +1568.5% 4992 perf-sched.wait_and_delay.max.ms.__cond_resched.__wait_for_common.affine_move_task.__set_cpus_allowed_ptr.__sched_setaffinity
236.11 ± 27% -100.0% 0.00 perf-sched.wait_and_delay.max.ms.devkmsg_read.vfs_read.ksys_read.do_syscall_64
69.82 ±139% +2069.6% 1514 ± 31% perf-sched.wait_and_delay.max.ms.do_task_dead.do_exit.do_group_exit.__x64_sys_exit_group.do_syscall_64
238.02 ± 28% +322.4% 1005 perf-sched.wait_and_delay.max.ms.pipe_read.vfs_read.ksys_read.do_syscall_64
292.85 ± 3% +70.7% 499.98 perf-sched.wait_and_delay.max.ms.schedule_hrtimeout_range_clock.ep_poll.do_epoll_wait.__x64_sys_epoll_wait
5.04 +6666.8% 340.75 ± 18% perf-sched.wait_and_delay.max.ms.schedule_timeout.rcu_gp_fqs_loop.rcu_gp_kthread.kthread
305.61 ± 2% +800.7% 2752 ± 29% perf-sched.wait_and_delay.max.ms.smpboot_thread_fn.kthread.ret_from_fork
303.33 ± 4% +705.6% 2443 ± 13% perf-sched.wait_and_delay.max.ms.worker_thread.kthread.ret_from_fork
1.83 ± 24% +1180.3% 23.43 perf-sched.wait_time.avg.ms.__cond_resched.__wait_for_common.affine_move_task.__set_cpus_allowed_ptr.__sched_setaffinity
68.27 ± 38% -97.3% 1.82 ± 12% perf-sched.wait_time.avg.ms.devkmsg_read.vfs_read.ksys_read.do_syscall_64
0.00 ±223% +7.7e+07% 766.71 ± 7% perf-sched.wait_time.avg.ms.do_nanosleep.hrtimer_nanosleep.common_nsleep.__x64_sys_clock_nanosleep
0.93 ± 30% +26838.2% 250.12 ± 3% perf-sched.wait_time.avg.ms.do_task_dead.do_exit.do_group_exit.__x64_sys_exit_group.do_syscall_64
0.29 ± 69% -100.0% 0.00 perf-sched.wait_time.avg.ms.do_task_dead.do_exit.do_group_exit.get_signal.arch_do_signal_or_restart
69.43 +46.6% 101.82 perf-sched.wait_time.avg.ms.irq_thread.kthread.ret_from_fork
1.63 ± 6% +2390.3% 40.62 ± 2% perf-sched.wait_time.avg.ms.pipe_read.vfs_read.ksys_read.do_syscall_64
38.67 ± 13% +876.3% 377.56 perf-sched.wait_time.avg.ms.schedule_hrtimeout_range_clock.ep_poll.do_epoll_wait.__x64_sys_epoll_wait
0.07 ±223% +576.2% 0.49 ± 7% perf-sched.wait_time.avg.ms.schedule_timeout.__wait_for_common.wait_for_completion_state.kernel_clone
4.34 ± 6% +578.4% 29.41 ± 6% perf-sched.wait_time.avg.ms.schedule_timeout.rcu_gp_fqs_loop.rcu_gp_kthread.kthread
32.27 ± 47% +1969.2% 667.81 perf-sched.wait_time.avg.ms.smpboot_thread_fn.kthread.ret_from_fork
48.50 ± 11% +749.6% 412.09 ± 4% perf-sched.wait_time.avg.ms.worker_thread.kthread.ret_from_fork
299.06 ± 3% +1569.4% 4992 perf-sched.wait_time.max.ms.__cond_resched.__wait_for_common.affine_move_task.__set_cpus_allowed_ptr.__sched_setaffinity
236.10 ± 28% -98.5% 3.65 ± 12% perf-sched.wait_time.max.ms.devkmsg_read.vfs_read.ksys_read.do_syscall_64
0.00 ±223% +5.5e+07% 1000 perf-sched.wait_time.max.ms.do_nanosleep.hrtimer_nanosleep.common_nsleep.__x64_sys_clock_nanosleep
69.82 ±139% +2069.6% 1514 ± 31% perf-sched.wait_time.max.ms.do_task_dead.do_exit.do_group_exit.__x64_sys_exit_group.do_syscall_64
0.55 ± 77% -100.0% 0.00 perf-sched.wait_time.max.ms.do_task_dead.do_exit.do_group_exit.get_signal.arch_do_signal_or_restart
238.01 ± 28% +322.4% 1005 perf-sched.wait_time.max.ms.pipe_read.vfs_read.ksys_read.do_syscall_64
292.84 ± 3% +70.7% 499.97 perf-sched.wait_time.max.ms.schedule_hrtimeout_range_clock.ep_poll.do_epoll_wait.__x64_sys_epoll_wait
0.15 ±223% +647.1% 1.09 ± 12% perf-sched.wait_time.max.ms.schedule_timeout.__wait_for_common.wait_for_completion_state.kernel_clone
5.03 +6675.8% 340.74 ± 18% perf-sched.wait_time.max.ms.schedule_timeout.rcu_gp_fqs_loop.rcu_gp_kthread.kthread
305.61 ± 2% +800.7% 2752 ± 29% perf-sched.wait_time.max.ms.smpboot_thread_fn.kthread.ret_from_fork
303.32 ± 4% +705.6% 2443 ± 13% perf-sched.wait_time.max.ms.worker_thread.kthread.ret_from_fork
37.90 ± 63% -36.6 1.29 ± 9% perf-profile.calltrace.cycles-pp.do_syscall_64.entry_SYSCALL_64_after_hwframe
37.90 ± 63% -36.6 1.29 ± 8% perf-profile.calltrace.cycles-pp.entry_SYSCALL_64_after_hwframe
27.62 ± 86% -27.5 0.16 ±173% perf-profile.calltrace.cycles-pp.exit_mmap.__mmput.exit_mm.do_exit.do_group_exit
24.63 ± 88% -24.6 0.00 perf-profile.calltrace.cycles-pp.unmap_vmas.exit_mmap.__mmput.exit_mm.do_exit
24.63 ± 88% -24.6 0.00 perf-profile.calltrace.cycles-pp.unmap_page_range.unmap_vmas.exit_mmap.__mmput.exit_mm
24.63 ± 88% -24.6 0.00 perf-profile.calltrace.cycles-pp.zap_pmd_range.unmap_page_range.unmap_vmas.exit_mmap.__mmput
24.63 ± 88% -24.6 0.00 perf-profile.calltrace.cycles-pp.zap_pte_range.zap_pmd_range.unmap_page_range.unmap_vmas.exit_mmap
23.24 ± 82% -23.2 0.00 perf-profile.calltrace.cycles-pp.do_group_exit.get_signal.arch_do_signal_or_restart.exit_to_user_mode_loop.exit_to_user_mode_prepare
23.24 ± 82% -23.2 0.00 perf-profile.calltrace.cycles-pp.do_exit.do_group_exit.get_signal.arch_do_signal_or_restart.exit_to_user_mode_loop
23.24 ± 82% -23.2 0.00 perf-profile.calltrace.cycles-pp.exit_mm.do_exit.do_group_exit.get_signal.arch_do_signal_or_restart
23.24 ± 82% -23.2 0.00 perf-profile.calltrace.cycles-pp.__mmput.exit_mm.do_exit.do_group_exit.get_signal
19.58 ±103% -19.6 0.00 perf-profile.calltrace.cycles-pp.exit_to_user_mode_loop.exit_to_user_mode_prepare.syscall_exit_to_user_mode.do_syscall_64.entry_SYSCALL_64_after_hwframe
18.70 ±111% -18.7 0.00 perf-profile.calltrace.cycles-pp.syscall_exit_to_user_mode.do_syscall_64.entry_SYSCALL_64_after_hwframe
18.70 ±111% -18.7 0.00 perf-profile.calltrace.cycles-pp.exit_to_user_mode_prepare.syscall_exit_to_user_mode.do_syscall_64.entry_SYSCALL_64_after_hwframe
18.70 ±111% -18.7 0.00 perf-profile.calltrace.cycles-pp.arch_do_signal_or_restart.exit_to_user_mode_loop.exit_to_user_mode_prepare.syscall_exit_to_user_mode.do_syscall_64
18.70 ±111% -18.7 0.00 perf-profile.calltrace.cycles-pp.get_signal.arch_do_signal_or_restart.exit_to_user_mode_loop.exit_to_user_mode_prepare.syscall_exit_to_user_mode
10.10 ± 88% -10.1 0.00 perf-profile.calltrace.cycles-pp.page_remove_rmap.zap_pte_range.zap_pmd_range.unmap_page_range.unmap_vmas
10.23 ± 79% -10.1 0.15 ±173% perf-profile.calltrace.cycles-pp.__libc_start_main
10.23 ± 79% -10.1 0.15 ±173% perf-profile.calltrace.cycles-pp.main.__libc_start_main
10.23 ± 79% -10.1 0.15 ±173% perf-profile.calltrace.cycles-pp.run_builtin.main.__libc_start_main
9.52 ± 97% -9.3 0.18 ±173% perf-profile.calltrace.cycles-pp.do_exit.do_group_exit.__x64_sys_exit_group.do_syscall_64.entry_SYSCALL_64_after_hwframe
9.52 ± 97% -9.3 0.19 ±173% perf-profile.calltrace.cycles-pp.__x64_sys_exit_group.do_syscall_64.entry_SYSCALL_64_after_hwframe
9.52 ± 97% -9.3 0.19 ±173% perf-profile.calltrace.cycles-pp.do_group_exit.__x64_sys_exit_group.do_syscall_64.entry_SYSCALL_64_after_hwframe
6.39 ± 87% -6.4 0.00 perf-profile.calltrace.cycles-pp.entry_SYSCALL_64_after_hwframe.__libc_write.writen.record__pushfn.perf_mmap__push
6.39 ± 87% -6.4 0.00 perf-profile.calltrace.cycles-pp.do_syscall_64.entry_SYSCALL_64_after_hwframe.__libc_write.writen.record__pushfn
6.39 ± 87% -6.4 0.00 perf-profile.calltrace.cycles-pp.__generic_file_write_iter.generic_file_write_iter.vfs_write.ksys_write.do_syscall_64
6.39 ± 87% -6.4 0.00 perf-profile.calltrace.cycles-pp.ksys_write.do_syscall_64.entry_SYSCALL_64_after_hwframe.__libc_write.writen
6.39 ± 87% -6.4 0.00 perf-profile.calltrace.cycles-pp.generic_file_write_iter.vfs_write.ksys_write.do_syscall_64.entry_SYSCALL_64_after_hwframe
6.39 ± 87% -6.4 0.00 perf-profile.calltrace.cycles-pp.generic_perform_write.__generic_file_write_iter.generic_file_write_iter.vfs_write.ksys_write
6.39 ± 87% -6.4 0.00 perf-profile.calltrace.cycles-pp.vfs_write.ksys_write.do_syscall_64.entry_SYSCALL_64_after_hwframe.__libc_write
6.39 ± 87% -6.4 0.00 perf-profile.calltrace.cycles-pp.record__pushfn.perf_mmap__push.record__mmap_read_evlist.__cmd_record.cmd_record
6.39 ± 87% -6.4 0.00 perf-profile.calltrace.cycles-pp.writen.record__pushfn.perf_mmap__push.record__mmap_read_evlist.__cmd_record
6.39 ± 87% -6.4 0.00 perf-profile.calltrace.cycles-pp.__libc_write.writen.record__pushfn.perf_mmap__push.record__mmap_read_evlist
6.15 ±101% -6.1 0.00 perf-profile.calltrace.cycles-pp.cmd_record.run_builtin.main.__libc_start_main
6.15 ±101% -6.1 0.00 perf-profile.calltrace.cycles-pp.__cmd_record.cmd_record.run_builtin.main.__libc_start_main
6.15 ±101% -6.1 0.00 perf-profile.calltrace.cycles-pp.record__mmap_read_evlist.__cmd_record.cmd_record.run_builtin.main
6.08 ± 79% -6.1 0.00 perf-profile.calltrace.cycles-pp.do_mmap.vm_mmap_pgoff.ksys_mmap_pgoff.do_syscall_64.entry_SYSCALL_64_after_hwframe
6.08 ± 79% -6.1 0.00 perf-profile.calltrace.cycles-pp.mmap_region.do_mmap.vm_mmap_pgoff.ksys_mmap_pgoff.do_syscall_64
4.63 ± 80% -4.6 0.00 perf-profile.calltrace.cycles-pp.perf_mmap__push.record__mmap_read_evlist.__cmd_record.cmd_record.run_builtin
3.84 ±104% -3.8 0.00 perf-profile.calltrace.cycles-pp.entry_SYSCALL_64_after_hwframe.__mmap
3.84 ±104% -3.8 0.00 perf-profile.calltrace.cycles-pp.do_syscall_64.entry_SYSCALL_64_after_hwframe.__mmap
3.84 ±104% -3.8 0.00 perf-profile.calltrace.cycles-pp.ksys_mmap_pgoff.do_syscall_64.entry_SYSCALL_64_after_hwframe.__mmap
3.84 ±104% -3.8 0.00 perf-profile.calltrace.cycles-pp.vm_mmap_pgoff.ksys_mmap_pgoff.do_syscall_64.entry_SYSCALL_64_after_hwframe.__mmap
3.84 ±104% -3.8 0.00 perf-profile.calltrace.cycles-pp.__mmap
0.00 +0.5 0.52 ± 3% perf-profile.calltrace.cycles-pp.__run_timers.run_timer_softirq.__do_softirq.__irq_exit_rcu.sysvec_apic_timer_interrupt
0.00 +0.5 0.52 ± 3% perf-profile.calltrace.cycles-pp.run_timer_softirq.__do_softirq.__irq_exit_rcu.sysvec_apic_timer_interrupt.asm_sysvec_apic_timer_interrupt
0.00 +0.6 0.59 ± 9% perf-profile.calltrace.cycles-pp.hrtimer_next_event_without.tick_nohz_get_sleep_length.menu_select.cpuidle_idle_call.do_idle
0.00 +0.7 0.71 ± 15% perf-profile.calltrace.cycles-pp._raw_spin_trylock.rebalance_domains.__do_softirq.__irq_exit_rcu.sysvec_apic_timer_interrupt
0.00 +0.8 0.77 ± 25% perf-profile.calltrace.cycles-pp.update_rq_clock_task.scheduler_tick.update_process_times.tick_sched_handle.tick_sched_timer
0.00 +0.8 0.79 ± 12% perf-profile.calltrace.cycles-pp.update_blocked_averages.run_rebalance_domains.__do_softirq.__irq_exit_rcu.sysvec_apic_timer_interrupt
0.00 +0.8 0.80 ± 23% perf-profile.calltrace.cycles-pp.__break_lease.do_dentry_open.dentry_open.__nfsd_open.nfsd_file_do_acquire
0.00 +0.8 0.80 ± 24% perf-profile.calltrace.cycles-pp.__nfsd_open.nfsd_file_do_acquire.nfsd4_commit.nfsd4_proc_compound.nfsd_dispatch
0.00 +0.8 0.80 ± 24% perf-profile.calltrace.cycles-pp.dentry_open.__nfsd_open.nfsd_file_do_acquire.nfsd4_commit.nfsd4_proc_compound
0.00 +0.8 0.80 ± 24% perf-profile.calltrace.cycles-pp.do_dentry_open.dentry_open.__nfsd_open.nfsd_file_do_acquire.nfsd4_commit
0.00 +0.8 0.80 ± 24% perf-profile.calltrace.cycles-pp.nfsd.kthread.ret_from_fork
0.00 +0.8 0.80 ± 24% perf-profile.calltrace.cycles-pp.svc_process.nfsd.kthread.ret_from_fork
0.00 +0.8 0.80 ± 24% perf-profile.calltrace.cycles-pp.svc_process_common.svc_process.nfsd.kthread.ret_from_fork
0.00 +0.8 0.80 ± 24% perf-profile.calltrace.cycles-pp.nfsd_dispatch.svc_process_common.svc_process.nfsd.kthread
0.00 +0.8 0.80 ± 24% perf-profile.calltrace.cycles-pp.nfsd4_proc_compound.nfsd_dispatch.svc_process_common.svc_process.nfsd
0.00 +0.8 0.80 ± 24% perf-profile.calltrace.cycles-pp.nfsd4_commit.nfsd4_proc_compound.nfsd_dispatch.svc_process_common.svc_process
0.00 +0.8 0.80 ± 24% perf-profile.calltrace.cycles-pp.nfsd_file_do_acquire.nfsd4_commit.nfsd4_proc_compound.nfsd_dispatch.svc_process_common
0.00 +0.8 0.81 ± 12% perf-profile.calltrace.cycles-pp.cpuidle_governor_latency_req.menu_select.cpuidle_idle_call.do_idle.cpu_startup_entry
0.00 +0.8 0.84 ± 12% perf-profile.calltrace.cycles-pp.run_rebalance_domains.__do_softirq.__irq_exit_rcu.sysvec_apic_timer_interrupt.asm_sysvec_apic_timer_interrupt
0.00 +0.9 0.86 ± 8% perf-profile.calltrace.cycles-pp.update_sg_lb_stats.update_sd_lb_stats.find_busiest_group.load_balance.rebalance_domains
0.00 +1.1 1.10 ± 20% perf-profile.calltrace.cycles-pp.tick_nohz_next_event.tick_nohz_get_sleep_length.menu_select.cpuidle_idle_call.do_idle
0.00 +1.1 1.11 ± 2% perf-profile.calltrace.cycles-pp.update_sd_lb_stats.find_busiest_group.load_balance.rebalance_domains.__do_softirq
0.00 +1.2 1.18 ± 5% perf-profile.calltrace.cycles-pp.find_busiest_group.load_balance.rebalance_domains.__do_softirq.__irq_exit_rcu
0.00 +1.3 1.27 ± 28% perf-profile.calltrace.cycles-pp.tick_sched_do_timer.tick_sched_timer.__hrtimer_run_queues.hrtimer_interrupt.__sysvec_apic_timer_interrupt
0.00 +1.4 1.38 ± 49% perf-profile.calltrace.cycles-pp.tick_irq_enter.irq_enter_rcu.sysvec_apic_timer_interrupt.asm_sysvec_apic_timer_interrupt.cpuidle_enter_state
0.00 +1.4 1.44 ± 46% perf-profile.calltrace.cycles-pp.irq_enter_rcu.sysvec_apic_timer_interrupt.asm_sysvec_apic_timer_interrupt.cpuidle_enter_state.cpuidle_enter
0.00 +1.4 1.45 ± 14% perf-profile.calltrace.cycles-pp.__intel_pmu_enable_all.perf_rotate_context.perf_mux_hrtimer_handler.__hrtimer_run_queues.hrtimer_interrupt
0.00 +1.5 1.51 ± 12% perf-profile.calltrace.cycles-pp.cpuidle_enter.cpuidle_idle_call.do_idle.cpu_startup_entry.rest_init
0.00 +1.6 1.56 perf-profile.calltrace.cycles-pp.load_balance.rebalance_domains.__do_softirq.__irq_exit_rcu.sysvec_apic_timer_interrupt
0.00 +1.6 1.59 ± 12% perf-profile.calltrace.cycles-pp.cpuidle_idle_call.do_idle.cpu_startup_entry.rest_init.arch_call_rest_init
0.00 +1.6 1.59 ± 5% perf-profile.calltrace.cycles-pp.lapic_next_deadline.clockevents_program_event.hrtimer_interrupt.__sysvec_apic_timer_interrupt.sysvec_apic_timer_interrupt
0.00 +1.6 1.61 ± 13% perf-profile.calltrace.cycles-pp.do_idle.cpu_startup_entry.rest_init.arch_call_rest_init.start_kernel
0.00 +1.6 1.62 ± 12% perf-profile.calltrace.cycles-pp.cpu_startup_entry.rest_init.arch_call_rest_init.start_kernel.x86_64_start_reservations
0.00 +1.6 1.62 ± 12% perf-profile.calltrace.cycles-pp.x86_64_start_kernel.secondary_startup_64_no_verify
0.00 +1.6 1.62 ± 12% perf-profile.calltrace.cycles-pp.x86_64_start_reservations.x86_64_start_kernel.secondary_startup_64_no_verify
0.00 +1.6 1.62 ± 12% perf-profile.calltrace.cycles-pp.start_kernel.x86_64_start_reservations.x86_64_start_kernel.secondary_startup_64_no_verify
0.00 +1.6 1.62 ± 12% perf-profile.calltrace.cycles-pp.arch_call_rest_init.start_kernel.x86_64_start_reservations.x86_64_start_kernel.secondary_startup_64_no_verify
0.00 +1.6 1.62 ± 12% perf-profile.calltrace.cycles-pp.rest_init.arch_call_rest_init.start_kernel.x86_64_start_reservations.x86_64_start_kernel
0.00 +1.7 1.72 ± 8% perf-profile.calltrace.cycles-pp.ret_from_fork
0.00 +1.7 1.72 ± 8% perf-profile.calltrace.cycles-pp.kthread.ret_from_fork
0.00 +1.9 1.86 ± 10% perf-profile.calltrace.cycles-pp.perf_rotate_context.perf_mux_hrtimer_handler.__hrtimer_run_queues.hrtimer_interrupt.__sysvec_apic_timer_interrupt
0.00 +2.0 1.97 ± 11% perf-profile.calltrace.cycles-pp.tick_nohz_get_sleep_length.menu_select.cpuidle_idle_call.do_idle.cpu_startup_entry
0.00 +2.2 2.22 ± 4% perf-profile.calltrace.cycles-pp.clockevents_program_event.hrtimer_interrupt.__sysvec_apic_timer_interrupt.sysvec_apic_timer_interrupt.asm_sysvec_apic_timer_interrupt
0.00 +2.6 2.56 ± 5% perf-profile.calltrace.cycles-pp.rebalance_domains.__do_softirq.__irq_exit_rcu.sysvec_apic_timer_interrupt.asm_sysvec_apic_timer_interrupt
0.00 +2.8 2.85 ± 7% perf-profile.calltrace.cycles-pp.perf_mux_hrtimer_handler.__hrtimer_run_queues.hrtimer_interrupt.__sysvec_apic_timer_interrupt.sysvec_apic_timer_interrupt
0.00 +3.1 3.10 ± 8% perf-profile.calltrace.cycles-pp.arch_scale_freq_tick.scheduler_tick.update_process_times.tick_sched_handle.tick_sched_timer
0.00 +4.4 4.40 ± 6% perf-profile.calltrace.cycles-pp.menu_select.cpuidle_idle_call.do_idle.cpu_startup_entry.start_secondary
0.00 +4.6 4.62 ± 5% perf-profile.calltrace.cycles-pp.__do_softirq.__irq_exit_rcu.sysvec_apic_timer_interrupt.asm_sysvec_apic_timer_interrupt.cpuidle_enter_state
0.88 ±223% +4.7 5.61 ± 11% perf-profile.calltrace.cycles-pp.scheduler_tick.update_process_times.tick_sched_handle.tick_sched_timer.__hrtimer_run_queues
0.00 +5.5 5.51 ± 4% perf-profile.calltrace.cycles-pp.__irq_exit_rcu.sysvec_apic_timer_interrupt.asm_sysvec_apic_timer_interrupt.cpuidle_enter_state.cpuidle_enter
2.36 ±100% +5.6 7.96 ± 9% perf-profile.calltrace.cycles-pp.update_process_times.tick_sched_handle.tick_sched_timer.__hrtimer_run_queues.hrtimer_interrupt
2.36 ±100% +5.9 8.22 ± 9% perf-profile.calltrace.cycles-pp.tick_sched_handle.tick_sched_timer.__hrtimer_run_queues.hrtimer_interrupt.__sysvec_apic_timer_interrupt
2.36 ±100% +8.6 10.93 ± 6% perf-profile.calltrace.cycles-pp.tick_sched_timer.__hrtimer_run_queues.hrtimer_interrupt.__sysvec_apic_timer_interrupt.sysvec_apic_timer_interrupt
2.36 ±100% +13.5 15.87 ± 4% perf-profile.calltrace.cycles-pp.__hrtimer_run_queues.hrtimer_interrupt.__sysvec_apic_timer_interrupt.sysvec_apic_timer_interrupt.asm_sysvec_apic_timer_interrupt
0.88 ±223% +21.2 22.07 ± 4% perf-profile.calltrace.cycles-pp.hrtimer_interrupt.__sysvec_apic_timer_interrupt.sysvec_apic_timer_interrupt.asm_sysvec_apic_timer_interrupt.cpuidle_enter_state
0.88 ±223% +21.3 22.22 ± 4% perf-profile.calltrace.cycles-pp.__sysvec_apic_timer_interrupt.sysvec_apic_timer_interrupt.asm_sysvec_apic_timer_interrupt.cpuidle_enter_state.cpuidle_enter
0.88 ±223% +30.2 31.04 ± 5% perf-profile.calltrace.cycles-pp.sysvec_apic_timer_interrupt.asm_sysvec_apic_timer_interrupt.cpuidle_enter_state.cpuidle_enter.cpuidle_idle_call
0.88 ±223% +38.9 39.74 perf-profile.calltrace.cycles-pp.asm_sysvec_apic_timer_interrupt.cpuidle_enter_state.cpuidle_enter.cpuidle_idle_call.do_idle
0.00 +42.2 42.16 perf-profile.calltrace.cycles-pp.intel_idle.cpuidle_enter_state.cpuidle_enter.cpuidle_idle_call.do_idle
0.88 ±223% +81.5 82.34 perf-profile.calltrace.cycles-pp.cpuidle_enter_state.cpuidle_enter.cpuidle_idle_call.do_idle.cpu_startup_entry
0.88 ±223% +82.4 83.24 perf-profile.calltrace.cycles-pp.cpuidle_enter.cpuidle_idle_call.do_idle.cpu_startup_entry.start_secondary
2.48 ±158% +87.7 90.16 perf-profile.calltrace.cycles-pp.do_idle.cpu_startup_entry.start_secondary.secondary_startup_64_no_verify
2.48 ±158% +87.9 90.37 perf-profile.calltrace.cycles-pp.cpu_startup_entry.start_secondary.secondary_startup_64_no_verify
2.48 ±158% +87.9 90.38 perf-profile.calltrace.cycles-pp.start_secondary.secondary_startup_64_no_verify
0.88 ±223% +88.1 88.95 perf-profile.calltrace.cycles-pp.cpuidle_idle_call.do_idle.cpu_startup_entry.start_secondary.secondary_startup_64_no_verify
2.48 ±158% +89.5 92.00 perf-profile.calltrace.cycles-pp.secondary_startup_64_no_verify
74.91 ± 19% -71.0 3.92 ± 2% perf-profile.children.cycles-pp.do_syscall_64
74.91 ± 19% -71.0 3.94 ± 2% perf-profile.children.cycles-pp.entry_SYSCALL_64_after_hwframe
28.80 ± 65% -28.5 0.29 ± 33% perf-profile.children.cycles-pp.zap_pte_range
28.80 ± 65% -28.5 0.31 ± 36% perf-profile.children.cycles-pp.unmap_page_range
28.80 ± 65% -28.5 0.31 ± 36% perf-profile.children.cycles-pp.zap_pmd_range
28.80 ± 65% -28.5 0.33 ± 29% perf-profile.children.cycles-pp.unmap_vmas
23.24 ± 82% -23.2 0.00 perf-profile.children.cycles-pp.arch_do_signal_or_restart
23.24 ± 82% -23.2 0.00 perf-profile.children.cycles-pp.get_signal
7.90 ± 90% -7.9 0.01 ±173% perf-profile.children.cycles-pp.record__mmap_read_evlist
6.39 ± 87% -6.4 0.01 ±173% perf-profile.children.cycles-pp.generic_perform_write
6.39 ± 87% -6.4 0.01 ±173% perf-profile.children.cycles-pp.perf_mmap__push
6.39 ± 87% -6.4 0.01 ±173% perf-profile.children.cycles-pp.record__pushfn
6.39 ± 87% -6.4 0.01 ±173% perf-profile.children.cycles-pp.writen
6.39 ± 87% -6.4 0.01 ±173% perf-profile.children.cycles-pp.__libc_write
6.39 ± 87% -6.4 0.02 ±173% perf-profile.children.cycles-pp.__generic_file_write_iter
6.39 ± 87% -6.4 0.02 ±173% perf-profile.children.cycles-pp.generic_file_write_iter
0.00 +0.1 0.06 ± 17% perf-profile.children.cycles-pp.unlink_anon_vmas
0.00 +0.1 0.06 ± 31% perf-profile.children.cycles-pp.__get_user_pages
0.00 +0.1 0.07 ± 28% perf-profile.children.cycles-pp.cpuidle_not_available
0.00 +0.1 0.07 ± 28% perf-profile.children.cycles-pp.__lookup_slow
0.00 +0.1 0.07 ± 26% perf-profile.children.cycles-pp.check_move_unevictable_folios
0.00 +0.1 0.07 ± 26% perf-profile.children.cycles-pp.check_move_unevictable_pages
0.00 +0.1 0.07 ± 14% perf-profile.children.cycles-pp.__percpu_counter_init
0.00 +0.1 0.07 ± 26% perf-profile.children.cycles-pp.get_arg_page
0.00 +0.1 0.07 ± 26% perf-profile.children.cycles-pp.get_user_pages_remote
0.00 +0.1 0.08 ± 26% perf-profile.children.cycles-pp.drm_gem_shmem_put_pages_locked
0.00 +0.1 0.08 ± 26% perf-profile.children.cycles-pp.drm_gem_put_pages
0.00 +0.1 0.08 ± 26% perf-profile.children.cycles-pp.drm_gem_check_release_pagevec
0.00 +0.1 0.08 ± 23% perf-profile.children.cycles-pp.hrtimer_run_queues
0.00 +0.1 0.08 ± 45% perf-profile.children.cycles-pp.__x64_sys_mprotect
0.00 +0.1 0.08 ± 45% perf-profile.children.cycles-pp.do_mprotect_pkey
0.00 +0.1 0.09 ± 33% perf-profile.children.cycles-pp.lookup_fast
0.00 +0.1 0.09 ± 29% perf-profile.children.cycles-pp.rcu_do_batch
0.00 +0.1 0.09 ± 32% perf-profile.children.cycles-pp.can_stop_idle_tick
0.00 +0.1 0.09 ± 32% perf-profile.children.cycles-pp.__vmalloc_node_range
0.00 +0.1 0.09 ± 32% perf-profile.children.cycles-pp.alloc_thread_stack_node
0.00 +0.1 0.09 ± 20% perf-profile.children.cycles-pp.perf_read
0.00 +0.1 0.10 ± 26% perf-profile.children.cycles-pp.perf_evsel__read
0.00 +0.1 0.10 ± 21% perf-profile.children.cycles-pp.menu_reflect
0.00 +0.1 0.10 ± 21% perf-profile.children.cycles-pp.alloc_bprm
0.00 +0.1 0.10 ± 12% perf-profile.children.cycles-pp.vma_interval_tree_insert
0.00 +0.1 0.10 ± 27% perf-profile.children.cycles-pp.nohz_balancer_kick
0.00 +0.1 0.10 ± 18% perf-profile.children.cycles-pp.mm_init
0.00 +0.1 0.10 ± 14% perf-profile.children.cycles-pp.note_gp_changes
0.00 +0.1 0.11 ± 21% perf-profile.children.cycles-pp.cpuidle_reflect
0.00 +0.1 0.11 ± 9% perf-profile.children.cycles-pp.dup_task_struct
0.00 +0.1 0.11 ± 52% perf-profile.children.cycles-pp.find_idlest_cpu
0.00 +0.1 0.11 ± 52% perf-profile.children.cycles-pp.find_idlest_group
0.00 +0.1 0.12 ± 11% perf-profile.children.cycles-pp.drm_gem_vunmap_unlocked
0.00 +0.1 0.12 ± 11% perf-profile.children.cycles-pp.drm_gem_vunmap
0.00 +0.1 0.12 ± 11% perf-profile.children.cycles-pp.drm_gem_shmem_vunmap
0.00 +0.1 0.12 ± 38% perf-profile.children.cycles-pp.irq_work_run_list
0.00 +0.1 0.12 ± 13% perf-profile.children.cycles-pp.__switch_to_asm
0.00 +0.1 0.13 ± 11% perf-profile.children.cycles-pp.pipe_read
0.00 +0.1 0.14 ± 8% perf-profile.children.cycles-pp.exec_mmap
0.00 +0.1 0.14 ± 44% perf-profile.children.cycles-pp.tsc_verify_tsc_adjust
0.00 +0.1 0.14 ± 14% perf-profile.children.cycles-pp.irqentry_exit
0.00 +0.1 0.14 ± 14% perf-profile.children.cycles-pp.walk_component
0.00 +0.1 0.14 ± 39% perf-profile.children.cycles-pp.pick_next_task_fair
0.00 +0.1 0.15 ± 25% perf-profile.children.cycles-pp.smpboot_thread_fn
0.00 +0.2 0.15 ± 51% perf-profile.children.cycles-pp.update_wall_time
0.00 +0.2 0.15 ± 51% perf-profile.children.cycles-pp.timekeeping_advance
0.00 +0.2 0.16 ± 47% perf-profile.children.cycles-pp.copy_strings
0.00 +0.2 0.16 ± 16% perf-profile.children.cycles-pp.ct_kernel_exit
0.00 +0.2 0.16 ± 40% perf-profile.children.cycles-pp.select_task_rq_fair
0.00 +0.2 0.16 ± 33% perf-profile.children.cycles-pp.perf_event_task_tick
0.00 +0.2 0.16 ± 4% perf-profile.children.cycles-pp.__libc_read
0.00 +0.2 0.16 ± 28% perf-profile.children.cycles-pp.__x64_sys_vfork
0.00 +0.2 0.16 ± 19% perf-profile.children.cycles-pp.intel_idle_irq
0.00 +0.2 0.16 ± 22% perf-profile.children.cycles-pp.___perf_sw_event
0.00 +0.2 0.16 ± 28% perf-profile.children.cycles-pp.update_rt_rq_load_avg
0.00 +0.2 0.16 ± 39% perf-profile.children.cycles-pp.arch_cpu_idle_enter
0.00 +0.2 0.16 ± 16% perf-profile.children.cycles-pp._find_next_and_bit
0.00 +0.2 0.16 ± 24% perf-profile.children.cycles-pp.evsel__read_counter
0.00 +0.2 0.17 ± 13% perf-profile.children.cycles-pp.irqtime_account_process_tick
0.00 +0.2 0.17 ± 49% perf-profile.children.cycles-pp.shmem_read_folio_gfp
0.00 +0.2 0.17 ± 33% perf-profile.children.cycles-pp.rb_erase
0.00 +0.2 0.18 ± 31% perf-profile.children.cycles-pp.drm_atomic_commit
0.00 +0.2 0.18 ± 31% perf-profile.children.cycles-pp.drm_atomic_helper_commit
0.00 +0.2 0.18 ± 16% perf-profile.children.cycles-pp.hrtimer_forward
0.00 +0.2 0.18 ± 4% perf-profile.children.cycles-pp.readn
0.00 +0.2 0.18 ± 30% perf-profile.children.cycles-pp.sched_clock
0.00 +0.2 0.18 ± 27% perf-profile.children.cycles-pp.drm_atomic_helper_dirtyfb
0.00 +0.2 0.19 ± 28% perf-profile.children.cycles-pp.check_cpu_stall
0.00 +0.2 0.19 ± 30% perf-profile.children.cycles-pp.begin_new_exec
0.00 +0.2 0.19 ± 20% perf-profile.children.cycles-pp.tick_nohz_tick_stopped
0.00 +0.2 0.19 ± 19% perf-profile.children.cycles-pp.__vfork
0.00 +0.2 0.20 ± 34% perf-profile.children.cycles-pp.drm_gem_shmem_get_pages
0.00 +0.2 0.20 ± 34% perf-profile.children.cycles-pp.drm_gem_shmem_get_pages_locked
0.00 +0.2 0.20 ± 34% perf-profile.children.cycles-pp.drm_gem_get_pages
0.00 +0.2 0.20 ± 34% perf-profile.children.cycles-pp.shmem_read_mapping_page_gfp
0.00 +0.2 0.20 ± 18% perf-profile.children.cycles-pp.rb_next
0.00 +0.2 0.20 ± 39% perf-profile.children.cycles-pp.drm_gem_vmap_unlocked
0.00 +0.2 0.20 ± 39% perf-profile.children.cycles-pp.drm_gem_vmap
0.00 +0.2 0.20 ± 39% perf-profile.children.cycles-pp.drm_gem_shmem_object_vmap
0.00 +0.2 0.20 ± 39% perf-profile.children.cycles-pp.drm_gem_shmem_vmap_locked
0.00 +0.2 0.21 ± 45% perf-profile.children.cycles-pp.run_posix_cpu_timers
0.00 +0.2 0.21 ± 20% perf-profile.children.cycles-pp.tick_program_event
0.00 +0.2 0.22 ±102% perf-profile.children.cycles-pp.tick_check_oneshot_broadcast_this_cpu
0.00 +0.2 0.23 ± 23% perf-profile.children.cycles-pp.ttwu_do_activate
0.00 +0.2 0.23 ± 25% perf-profile.children.cycles-pp.arch_cpu_idle_exit
0.00 +0.2 0.24 ± 15% perf-profile.children.cycles-pp.rb_insert_color
0.00 +0.2 0.24 ± 45% perf-profile.children.cycles-pp.irqentry_enter
0.00 +0.3 0.26 ± 7% perf-profile.children.cycles-pp._raw_spin_unlock_irqrestore
0.00 +0.3 0.27 ± 16% perf-profile.children.cycles-pp.rcu_core
0.00 +0.3 0.29 ± 9% perf-profile.children.cycles-pp.error_entry
0.00 +0.3 0.31 ± 15% perf-profile.children.cycles-pp.call_cpuidle
0.00 +0.3 0.33 ± 22% perf-profile.children.cycles-pp.drm_fb_helper_damage_work
0.00 +0.3 0.33 ± 22% perf-profile.children.cycles-pp.drm_fbdev_generic_helper_fb_dirty
0.00 +0.3 0.33 ± 27% perf-profile.children.cycles-pp.sched_clock_idle_wakeup_event
0.00 +0.3 0.33 ± 18% perf-profile.children.cycles-pp.ct_kernel_exit_state
0.00 +0.3 0.35 ± 47% perf-profile.children.cycles-pp.ct_kernel_enter
0.00 +0.3 0.35 ± 17% perf-profile.children.cycles-pp.hrtimer_update_next_event
0.00 +0.4 0.37 ± 16% perf-profile.children.cycles-pp.idle_cpu
0.00 +0.4 0.40 ± 30% perf-profile.children.cycles-pp.hrtimer_get_next_event
0.00 +0.4 0.40 ± 15% perf-profile.children.cycles-pp.irq_work_tick
0.00 +0.4 0.40 ± 20% perf-profile.children.cycles-pp.timerqueue_del
0.00 +0.4 0.43 ± 10% perf-profile.children.cycles-pp.call_timer_fn
0.00 +0.4 0.44 ± 7% perf-profile.children.cycles-pp.read_counters
0.00 +0.4 0.44 ± 45% perf-profile.children.cycles-pp.ct_idle_exit
0.00 +0.5 0.48 ± 8% perf-profile.children.cycles-pp.cmd_stat
0.00 +0.5 0.48 ± 8% perf-profile.children.cycles-pp.dispatch_events
0.00 +0.5 0.48 ± 8% perf-profile.children.cycles-pp.process_interval
0.00 +0.5 0.50 ± 18% perf-profile.children.cycles-pp.process_one_work
0.00 +0.5 0.51 ± 23% perf-profile.children.cycles-pp.__hrtimer_next_event_base
0.00 +0.5 0.51 ± 10% perf-profile.children.cycles-pp._raw_spin_lock_irq
0.00 +0.5 0.52 ± 3% perf-profile.children.cycles-pp.run_timer_softirq
0.00 +0.5 0.52 ± 27% perf-profile.children.cycles-pp.schedule_timeout
0.00 +0.5 0.53 ± 16% perf-profile.children.cycles-pp.worker_thread
0.00 +0.5 0.53 ± 28% perf-profile.children.cycles-pp.timerqueue_add
0.00 +0.6 0.56 ± 18% perf-profile.children.cycles-pp.schedule
0.00 +0.6 0.58 ± 3% perf-profile.children.cycles-pp.__run_timers
0.00 +0.6 0.58 ± 18% perf-profile.children.cycles-pp.get_cpu_device
0.00 +0.6 0.59 ± 14% perf-profile.children.cycles-pp.tick_nohz_irq_exit
0.00 +0.6 0.60 ± 16% perf-profile.children.cycles-pp.update_rq_clock
0.00 +0.6 0.62 ± 61% perf-profile.children.cycles-pp.calc_global_load_tick
0.00 +0.7 0.66 ± 21% perf-profile.children.cycles-pp.enqueue_hrtimer
0.00 +0.7 0.66 ± 17% perf-profile.children.cycles-pp.local_clock
0.00 +0.7 0.68 ± 7% perf-profile.children.cycles-pp.hrtimer_next_event_without
0.00 +0.7 0.70 ± 24% perf-profile.children.cycles-pp.get_next_timer_interrupt
0.00 +0.7 0.74 ± 15% perf-profile.children.cycles-pp._raw_spin_trylock
0.00 +0.7 0.75 ± 29% perf-profile.children.cycles-pp.update_irq_load_avg
0.00 +0.8 0.80 ± 24% perf-profile.children.cycles-pp.__nfsd_open
0.00 +0.8 0.80 ± 24% perf-profile.children.cycles-pp.dentry_open
0.00 +0.8 0.80 ± 24% perf-profile.children.cycles-pp.__break_lease
0.00 +0.8 0.80 ± 24% perf-profile.children.cycles-pp.nfsd
0.00 +0.8 0.80 ± 24% perf-profile.children.cycles-pp.svc_process
0.00 +0.8 0.80 ± 24% perf-profile.children.cycles-pp.svc_process_common
0.00 +0.8 0.80 ± 24% perf-profile.children.cycles-pp.nfsd_dispatch
0.00 +0.8 0.80 ± 24% perf-profile.children.cycles-pp.nfsd4_proc_compound
0.00 +0.8 0.80 ± 24% perf-profile.children.cycles-pp.nfsd4_commit
0.00 +0.8 0.80 ± 24% perf-profile.children.cycles-pp.nfsd_file_do_acquire
0.00 +0.8 0.81 ± 13% perf-profile.children.cycles-pp.update_blocked_averages
0.00 +0.8 0.82 ± 25% perf-profile.children.cycles-pp.do_dentry_open
0.00 +0.8 0.84 ± 12% perf-profile.children.cycles-pp.run_rebalance_domains
0.00 +0.9 0.89 ± 15% perf-profile.children.cycles-pp.cpuidle_governor_latency_req
0.00 +1.0 0.98 ± 8% perf-profile.children.cycles-pp.update_sg_lb_stats
0.00 +1.0 0.98 ± 24% perf-profile.children.cycles-pp.update_rq_clock_task
0.00 +1.1 1.08 ± 9% perf-profile.children.cycles-pp.irqtime_account_irq
0.00 +1.2 1.16 ± 17% perf-profile.children.cycles-pp.tick_nohz_next_event
0.00 +1.2 1.17 ± 14% perf-profile.children.cycles-pp._raw_spin_lock_irqsave
0.00 +1.3 1.25 ± 2% perf-profile.children.cycles-pp.update_sd_lb_stats
0.00 +1.3 1.31 ± 2% perf-profile.children.cycles-pp.find_busiest_group
0.00 +1.4 1.35 ± 28% perf-profile.children.cycles-pp.tick_sched_do_timer
0.00 +1.5 1.46 ± 48% perf-profile.children.cycles-pp.tick_irq_enter
0.00 +1.5 1.46 ± 3% perf-profile.children.cycles-pp.sched_clock_cpu
0.00 +1.5 1.51 ± 12% perf-profile.children.cycles-pp.__intel_pmu_enable_all
0.00 +1.5 1.53 ± 42% perf-profile.children.cycles-pp.irq_enter_rcu
0.00 +1.6 1.62 ± 12% perf-profile.children.cycles-pp.x86_64_start_kernel
0.00 +1.6 1.62 ± 12% perf-profile.children.cycles-pp.x86_64_start_reservations
0.00 +1.6 1.62 ± 12% perf-profile.children.cycles-pp.start_kernel
0.00 +1.6 1.62 ± 12% perf-profile.children.cycles-pp.arch_call_rest_init
0.00 +1.6 1.62 ± 12% perf-profile.children.cycles-pp.rest_init
0.00 +1.7 1.70 ± 7% perf-profile.children.cycles-pp.native_sched_clock
0.00 +1.7 1.70 ± 3% perf-profile.children.cycles-pp.load_balance
0.00 +1.7 1.71 ± 5% perf-profile.children.cycles-pp.lapic_next_deadline
0.00 +1.7 1.72 ± 8% perf-profile.children.cycles-pp.kthread
0.00 +1.8 1.76 ± 7% perf-profile.children.cycles-pp.ret_from_fork
0.00 +1.9 1.95 ± 16% perf-profile.children.cycles-pp.read_tsc
0.00 +2.0 1.98 ± 9% perf-profile.children.cycles-pp.perf_rotate_context
0.00 +2.0 2.04 ± 11% perf-profile.children.cycles-pp.tick_nohz_get_sleep_length
0.00 +2.3 2.30 ± 5% perf-profile.children.cycles-pp.clockevents_program_event
0.00 +2.6 2.60 ± 6% perf-profile.children.cycles-pp.rebalance_domains
0.00 +3.0 2.99 ± 7% perf-profile.children.cycles-pp.perf_mux_hrtimer_handler
0.00 +3.2 3.18 ± 7% perf-profile.children.cycles-pp.arch_scale_freq_tick
0.00 +3.9 3.92 ± 31% perf-profile.children.cycles-pp.ktime_get
0.00 +4.5 4.53 ± 6% perf-profile.children.cycles-pp.menu_select
0.00 +4.7 4.71 ± 6% perf-profile.children.cycles-pp.__do_softirq
0.88 ±223% +5.0 5.86 ± 11% perf-profile.children.cycles-pp.scheduler_tick
0.00 +5.6 5.64 ± 4% perf-profile.children.cycles-pp.__irq_exit_rcu
2.36 ±100% +5.8 8.15 ± 9% perf-profile.children.cycles-pp.update_process_times
2.36 ±100% +6.0 8.35 ± 9% perf-profile.children.cycles-pp.tick_sched_handle
2.36 ±100% +8.8 11.21 ± 6% perf-profile.children.cycles-pp.tick_sched_timer
2.36 ±100% +13.9 16.30 ± 3% perf-profile.children.cycles-pp.__hrtimer_run_queues
3.24 ±117% +19.3 22.55 ± 4% perf-profile.children.cycles-pp.hrtimer_interrupt
3.24 ±117% +19.4 22.66 ± 4% perf-profile.children.cycles-pp.__sysvec_apic_timer_interrupt
3.24 ±117% +28.0 31.28 ± 4% perf-profile.children.cycles-pp.sysvec_apic_timer_interrupt
3.24 ±117% +32.7 35.97 ± 2% perf-profile.children.cycles-pp.asm_sysvec_apic_timer_interrupt
0.00 +42.2 42.16 perf-profile.children.cycles-pp.intel_idle
0.88 ±223% +83.5 84.34 perf-profile.children.cycles-pp.cpuidle_enter_state
0.88 ±223% +83.9 84.78 perf-profile.children.cycles-pp.cpuidle_enter
2.48 ±158% +87.9 90.38 perf-profile.children.cycles-pp.start_secondary
2.48 ±158% +89.5 92.00 perf-profile.children.cycles-pp.secondary_startup_64_no_verify
2.48 ±158% +89.5 92.00 perf-profile.children.cycles-pp.cpu_startup_entry
2.48 ±158% +89.5 92.00 perf-profile.children.cycles-pp.do_idle
0.88 ±223% +89.8 90.65 perf-profile.children.cycles-pp.cpuidle_idle_call
0.00 +0.1 0.06 ± 20% perf-profile.self.cycles-pp.shmem_get_folio_gfp
0.00 +0.1 0.07 ± 19% perf-profile.self.cycles-pp.tick_irq_enter
0.00 +0.1 0.07 ± 26% perf-profile.self.cycles-pp.check_move_unevictable_folios
0.00 +0.1 0.07 ± 33% perf-profile.self.cycles-pp.perf_event_task_tick
0.00 +0.1 0.08 ± 19% perf-profile.self.cycles-pp.hrtimer_run_queues
0.00 +0.1 0.08 ± 14% perf-profile.self.cycles-pp.irqentry_exit
0.00 +0.1 0.08 ± 21% perf-profile.self.cycles-pp.hrtimer_get_next_event
0.00 +0.1 0.08 ± 26% perf-profile.self.cycles-pp.can_stop_idle_tick
0.00 +0.1 0.09 ± 29% perf-profile.self.cycles-pp.irqentry_enter
0.00 +0.1 0.09 ± 43% perf-profile.self.cycles-pp.update_blocked_averages
0.00 +0.1 0.10 ± 12% perf-profile.self.cycles-pp.vma_interval_tree_insert
0.00 +0.1 0.10 ± 34% perf-profile.self.cycles-pp.irq_work_run_list
0.00 +0.1 0.10 ± 43% perf-profile.self.cycles-pp.ct_kernel_exit
0.00 +0.1 0.10 ± 41% perf-profile.self.cycles-pp.__sysvec_apic_timer_interrupt
0.00 +0.1 0.11 ± 13% perf-profile.self.cycles-pp.tick_sched_timer
0.00 +0.1 0.11 ± 42% perf-profile.self.cycles-pp.filemap_map_pages
0.00 +0.1 0.12 ± 26% perf-profile.self.cycles-pp.rb_erase
0.00 +0.1 0.12 ± 56% perf-profile.self.cycles-pp.tsc_verify_tsc_adjust
0.00 +0.1 0.12 ± 13% perf-profile.self.cycles-pp.__switch_to_asm
0.00 +0.1 0.13 ± 21% perf-profile.self.cycles-pp.hrtimer_update_next_event
0.00 +0.1 0.13 ± 36% perf-profile.self.cycles-pp.hrtimer_forward
0.00 +0.1 0.13 ± 25% perf-profile.self.cycles-pp.rb_next
0.00 +0.1 0.13 ± 42% perf-profile.self.cycles-pp.update_rq_clock
0.00 +0.1 0.14 ± 34% perf-profile.self.cycles-pp.sysvec_apic_timer_interrupt
0.00 +0.1 0.14 ± 23% perf-profile.self.cycles-pp.enqueue_hrtimer
0.00 +0.1 0.14 ± 32% perf-profile.self.cycles-pp.tick_nohz_get_sleep_length
0.00 +0.1 0.14 ± 15% perf-profile.self.cycles-pp.rcu_sched_clock_irq
0.00 +0.1 0.15 ± 40% perf-profile.self.cycles-pp.clockevents_program_event
0.00 +0.1 0.15 ± 23% perf-profile.self.cycles-pp.tick_nohz_tick_stopped
0.00 +0.2 0.15 ± 21% perf-profile.self.cycles-pp.___perf_sw_event
0.00 +0.2 0.16 ± 14% perf-profile.self.cycles-pp.irqtime_account_process_tick
0.00 +0.2 0.16 ± 19% perf-profile.self.cycles-pp._find_next_and_bit
0.00 +0.2 0.16 ± 23% perf-profile.self.cycles-pp._raw_spin_unlock_irqrestore
0.00 +0.2 0.16 ± 25% perf-profile.self.cycles-pp.update_rt_rq_load_avg
0.00 +0.2 0.16 ± 19% perf-profile.self.cycles-pp.intel_idle_irq
0.00 +0.2 0.16 ± 17% perf-profile.self.cycles-pp.hrtimer_next_event_without
0.00 +0.2 0.18 ± 45% perf-profile.self.cycles-pp.timerqueue_del
0.00 +0.2 0.18 ± 27% perf-profile.self.cycles-pp.check_cpu_stall
0.00 +0.2 0.18 ± 36% perf-profile.self.cycles-pp.update_sd_lb_stats
0.00 +0.2 0.19 ± 44% perf-profile.self.cycles-pp.run_posix_cpu_timers
0.00 +0.2 0.19 ± 18% perf-profile.self.cycles-pp.perf_mux_hrtimer_handler
0.00 +0.2 0.20 ± 47% perf-profile.self.cycles-pp.__do_softirq
0.00 +0.2 0.20 ± 23% perf-profile.self.cycles-pp.tick_program_event
0.00 +0.2 0.20 ± 26% perf-profile.self.cycles-pp.rb_insert_color
0.00 +0.2 0.20 ± 8% perf-profile.self.cycles-pp.sched_clock_cpu
0.00 +0.2 0.21 ± 41% perf-profile.self.cycles-pp.get_next_timer_interrupt
0.00 +0.2 0.21 ± 60% perf-profile.self.cycles-pp.ct_kernel_enter
0.00 +0.2 0.22 ±102% perf-profile.self.cycles-pp.tick_check_oneshot_broadcast_this_cpu
0.00 +0.2 0.25 ± 26% perf-profile.self.cycles-pp.update_rq_clock_task
0.00 +0.2 0.25 ± 26% perf-profile.self.cycles-pp.__irq_exit_rcu
0.00 +0.3 0.26 ± 38% perf-profile.self.cycles-pp.rebalance_domains
0.00 +0.3 0.26 ± 33% perf-profile.self.cycles-pp.cpuidle_governor_latency_req
0.00 +0.3 0.26 ± 25% perf-profile.self.cycles-pp.perf_rotate_context
0.00 +0.3 0.28 ± 20% perf-profile.self.cycles-pp.tick_nohz_next_event
0.00 +0.3 0.29 ± 9% perf-profile.self.cycles-pp.error_entry
0.00 +0.3 0.29 ± 38% perf-profile.self.cycles-pp.hrtimer_interrupt
0.00 +0.3 0.30 ± 18% perf-profile.self.cycles-pp.call_cpuidle
0.00 +0.3 0.30 ± 16% perf-profile.self.cycles-pp.load_balance
0.00 +0.3 0.31 ± 22% perf-profile.self.cycles-pp.ct_kernel_exit_state
0.00 +0.3 0.32 ± 20% perf-profile.self.cycles-pp.irqtime_account_irq
0.00 +0.3 0.32 ± 31% perf-profile.self.cycles-pp.timerqueue_add
0.00 +0.3 0.32 ± 28% perf-profile.self.cycles-pp.sched_clock_idle_wakeup_event
0.00 +0.4 0.35 ± 20% perf-profile.self.cycles-pp.scheduler_tick
0.00 +0.4 0.36 ± 16% perf-profile.self.cycles-pp.idle_cpu
0.00 +0.4 0.38 ± 17% perf-profile.self.cycles-pp.irq_work_tick
0.00 +0.4 0.39 ± 20% perf-profile.self.cycles-pp.cpuidle_enter
0.00 +0.4 0.40 ± 18% perf-profile.self.cycles-pp.__hrtimer_next_event_base
0.00 +0.4 0.45 ± 36% perf-profile.self.cycles-pp.do_idle
0.00 +0.5 0.50 ± 7% perf-profile.self.cycles-pp._raw_spin_lock_irq
0.00 +0.5 0.51 ± 18% perf-profile.self.cycles-pp.__hrtimer_run_queues
0.00 +0.5 0.53 ± 17% perf-profile.self.cycles-pp.get_cpu_device
0.00 +0.5 0.54 ± 8% perf-profile.self.cycles-pp.update_process_times
0.00 +0.6 0.56 ± 27% perf-profile.self.cycles-pp.asm_sysvec_apic_timer_interrupt
0.00 +0.6 0.62 ± 61% perf-profile.self.cycles-pp.calc_global_load_tick
0.00 +0.7 0.69 ± 16% perf-profile.self.cycles-pp.cpuidle_idle_call
0.00 +0.7 0.73 ± 14% perf-profile.self.cycles-pp._raw_spin_trylock
0.00 +0.7 0.73 ± 29% perf-profile.self.cycles-pp.update_irq_load_avg
0.00 +0.7 0.74 ± 9% perf-profile.self.cycles-pp.update_sg_lb_stats
0.00 +1.1 1.10 ± 14% perf-profile.self.cycles-pp._raw_spin_lock_irqsave
0.00 +1.2 1.18 ± 31% perf-profile.self.cycles-pp.tick_sched_do_timer
0.00 +1.4 1.45 ± 9% perf-profile.self.cycles-pp.menu_select
0.00 +1.5 1.51 ± 12% perf-profile.self.cycles-pp.__intel_pmu_enable_all
0.00 +1.6 1.63 ± 9% perf-profile.self.cycles-pp.native_sched_clock
0.00 +1.7 1.70 ± 4% perf-profile.self.cycles-pp.lapic_next_deadline
0.00 +1.9 1.93 ± 16% perf-profile.self.cycles-pp.read_tsc
0.00 +2.3 2.30 ± 52% perf-profile.self.cycles-pp.ktime_get
0.00 +3.2 3.18 ± 7% perf-profile.self.cycles-pp.arch_scale_freq_tick
0.00 +5.8 5.84 ± 9% perf-profile.self.cycles-pp.cpuidle_enter_state
0.00 +42.2 42.16 perf-profile.self.cycles-pp.intel_idle


***************************************************************************************************
lkp-icl-2sp7: 64 threads 2 sockets Intel(R) Xeon(R) Gold 6346 CPU @ 3.10GHz (Ice Lake) with 256G memory
=========================================================================================
compiler/cpufreq_governor/debug-setup/disk/filesize/fs2/fs/iterations/kconfig/nr_directories/nr_files_per_directory/nr_threads/rootfs/sync_method/tbox_group/test_size/testcase:
gcc-12/performance/no-monitor/1SSD/16MB/nfsv4/btrfs/1x/x86_64-rhel-8.3/16d/256fpd/32t/debian-11.1-x86_64-20220510.cgz/NoSync/lkp-icl-2sp7/20G/fsmark

commit:
3434d7aa77 ("NFSD: Clean up nfsctl_transaction_write()")
39d432fc76 ("NFSD: trace nfsctl operations")

3434d7aa77d24c5c 39d432fc76301cf0a0c45402211
---------------- ---------------------------
%stddev %change %stddev
\ | \
7488368 ± 2% +7979.7% 6.05e+08 ± 47% fsmark.app_overhead
62.42 ± 2% -100.0% 0.00 fsmark.files_per_sec
22.46 ± 2% +15766.1% 3564 ± 5% fsmark.time.elapsed_time
22.46 ± 2% +15766.1% 3564 ± 5% fsmark.time.elapsed_time.max
192.33 -99.5% 1.00 fsmark.time.percent_of_cpu_this_job_got
43.05 +13.7% 48.94 ± 4% fsmark.time.system_time
978884 +2.9% 1007574 ± 2% fsmark.time.voluntary_context_switches



Disclaimer:
Results have been estimated based on internal Intel analysis and are provided
for informational purposes only. Any difference in system hardware or software
design or configuration may affect actual performance.


--
0-DAY CI Kernel Test Service
https://github.com/intel/lkp-tests/wiki