October 2018 - LKP - Ml01.01.Org

[null_blk] e50b1e327a: blktests.block.022.fail

by kernel test robot

FYI, we noticed the following commit (built with gcc-7): commit: e50b1e327aeb4b224364aa6f85c1713ff8b3654b ("null_blk: remove legacy IO path") https://git.kernel.org/cgit/linux/kernel/git/torvalds/linux.git master in testcase: blktests with following parameters: disk: 1HDD test: block-group2 on test machine: qemu-system-x86_64 -enable-kvm -cpu host -smp 2 -m 4G caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace): Cloning into 'blktests'... Note: checking out 'e9ffecc0ee82759cf8316e6a4b8cf80df0c27e0a'. You are in 'detached HEAD' state. You can look around, make experimental changes and commit them, and you can discard any commits you make in this state without impacting any branches by performing another checkout. If you want to create a new branch to retain commits you create, you may do so (now or later) by using -b with the checkout command again. Example: git checkout -b <new-branch-name> HEAD is now at e9ffecc... Convert license headers to SPDX tags make -C src all make[1]: Entering directory '/lkp/benchmarks/blktests/blktests/src' cc -O2 -Wall -o loblksize loblksize.c cc -O2 -Wall -o loop_get_status_null loop_get_status_null.c cc -O2 -Wall -o openclose openclose.c cc -O2 -Wall -o sg/dxfer-from-dev sg/dxfer-from-dev.c cc -O2 -Wall -o sg/syzkaller1 sg/syzkaller1.c cc -O2 -Wall -o nbdsetsize nbdsetsize.c g++ -O2 -std=c++11 -Wall -Wextra -Wno-sign-compare -Werror -o discontiguous-io discontiguous-io.cpp make[1]: Leaving directory '/lkp/benchmarks/blktests/blktests/src' 2018-10-23 14:13:49 sed "s:^:block/:" /lkp/benchmarks/blktests/blktests/tests/block-group2 | grep -F -f merged_ignored_files block/011 [ignored by lkp] block/019 [ignored by lkp] 2018-10-23 14:13:49 sed "s:^:block/:" /lkp/benchmarks/blktests/blktests/tests/block-group2 | grep -v -F -f merged_ignored_files 2018-10-23 14:13:49 ./check block/009 block/010 block/012 block/013 block/014 block/015 block/016 block/017 block/018 block/020 block/021 block/022 block/023 block/024 block/009 (check page-cache coherency after BLKDISCARD) runtime ... block/009 (check page-cache coherency after BLKDISCARD) [passed] runtime ... 0.925s block/010 (run I/O on null_blk with shared and non-shared tags) runtime ... block/010 (run I/O on null_blk with shared and non-shared tags) [passed] runtime ... 469.856s Individual tags read iops ... 272936 Shared tags read iops ... 303528 block/012 => vda (check that a read-only block device fails writes) runtime ... block/012 => vda (check that a read-only block device fails writes) [passed] runtime ... 1.244s block/013 => vda (try BLKRRPART on a mounted device) runtime ... block/013 => vda (try BLKRRPART on a mounted device) [passed] runtime ... 696.818s block/014 (run null-blk with blk-mq and timeout injection configured) runtime ... block/014 (run null-blk with blk-mq and timeout injection configured) [passed] runtime ... 101.446s block/015 (run null-blk on different schedulers with requeue injection configured) runtime ... block/015 (run null-blk on different schedulers with requeue injection configured) [passed] runtime ... 11.414s block/016 (send a signal to a process waiting on a frozen queue) runtime ... block/016 (send a signal to a process waiting on a frozen queue) [passed] runtime ... 8.045s block/017 (do I/O and check the inflight counter) runtime ... block/017 (do I/O and check the inflight counter) [failed] runtime ... 1.661s --- tests/block/017.out 2018-10-23 14:13:47.496000000 +0000 +++ /lkp/benchmarks/blktests/blktests/results/nodev/block/017.out.bad 2018-10-23 14:35:21.045000000 +0000 @@ -1,17 +1,6 @@ Running block/017 queue mode 1 -sysfs inflight reads 1 -sysfs inflight writes 0 -sysfs stat 1 -diskstats 1 -sysfs inflight reads 1 ... (Run 'diff -u tests/block/017.out /lkp/benchmarks/blktests/blktests/results/nodev/block/017.out.bad' to see the entire diff) block/018 (do I/O and check iostats times) runtime ... block/018 (do I/O and check iostats times) [failed] runtime ... 5.078s --- tests/block/018.out 2018-10-23 14:13:47.496000000 +0000 +++ /lkp/benchmarks/blktests/blktests/results/nodev/block/018.out.bad 2018-10-23 14:35:26.171000000 +0000 @@ -1,13 +1,6 @@ Running block/018 queue mode 1 -read 0 s -write 0 s -read 1 s -write 0 s -read 1 s ... (Run 'diff -u tests/block/018.out /lkp/benchmarks/blktests/blktests/results/nodev/block/018.out.bad' to see the entire diff) block/020 (run null-blk on different schedulers with only one hardware tag) runtime ... block/020 (run null-blk on different schedulers with only one hardware tag) [passed] runtime ... 31.216s block/021 (read/write nr_requests on null-blk with different schedulers) runtime ... block/021 (read/write nr_requests on null-blk with different schedulers) [passed] runtime ... 5.016s block/022 (run null-blk with legacy blk path and timeout injection configured) runtime ... block/022 (run null-blk with legacy blk path and timeout injection configured) [failed] runtime ... 0.012s --- tests/block/022.out 2018-10-23 14:13:47.496000000 +0000 +++ /lkp/benchmarks/blktests/blktests/results/nodev/block/022.out.bad 2018-10-23 14:36:02.653000000 +0000 @@ -1,2 +1,2 @@ Running block/022 -Test complete +modprobe: ERROR: could not insert 'null_blk': Invalid argument block/023 (do I/O on all null_blk queue modes) runtime ... block/023 (do I/O on all null_blk queue modes) [failed] runtime ... 0.243s --- tests/block/023.out 2018-10-23 14:13:47.496000000 +0000 +++ /lkp/benchmarks/blktests/blktests/results/nodev/block/023.out.bad 2018-10-23 14:36:02.946000000 +0000 @@ -1,5 +1,5 @@ Running block/023 Queue mode 0 -Queue mode 1 +modprobe: ERROR: could not insert 'null_blk': Invalid argument Queue mode 2 Test complete block/024 (do I/O faster than a jiffy and check iostats times) runtime ... block/024 (do I/O faster than a jiffy and check iostats times) [failed] runtime ... 2.500s --- tests/block/024.out 2018-10-23 14:13:47.496000000 +0000 +++ /lkp/benchmarks/blktests/blktests/results/nodev/block/024.out.bad 2018-10-23 14:36:05.496000000 +0000 @@ -1,13 +1,6 @@ Running block/024 queue mode 1 -read 0 s -write 0 s -read 1 s -write 0 s -read 1 s ... (Run 'diff -u tests/block/024.out /lkp/benchmarks/blktests/blktests/results/nodev/block/024.out.bad' to see the entire diff) To reproduce: git clone https://github.com/intel/lkp-tests.git cd lkp-tests bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email Thanks, Rong Chen

2 years, 4 months

1
0
0 / 0

[lkp-robot] [Btrfs] 5239834016: blogbench.read_score -7.2% regression

by kernel test robot

Greeting, FYI, we noticed a -7.2% regression of blogbench.read_score due to commit: commit: 523983401644ebeb331c923c28c9591c07430a7d ("Btrfs: kill btrfs_clear_path_blocking") https://git.kernel.org/cgit/linux/kernel/git/kdave/linux.git for-4.20-part1 in testcase: blogbench on test machine: 88 threads Intel(R) Xeon(R) CPU E5-2699 v4 @ 2.20GHz with 64G memory with following parameters: disk: 1SSD fs: btrfs ucode: 0xb00002e cpufreq_governor: performance test-description: Blogbench is a portable filesystem benchmark that tries to reproduce the load of a real-world busy file server. test-url: https://www.pureftpd.org/project/blogbench In addition to that, the commit also has significant impact on the following tests: +------------------+-----------------------------------------------------------------------+ | testcase: change | fio-basic: | | test machine | 56 threads Intel(R) Xeon(R) CPU E5-2695 v3 @ 2.30GHz with 256G memory | | test parameters | bs=4k | | | cpufreq_governor=performance | | | disk=2pmem | | | fs=btrfs | | | ioengine=mmap | | | nr_task=50% | | | runtime=200s | | | rw=randwrite | | | test_size=100G | | | time_based=tb | | | ucode=0x3d | +------------------+-----------------------------------------------------------------------+ Details are as below: --------------------------------------------------------------------------------------------------> To reproduce: git clone https://github.com/intel/lkp-tests.git cd lkp-tests bin/lkp install job.yaml # job file is attached in this email bin/lkp run job.yaml ========================================================================================= compiler/cpufreq_governor/disk/fs/kconfig/rootfs/tbox_group/testcase/ucode: gcc-7/performance/1SSD/btrfs/x86_64-rhel-7.2/debian-x86_64-2018-04-03.cgz/lkp-bdw-ep3b/blogbench/0xb00002e commit: 9b142115ed ("btrfs: dev-replace: remove pointless assert in write unlock") 5239834016 ("Btrfs: kill btrfs_clear_path_blocking") 9b142115ed359348 523983401644ebeb331c923c28 ---------------- -------------------------- fail:runs %reproduction fail:runs | | | :4 25% 1:4 dmesg.WARNING:at#for_ip_interrupt_entry/0x %stddev %change %stddev \ | \ 2339806 ± 2% -7.2% 2171755 ± 2% blogbench.read_score 35999832 +102.7% 72956144 blogbench.time.file_system_outputs 9368 ± 6% +16.1% 10872 ± 12% blogbench.time.minor_page_faults 8033 +4.2% 8371 blogbench.time.percent_of_cpu_this_job_got 23833 +4.2% 24824 blogbench.time.system_time 14208581 -36.5% 9023264 ± 2% blogbench.time.voluntary_context_switches 3942 +90.7% 7519 blogbench.write_score 623343 ± 2% +40.2% 874008 ± 3% interrupts.CAL:Function_call_interrupts 5.32 ± 12% -3.5 1.80 ± 10% mpstat.cpu.idle% 0.00 ± 36% +0.0 0.01 ± 31% mpstat.cpu.iowait% 197893 ± 4% +115.2% 425773 softirqs.BLOCK 708383 ± 7% -29.7% 498259 ± 7% softirqs.SCHED 6513961 ± 5% +25.1% 8150114 numa-numastat.node0.local_node 6522556 ± 5% +25.0% 8154430 numa-numastat.node0.numa_hit 6186981 ± 3% +38.2% 8549312 ± 3% numa-numastat.node1.local_node 6195606 ± 3% +38.2% 8562194 ± 3% numa-numastat.node1.numa_hit 8.36 ± 15% +208.1% 25.76 ± 2% iostat.sda.avgqu-sz 117.35 ± 19% +72.9% 202.85 ± 5% iostat.sda.avgrq-sz 1337 ± 4% +117.9% 2914 iostat.sda.w/s 55899 +119.2% 122524 iostat.sda.wkB/s 1336 ± 6% +115.4% 2879 iostat.sda.wrqm/s 55754 +119.8% 122532 vmstat.io.bo 9410599 +84.4% 17349395 vmstat.memory.cache 55400592 -14.7% 47252921 vmstat.memory.free 135164 -29.3% 95527 vmstat.system.cs 226228 -2.1% 221394 vmstat.system.in 1.138e+08 ± 5% -72.8% 30993990 ± 4% cpuidle.C1.time 5684766 ± 5% -78.9% 1198851 ± 5% cpuidle.C1.usage 80465587 ± 58% -83.5% 13288839 ± 24% cpuidle.C1E.time 939509 ± 49% -80.4% 184165 ± 17% cpuidle.C1E.usage 8.707e+08 ± 31% -77.9% 1.925e+08 ± 45% cpuidle.C6.time 1114164 ± 21% -77.5% 251130 ± 47% cpuidle.C6.usage 799330 ± 18% -72.6% 218752 ± 4% cpuidle.POLL.time 43028 ± 6% -74.7% 10884 ± 5% cpuidle.POLL.usage 6383503 +91.0% 12190888 meminfo.Active 6076016 +95.6% 11882998 meminfo.Active(file) 8683414 +84.4% 16014311 meminfo.Cached 1644022 +92.6% 3166518 meminfo.Inactive 1625430 +93.7% 3147989 meminfo.Inactive(file) 55450824 -14.7% 47325613 meminfo.MemFree 678179 +86.4% 1264342 meminfo.SReclaimable 401687 +50.2% 603151 meminfo.SUnreclaim 1079867 +72.9% 1867493 meminfo.Slab 6814 ± 34% +458.0% 38021 ± 6% meminfo.Writeback 2656 +3.5% 2749 turbostat.Avg_MHz 5681184 ± 5% -79.0% 1194935 ± 5% turbostat.C1 0.42 ± 5% -0.3 0.12 ± 3% turbostat.C1% 935242 ± 50% -80.8% 179244 ± 17% turbostat.C1E 0.30 ± 58% -0.3 0.05 ± 27% turbostat.C1E% 1102813 ± 21% -77.7% 245393 ± 49% turbostat.C6 3.23 ± 31% -2.5 0.71 ± 45% turbostat.C6% 3.16 ± 9% -69.2% 0.97 ± 12% turbostat.CPU%c1 1.39 ± 47% -77.3% 0.32 ± 58% turbostat.CPU%c6 0.67 ± 49% -62.2% 0.25 ± 27% turbostat.Pkg%pc2 234.32 +1.6% 238.14 turbostat.PkgWatt 3.862e+12 +4.3% 4.027e+12 perf-stat.branch-instructions 1.684e+10 +2.3% 1.723e+10 perf-stat.branch-misses 30.36 -0.3 30.02 perf-stat.cache-miss-rate% 8.746e+10 -3.9% 8.406e+10 perf-stat.cache-misses 41358877 -29.9% 28984102 perf-stat.context-switches 4.32 -2.0% 4.23 perf-stat.cpi 7.074e+13 +2.7% 7.263e+13 perf-stat.cpu-cycles 0.02 ± 7% +0.0 0.02 perf-stat.dTLB-load-miss-rate% 9.149e+08 ± 7% +22.4% 1.12e+09 perf-stat.dTLB-load-misses 4.387e+12 +4.4% 4.58e+12 perf-stat.dTLB-loads 1.638e+13 +4.7% 1.716e+13 perf-stat.instructions 0.23 +2.0% 0.24 perf-stat.ipc 761956 -1.0% 754649 perf-stat.minor-faults 1.714e+10 -4.1% 1.643e+10 perf-stat.node-load-misses 1.202e+10 ± 2% -5.9% 1.131e+10 ± 2% perf-stat.node-store-misses 761958 -1.0% 754651 perf-stat.page-faults 1517674 +95.8% 2971175 proc-vmstat.nr_active_file 4752729 +109.2% 9944485 proc-vmstat.nr_dirtied 321416 ± 7% +9.1% 350782 ± 3% proc-vmstat.nr_dirty 1567569 -1.3% 1547670 proc-vmstat.nr_dirty_background_threshold 3138973 -1.3% 3099125 proc-vmstat.nr_dirty_threshold 2169155 +84.6% 4004236 proc-vmstat.nr_file_pages 13864507 -14.7% 11830502 proc-vmstat.nr_free_pages 405998 +93.9% 787215 proc-vmstat.nr_inactive_file 16065 +2.3% 16429 proc-vmstat.nr_kernel_stack 169409 +86.6% 316143 proc-vmstat.nr_slab_reclaimable 100393 +50.3% 150923 proc-vmstat.nr_slab_unreclaimable 1790 ± 35% +439.6% 9659 ± 6% proc-vmstat.nr_writeback 4229206 +120.2% 9311657 proc-vmstat.nr_written 1517674 +95.8% 2971175 proc-vmstat.nr_zone_active_file 405998 +93.9% 787215 proc-vmstat.nr_zone_inactive_file 323756 ± 7% +11.6% 361231 ± 2% proc-vmstat.nr_zone_write_pending 12740396 +31.3% 16724676 proc-vmstat.numa_hit 12723171 +31.3% 16707470 proc-vmstat.numa_local 3466918 +97.1% 6833346 proc-vmstat.pgactivate 21010209 +21.5% 25519008 proc-vmstat.pgalloc_normal 16906221 ± 2% +4.3% 17638056 proc-vmstat.pgfree 16888174 +120.2% 37189214 proc-vmstat.pgpgout 3439308 ± 12% +68.0% 5779423 ± 2% numa-meminfo.node0.Active 3292978 ± 13% +70.5% 5615415 ± 2% numa-meminfo.node0.Active(file) 4674100 ± 12% +62.9% 7614966 ± 2% numa-meminfo.node0.FilePages 900475 ± 16% +67.0% 1504140 ± 2% numa-meminfo.node0.Inactive 890125 ± 16% +67.0% 1486500 ± 2% numa-meminfo.node0.Inactive(file) 27300625 ± 2% -12.0% 24025498 numa-meminfo.node0.MemFree 5558153 ± 11% +58.8% 8826192 ± 2% numa-meminfo.node0.MemUsed 370938 ± 15% +60.9% 596960 ± 3% numa-meminfo.node0.SReclaimable 198127 ± 6% +45.2% 287679 ± 3% numa-meminfo.node0.SUnreclaim 569065 ± 11% +55.5% 884640 ± 3% numa-meminfo.node0.Slab 3534 ± 30% +439.6% 19072 ± 6% numa-meminfo.node0.Writeback 2936433 ± 16% +117.9% 6399389 ± 3% numa-meminfo.node1.Active 2775387 ± 16% +125.4% 6255600 ± 4% numa-meminfo.node1.Active(file) 590133 ± 5% +24.9% 737354 ± 5% numa-meminfo.node1.Dirty 3999720 ± 15% +109.6% 8383937 ± 4% numa-meminfo.node1.FilePages 741722 ± 18% +123.7% 1659082 ± 5% numa-meminfo.node1.Inactive 733558 ± 17% +126.0% 1658132 ± 5% numa-meminfo.node1.Inactive(file) 28161048 ± 2% -17.2% 23316049 numa-meminfo.node1.MemFree 4832002 ± 14% +100.4% 9684087 ± 3% numa-meminfo.node1.MemUsed 306477 ± 19% +117.4% 666212 ± 4% numa-meminfo.node1.SReclaimable 202862 ± 5% +55.7% 315954 ± 3% numa-meminfo.node1.SUnreclaim 509340 ± 13% +92.8% 982167 ± 4% numa-meminfo.node1.Slab 3309 ± 51% +500.9% 19885 ± 4% numa-meminfo.node1.Writeback 1238 ± 18% -72.5% 339.89 ± 4% sched_debug.cfs_rq:/.exec_clock.stddev 12674187 +10.6% 14013368 sched_debug.cfs_rq:/.min_vruntime.avg 13159010 +10.3% 14515696 sched_debug.cfs_rq:/.min_vruntime.max 284068 ± 3% +20.1% 341267 ± 8% sched_debug.cfs_rq:/.min_vruntime.stddev -1661029 +33.9% -2223696 sched_debug.cfs_rq:/.spread0.min 284027 ± 3% +20.1% 341256 ± 8% sched_debug.cfs_rq:/.spread0.stddev 261246 -34.5% 171104 sched_debug.cpu.nr_switches.avg 131794 ± 5% -25.3% 98505 ± 3% sched_debug.cpu.nr_switches.min 88301 ± 6% -20.2% 70424 ± 5% sched_debug.cpu.nr_switches.stddev -70.00 -9.8% -63.12 sched_debug.cpu.nr_uninterruptible.min 261990 -34.5% 171673 sched_debug.cpu.sched_count.avg 131829 ± 5% -25.3% 98478 ± 3% sched_debug.cpu.sched_count.min 89185 ± 7% -18.8% 72377 ± 7% sched_debug.cpu.sched_count.stddev 55278 ± 4% -78.3% 11971 ± 5% sched_debug.cpu.sched_goidle.avg 124430 ± 4% -73.5% 33031 ± 9% sched_debug.cpu.sched_goidle.max 9033 ± 24% -67.9% 2899 ± 27% sched_debug.cpu.sched_goidle.min 31192 ± 15% -80.3% 6157 ± 9% sched_debug.cpu.sched_goidle.stddev 163543 -33.2% 109236 sched_debug.cpu.ttwu_count.avg 559684 ± 9% +30.0% 727656 ± 5% sched_debug.cpu.ttwu_count.max 82509 ± 4% -30.6% 57266 ± 2% sched_debug.cpu.ttwu_count.min 60134 ± 4% +16.1% 69840 ± 5% sched_debug.cpu.ttwu_count.stddev 7548 +70.3% 12853 sched_debug.cpu.ttwu_local.avg 49292 ± 13% +133.5% 115118 ± 5% sched_debug.cpu.ttwu_local.max 4640 ± 5% +50.0% 6962 ± 2% sched_debug.cpu.ttwu_local.min 4665 ± 13% +140.2% 11204 ± 5% sched_debug.cpu.ttwu_local.stddev 823017 ± 13% +70.6% 1403862 ± 3% numa-vmstat.node0.nr_active_file 1291355 ± 14% +80.5% 2330828 ± 2% numa-vmstat.node0.nr_dirtied 1168265 ± 12% +63.0% 1903769 ± 2% numa-vmstat.node0.nr_file_pages 6825413 ± 2% -12.0% 6006516 numa-vmstat.node0.nr_free_pages 222478 ± 15% +67.0% 371641 ± 2% numa-vmstat.node0.nr_inactive_file 92708 ± 15% +61.0% 149233 ± 3% numa-vmstat.node0.nr_slab_reclaimable 49543 ± 5% +44.9% 71769 ± 3% numa-vmstat.node0.nr_slab_unreclaimable 906.00 ± 23% +412.1% 4639 ± 12% numa-vmstat.node0.nr_writeback 1053489 ± 13% +97.8% 2084007 ± 2% numa-vmstat.node0.nr_written 823017 ± 13% +70.6% 1403862 ± 3% numa-vmstat.node0.nr_zone_active_file 222478 ± 15% +67.0% 371640 ± 2% numa-vmstat.node0.nr_zone_inactive_file 3701754 ± 5% +23.3% 4562929 numa-vmstat.node0.numa_hit 3692968 ± 5% +23.4% 4558577 numa-vmstat.node0.numa_local 693808 ± 16% +125.4% 1563789 ± 4% numa-vmstat.node1.nr_active_file 1079325 ± 18% +142.5% 2617710 ± 4% numa-vmstat.node1.nr_dirtied 147501 ± 6% +25.1% 184488 ± 5% numa-vmstat.node1.nr_dirty 999853 ± 15% +109.6% 2095923 ± 4% numa-vmstat.node1.nr_file_pages 7040287 ± 2% -17.2% 5829252 numa-vmstat.node1.nr_free_pages 183348 ± 18% +126.1% 414583 ± 5% numa-vmstat.node1.nr_inactive_file 3287 ± 16% -19.7% 2639 ± 2% numa-vmstat.node1.nr_mapped 76615 ± 19% +117.4% 166540 ± 4% numa-vmstat.node1.nr_slab_reclaimable 50754 ± 5% +55.3% 78827 ± 3% numa-vmstat.node1.nr_slab_unreclaimable 837.00 ± 38% +475.9% 4820 ± 5% numa-vmstat.node1.nr_writeback 880188 ± 19% +164.8% 2330392 ± 4% numa-vmstat.node1.nr_written 693808 ± 16% +125.4% 1563789 ± 4% numa-vmstat.node1.nr_zone_active_file 183348 ± 18% +126.1% 414583 ± 5% numa-vmstat.node1.nr_zone_inactive_file 148581 ± 6% +27.7% 189690 ± 5% numa-vmstat.node1.nr_zone_write_pending 3503508 ± 5% +35.4% 4744281 ± 2% numa-vmstat.node1.numa_hit 3359384 ± 5% +36.8% 4595659 ± 2% numa-vmstat.node1.numa_local 412161 +75.9% 724947 slabinfo.Acpi-Namespace.active_objs 4040 +75.9% 7107 slabinfo.Acpi-Namespace.active_slabs 412179 +75.9% 725010 slabinfo.Acpi-Namespace.num_objs 4040 +75.9% 7107 slabinfo.Acpi-Namespace.num_slabs 14738 ± 7% +41.9% 20916 ± 5% slabinfo.Acpi-ParseExt.active_objs 377.50 ± 7% +42.5% 537.75 ± 5% slabinfo.Acpi-ParseExt.active_slabs 14747 ± 7% +42.3% 20985 ± 5% slabinfo.Acpi-ParseExt.num_objs 377.50 ± 7% +42.5% 537.75 ± 5% slabinfo.Acpi-ParseExt.num_slabs 338988 +97.8% 670518 slabinfo.Acpi-State.active_objs 6656 +98.0% 13180 slabinfo.Acpi-State.active_slabs 339477 +98.0% 672237 slabinfo.Acpi-State.num_objs 6656 +98.0% 13180 slabinfo.Acpi-State.num_slabs 17218 ± 6% +58.5% 27293 ± 4% slabinfo.avc_xperms_data.active_objs 17218 ± 6% +58.6% 27307 ± 4% slabinfo.avc_xperms_data.num_objs 1083 ± 2% +14.7% 1242 ± 2% slabinfo.biovec-128.active_objs 1083 ± 2% +14.7% 1242 ± 2% slabinfo.biovec-128.num_objs 852.25 ± 10% +158.9% 2206 ± 4% slabinfo.biovec-max.active_objs 222.25 ± 9% +153.1% 562.50 ± 4% slabinfo.biovec-max.active_slabs 890.75 ± 9% +152.7% 2251 ± 4% slabinfo.biovec-max.num_objs 222.25 ± 9% +153.1% 562.50 ± 4% slabinfo.biovec-max.num_slabs 328556 +94.7% 639780 slabinfo.btrfs_delayed_node.active_objs 6318 +94.7% 12303 slabinfo.btrfs_delayed_node.active_slabs 328560 +94.7% 639784 slabinfo.btrfs_delayed_node.num_objs 6318 +94.7% 12303 slabinfo.btrfs_delayed_node.num_slabs 24166 +69.7% 41014 slabinfo.btrfs_extent_buffer.active_objs 418.25 +71.2% 716.00 slabinfo.btrfs_extent_buffer.active_slabs 24292 +71.1% 41558 slabinfo.btrfs_extent_buffer.num_objs 418.25 +71.2% 716.00 slabinfo.btrfs_extent_buffer.num_slabs 274744 ± 2% +110.3% 577705 slabinfo.btrfs_extent_map.active_objs 4906 ± 2% +110.3% 10316 slabinfo.btrfs_extent_map.active_slabs 274769 ± 2% +110.3% 577758 slabinfo.btrfs_extent_map.num_objs 4906 ± 2% +110.3% 10316 slabinfo.btrfs_extent_map.num_slabs 327623 +95.1% 639035 slabinfo.btrfs_inode.active_objs 11702 +95.1% 22826 slabinfo.btrfs_inode.active_slabs 327679 +95.1% 639160 slabinfo.btrfs_inode.num_objs 11702 +95.1% 22826 slabinfo.btrfs_inode.num_slabs 431945 +80.1% 778072 slabinfo.dentry.active_objs 10288 +80.2% 18535 slabinfo.dentry.active_slabs 432133 +80.2% 778505 slabinfo.dentry.num_objs 10288 +80.2% 18535 slabinfo.dentry.num_slabs 6708 +25.7% 8431 ± 4% slabinfo.kmalloc-1024.active_objs 6770 +25.8% 8518 ± 4% slabinfo.kmalloc-1024.num_objs 19023 ± 6% +16.2% 22103 ± 3% slabinfo.kmalloc-128.active_objs 19181 ± 6% +16.5% 22340 ± 3% slabinfo.kmalloc-128.num_objs 22578 ± 4% +69.3% 38216 ± 5% slabinfo.kmalloc-192.active_objs 538.25 ± 4% +69.6% 913.00 ± 5% slabinfo.kmalloc-192.active_slabs 22622 ± 4% +69.6% 38367 ± 5% slabinfo.kmalloc-192.num_objs 538.25 ± 4% +69.6% 913.00 ± 5% slabinfo.kmalloc-192.num_slabs 1302 +9.4% 1424 slabinfo.kmalloc-4096.active_objs 29789 ± 5% +15.5% 34398 ± 3% slabinfo.kmalloc-96.active_objs 30023 ± 5% +16.2% 34881 ± 3% slabinfo.kmalloc-96.num_objs 222.50 ± 32% +36.4% 303.50 ± 19% slabinfo.nfs_read_data.active_objs 222.50 ± 32% +36.4% 303.50 ± 19% slabinfo.nfs_read_data.num_objs 315452 +91.1% 602801 slabinfo.radix_tree_node.active_objs 5633 +91.1% 10765 slabinfo.radix_tree_node.active_slabs 315485 +91.1% 602881 slabinfo.radix_tree_node.num_objs 5633 +91.1% 10765 slabinfo.radix_tree_node.num_slabs 4980 +31.5% 6549 ± 4% slabinfo.scsi_sense_cache.active_objs 4993 +31.5% 6564 ± 4% slabinfo.scsi_sense_cache.num_objs 567.00 ± 11% +25.9% 714.00 ± 2% slabinfo.sgpool-128.active_objs 573.75 ± 11% +27.0% 728.75 ± 2% slabinfo.sgpool-128.num_objs 9.71 ± 10% -1.0 8.66 ± 2% perf-profile.calltrace.cycles-pp.ksys_read.do_syscall_64.entry_SYSCALL_64_after_hwframe 9.39 ± 10% -1.0 8.37 ± 2% perf-profile.calltrace.cycles-pp.vfs_read.ksys_read.do_syscall_64.entry_SYSCALL_64_after_hwframe 9.16 ± 11% -1.0 8.15 ± 2% perf-profile.calltrace.cycles-pp.__vfs_read.vfs_read.ksys_read.do_syscall_64.entry_SYSCALL_64_after_hwframe 9.00 ± 11% -1.0 8.00 ± 2% perf-profile.calltrace.cycles-pp.generic_file_read_iter.__vfs_read.vfs_read.ksys_read.do_syscall_64 6.80 ± 11% -0.8 6.02 ± 2% perf-profile.calltrace.cycles-pp.copy_page_to_iter.generic_file_read_iter.__vfs_read.vfs_read.ksys_read 6.56 ± 12% -0.8 5.79 ± 2% perf-profile.calltrace.cycles-pp.copyout.copy_page_to_iter.generic_file_read_iter.__vfs_read.vfs_read 6.50 ± 12% -0.8 5.74 ± 2% perf-profile.calltrace.cycles-pp.copy_user_enhanced_fast_string.copyout.copy_page_to_iter.generic_file_read_iter.__vfs_read 1.48 ± 5% -0.5 0.93 ± 3% perf-profile.calltrace.cycles-pp.__x64_sys_rename.do_syscall_64.entry_SYSCALL_64_after_hwframe 1.47 ± 5% -0.5 0.93 ± 3% perf-profile.calltrace.cycles-pp.do_renameat2.__x64_sys_rename.do_syscall_64.entry_SYSCALL_64_after_hwframe 1.08 ± 5% -0.4 0.65 ± 3% perf-profile.calltrace.cycles-pp.btrfs_rename.vfs_rename.do_renameat2.__x64_sys_rename.do_syscall_64 1.09 ± 5% -0.4 0.67 ± 2% perf-profile.calltrace.cycles-pp.vfs_rename.do_renameat2.__x64_sys_rename.do_syscall_64.entry_SYSCALL_64_after_hwframe 2.29 ± 8% -0.3 2.03 ± 3% perf-profile.calltrace.cycles-pp.__softirqentry_text_start.run_ksoftirqd.smpboot_thread_fn.kthread.ret_from_fork 2.29 ± 8% -0.3 2.03 ± 3% perf-profile.calltrace.cycles-pp.run_ksoftirqd.smpboot_thread_fn.kthread.ret_from_fork 2.28 ± 8% -0.3 2.02 ± 3% perf-profile.calltrace.cycles-pp.rcu_process_callbacks.__softirqentry_text_start.run_ksoftirqd.smpboot_thread_fn.kthread 3.34 ± 6% -0.3 3.08 ± 3% perf-profile.calltrace.cycles-pp.do_filp_open.do_sys_open.do_syscall_64.entry_SYSCALL_64_after_hwframe 2.30 ± 8% -0.3 2.04 ± 3% perf-profile.calltrace.cycles-pp.smpboot_thread_fn.kthread.ret_from_fork 3.31 ± 6% -0.3 3.06 ± 3% perf-profile.calltrace.cycles-pp.path_openat.do_filp_open.do_sys_open.do_syscall_64.entry_SYSCALL_64_after_hwframe 2.06 ± 7% -0.2 1.82 ± 3% perf-profile.calltrace.cycles-pp.file_free_rcu.rcu_process_callbacks.__softirqentry_text_start.run_ksoftirqd.smpboot_thread_fn 0.59 ± 3% -0.0 0.54 ± 3% perf-profile.calltrace.cycles-pp.__alloc_file.alloc_empty_file.path_openat.do_filp_open.do_sys_open 0.61 ± 4% -0.0 0.57 ± 3% perf-profile.calltrace.cycles-pp.alloc_empty_file.path_openat.do_filp_open.do_sys_open.do_syscall_64 94.63 +0.7 95.34 perf-profile.calltrace.cycles-pp.entry_SYSCALL_64_after_hwframe 94.58 +0.7 95.30 perf-profile.calltrace.cycles-pp.do_syscall_64.entry_SYSCALL_64_after_hwframe 48.35 +0.8 49.15 perf-profile.calltrace.cycles-pp.do_sys_open.do_syscall_64.entry_SYSCALL_64_after_hwframe 39.07 +1.0 40.06 perf-profile.calltrace.cycles-pp._raw_spin_lock.__alloc_fd.do_sys_open.do_syscall_64.entry_SYSCALL_64_after_hwframe 38.86 +1.0 39.85 perf-profile.calltrace.cycles-pp.native_queued_spin_lock_slowpath._raw_spin_lock.__alloc_fd.do_sys_open.do_syscall_64 39.37 +1.0 40.36 perf-profile.calltrace.cycles-pp.__alloc_fd.do_sys_open.do_syscall_64.entry_SYSCALL_64_after_hwframe 33.62 +1.5 35.09 perf-profile.calltrace.cycles-pp._raw_spin_lock.__close_fd.__x64_sys_close.do_syscall_64.entry_SYSCALL_64_after_hwframe 33.98 +1.5 35.45 perf-profile.calltrace.cycles-pp.__x64_sys_close.do_syscall_64.entry_SYSCALL_64_after_hwframe 33.44 +1.5 34.91 perf-profile.calltrace.cycles-pp.native_queued_spin_lock_slowpath._raw_spin_lock.__close_fd.__x64_sys_close.do_syscall_64 33.78 +1.5 35.25 perf-profile.calltrace.cycles-pp.__close_fd.__x64_sys_close.do_syscall_64.entry_SYSCALL_64_after_hwframe 3.10 ± 27% -1.5 1.60 ± 2% perf-profile.children.cycles-pp.btrfs_search_slot 1.53 ± 34% -1.1 0.41 ± 2% perf-profile.children.cycles-pp.btrfs_tree_lock 1.52 ± 35% -1.1 0.40 ± 2% perf-profile.children.cycles-pp.btrfs_lock_root_node 9.72 ± 10% -1.0 8.68 ± 2% perf-profile.children.cycles-pp.ksys_read 9.41 ± 10% -1.0 8.38 ± 2% perf-profile.children.cycles-pp.vfs_read 9.16 ± 11% -1.0 8.16 ± 2% perf-profile.children.cycles-pp.__vfs_read 9.02 ± 11% -1.0 8.02 ± 2% perf-profile.children.cycles-pp.generic_file_read_iter 6.81 ± 11% -0.8 6.04 ± 2% perf-profile.children.cycles-pp.copy_page_to_iter 6.56 ± 12% -0.8 5.79 ± 2% perf-profile.children.cycles-pp.copyout 6.55 ± 12% -0.8 5.79 ± 2% perf-profile.children.cycles-pp.copy_user_enhanced_fast_string 1.26 ± 35% -0.7 0.59 ± 3% perf-profile.children.cycles-pp._raw_spin_lock_irqsave 1.48 ± 5% -0.5 0.93 ± 3% perf-profile.children.cycles-pp.__x64_sys_rename 1.48 ± 5% -0.5 0.93 ± 3% perf-profile.children.cycles-pp.do_renameat2 0.59 ± 40% -0.5 0.11 ± 4% perf-profile.children.cycles-pp.finish_wait 1.08 ± 5% -0.4 0.65 ± 3% perf-profile.children.cycles-pp.btrfs_rename 0.82 ± 7% -0.4 0.39 ± 3% perf-profile.children.cycles-pp.__btrfs_unlink_inode 1.09 ± 5% -0.4 0.67 ± 2% perf-profile.children.cycles-pp.vfs_rename 0.54 ± 30% -0.4 0.17 ± 4% perf-profile.children.cycles-pp.queued_write_lock_slowpath 0.56 ± 7% -0.3 0.26 perf-profile.children.cycles-pp.btrfs_lookup_dir_item 2.52 ± 8% -0.3 2.24 ± 3% perf-profile.children.cycles-pp.rcu_process_callbacks 2.29 ± 8% -0.3 2.03 ± 3% perf-profile.children.cycles-pp.run_ksoftirqd 2.30 ± 8% -0.3 2.04 ± 3% perf-profile.children.cycles-pp.smpboot_thread_fn 3.34 ± 6% -0.3 3.09 ± 3% perf-profile.children.cycles-pp.do_filp_open 2.27 ± 7% -0.3 2.01 ± 3% perf-profile.children.cycles-pp.file_free_rcu 3.31 ± 6% -0.3 3.06 ± 3% perf-profile.children.cycles-pp.path_openat 0.33 ± 27% -0.2 0.09 ± 7% perf-profile.children.cycles-pp.queued_read_lock_slowpath 0.50 ± 19% -0.2 0.29 ± 6% perf-profile.children.cycles-pp.btrfs_async_run_delayed_root 0.33 ± 9% -0.2 0.14 ± 5% perf-profile.children.cycles-pp.btrfs_del_inode_ref 0.50 ± 15% -0.2 0.32 ± 6% perf-profile.children.cycles-pp.__wake_up_common_lock 0.33 ± 13% -0.1 0.20 ± 5% perf-profile.children.cycles-pp.btrfs_evict_inode 0.33 ± 13% -0.1 0.20 ± 5% perf-profile.children.cycles-pp.evict 0.32 ± 25% -0.1 0.19 ± 5% perf-profile.children.cycles-pp.btrfs_lookup_inode 0.33 ± 11% -0.1 0.21 ± 5% perf-profile.children.cycles-pp.dentry_kill 0.33 ± 11% -0.1 0.21 ± 5% perf-profile.children.cycles-pp.__dentry_kill 0.56 ± 7% -0.1 0.45 ± 2% perf-profile.children.cycles-pp.dput 0.38 ± 14% -0.1 0.27 ± 6% perf-profile.children.cycles-pp.__wake_up_common 0.19 ± 15% -0.1 0.08 ± 5% perf-profile.children.cycles-pp.btrfs_delete_delayed_items 0.36 ± 13% -0.1 0.26 ± 4% perf-profile.children.cycles-pp.autoremove_wake_function 0.56 ± 9% -0.1 0.49 ± 4% perf-profile.children.cycles-pp.may_open 0.21 ± 17% -0.1 0.15 ± 12% perf-profile.children.cycles-pp.reschedule_interrupt 0.19 ± 17% -0.1 0.13 ± 14% perf-profile.children.cycles-pp.scheduler_ipi 0.40 ± 2% -0.1 0.34 ± 2% perf-profile.children.cycles-pp.btrfs_insert_empty_items 0.11 ± 9% -0.1 0.05 perf-profile.children.cycles-pp.btrfs_unlink_inode 0.49 ± 4% -0.0 0.44 ± 4% perf-profile.children.cycles-pp.generic_permission 0.62 ± 4% -0.0 0.57 ± 3% perf-profile.children.cycles-pp.alloc_empty_file 0.58 ± 4% -0.0 0.54 ± 4% perf-profile.children.cycles-pp.inode_permission 0.59 ± 4% -0.0 0.54 ± 3% perf-profile.children.cycles-pp.__alloc_file 0.10 ± 15% -0.0 0.06 ± 7% perf-profile.children.cycles-pp.btrfs_truncate_inode_items 0.11 ± 11% -0.0 0.07 ± 7% perf-profile.children.cycles-pp.btrfs_commit_inode_delayed_inode 0.15 ± 3% -0.0 0.11 ± 6% perf-profile.children.cycles-pp.insert_with_overflow 0.11 ± 4% -0.0 0.08 ± 8% perf-profile.children.cycles-pp.btrfs_lookup 0.11 ± 4% -0.0 0.08 ± 8% perf-profile.children.cycles-pp.btrfs_lookup_dentry 0.16 ± 2% -0.0 0.14 ± 8% perf-profile.children.cycles-pp.btrfs_insert_dir_item 0.21 ± 4% -0.0 0.19 ± 3% perf-profile.children.cycles-pp.fput 0.16 ± 2% -0.0 0.14 ± 5% perf-profile.children.cycles-pp.btrfs_add_link 0.07 ± 5% -0.0 0.06 perf-profile.children.cycles-pp.btrfs_insert_inode_ref 0.01 ±173% +0.0 0.06 ± 14% perf-profile.children.cycles-pp.blk_done_softirq 0.01 ±173% +0.0 0.06 ± 14% perf-profile.children.cycles-pp.scsi_io_completion 0.01 ±173% +0.0 0.06 ± 14% perf-profile.children.cycles-pp.scsi_end_request 0.15 ± 15% +0.0 0.19 ± 2% perf-profile.children.cycles-pp.__vfs_write 0.15 ± 15% +0.0 0.20 ± 2% perf-profile.children.cycles-pp.vfs_write 0.13 ± 17% +0.1 0.18 ± 3% perf-profile.children.cycles-pp.btrfs_file_write_iter 0.01 ±173% +0.1 0.07 ± 13% perf-profile.children.cycles-pp.__filemap_fdatawrite_range 0.00 +0.1 0.05 ± 8% perf-profile.children.cycles-pp.btrfs_delayed_update_inode 0.15 ± 15% +0.1 0.20 ± 2% perf-profile.children.cycles-pp.ksys_write 0.00 +0.1 0.05 ± 9% perf-profile.children.cycles-pp.btrfs_release_path 0.05 ± 70% +0.1 0.11 ± 17% perf-profile.children.cycles-pp.__extent_writepage 0.05 ± 9% +0.1 0.11 ± 7% perf-profile.children.cycles-pp.setup_items_for_insert 0.00 +0.1 0.06 ± 14% perf-profile.children.cycles-pp.btrfs_set_token_32 0.00 +0.1 0.06 perf-profile.children.cycles-pp.btrfs_update_inode 0.06 ± 70% +0.1 0.12 ± 14% perf-profile.children.cycles-pp.extent_write_cache_pages 0.02 ±173% +0.1 0.08 ± 17% perf-profile.children.cycles-pp.btrfs_run_delayed_refs 0.02 ±173% +0.1 0.08 ± 17% perf-profile.children.cycles-pp.__btrfs_run_delayed_refs 0.04 ± 58% +0.1 0.10 ± 4% perf-profile.children.cycles-pp.read_block_for_search 0.00 +0.1 0.06 ± 6% perf-profile.children.cycles-pp.find_extent_buffer 0.03 ±100% +0.1 0.09 ± 7% perf-profile.children.cycles-pp.btrfs_get_token_32 0.09 ± 45% +0.1 0.15 ± 12% perf-profile.children.cycles-pp.do_writepages 0.01 ±173% +0.1 0.08 ± 17% perf-profile.children.cycles-pp.delayed_ref_async_start 0.00 +0.1 0.07 ± 6% perf-profile.children.cycles-pp.btrfs_del_items 0.06 ± 58% +0.1 0.13 ± 5% perf-profile.children.cycles-pp.btrfs_buffered_write 0.07 ± 68% +0.1 0.15 ± 14% perf-profile.children.cycles-pp.extent_writepages 0.00 +0.1 0.09 ± 4% perf-profile.children.cycles-pp.generic_bin_search 0.03 ±173% +0.1 0.12 ± 15% perf-profile.children.cycles-pp.run_one_async_start 0.03 ±173% +0.1 0.12 ± 15% perf-profile.children.cycles-pp.btrfs_submit_bio_start 0.03 ±173% +0.1 0.12 ± 15% perf-profile.children.cycles-pp.btrfs_csum_one_bio 0.03 ±173% +0.1 0.13 ± 14% perf-profile.children.cycles-pp.crc32c 0.00 +0.1 0.10 ± 4% perf-profile.children.cycles-pp.btrfs_try_tree_write_lock 0.00 +0.3 0.28 ± 4% perf-profile.children.cycles-pp.unlock_up 94.68 +0.7 95.39 perf-profile.children.cycles-pp.entry_SYSCALL_64_after_hwframe 94.63 +0.7 95.34 perf-profile.children.cycles-pp.do_syscall_64 48.36 +0.8 49.16 perf-profile.children.cycles-pp.do_sys_open 39.37 +1.0 40.36 perf-profile.children.cycles-pp.__alloc_fd 33.98 +1.5 35.45 perf-profile.children.cycles-pp.__x64_sys_close 33.78 +1.5 35.25 perf-profile.children.cycles-pp.__close_fd 79.40 +1.5 80.90 perf-profile.children.cycles-pp.native_queued_spin_lock_slowpath 78.19 +2.5 80.73 perf-profile.children.cycles-pp._raw_spin_lock 6.48 ± 12% -0.7 5.74 ± 2% perf-profile.self.cycles-pp.copy_user_enhanced_fast_string 2.26 ± 7% -0.3 2.00 ± 3% perf-profile.self.cycles-pp.file_free_rcu 1.16 ± 10% -0.2 1.00 ± 2% perf-profile.self.cycles-pp.generic_file_read_iter 0.23 ± 24% -0.1 0.12 ± 3% perf-profile.self.cycles-pp.queued_write_lock_slowpath 0.09 ± 24% -0.1 0.03 ±100% perf-profile.self.cycles-pp._raw_spin_lock_irqsave 0.34 ± 5% -0.0 0.30 ± 4% perf-profile.self.cycles-pp.__alloc_file 0.17 ± 2% +0.0 0.20 ± 2% perf-profile.self.cycles-pp.__alloc_fd 0.00 +0.1 0.05 ± 8% perf-profile.self.cycles-pp.generic_bin_search 0.01 ±173% +0.1 0.08 ± 6% perf-profile.self.cycles-pp.btrfs_get_token_32 0.03 ±173% +0.1 0.12 ± 12% perf-profile.self.cycles-pp.__crc32c_le 78.84 +1.5 80.29 perf-profile.self.cycles-pp.native_queued_spin_lock_slowpath blogbench.time.system_time 30000 +-+-----------------------------------------------------------------+ | | 25000 OO+OO O O OO OO OO OO OOO OO OO O OO OOO | |+.++.++.O+.O++.++.++.++.+++.++.++.O+.++.+++.++.++.++.+++.++.++.++.+| | | 20000 +-+ | | | 15000 +-+ | | | 10000 +-+ | | | | | 5000 +-+ | | | 0 +-+----O------------------------------------------------------------+ blogbench.time.percent_of_cpu_this_job_got 9000 +-+------------------------------------------------------------------+ OO.OO.O+.OO.OO.OO.OO.OOO.OO.OO.OO.OO.OO.OO.OO.+++.+ .++.+ .++.++.+ | 8000 +-+ + + + + + + + + +.+| 7000 +-+ | | | 6000 +-+ | 5000 +-+ | | | 4000 +-+ | 3000 +-+ | | | 2000 +-+ | 1000 +-+ | | | 0 +-+----O-------------------------------------------------------------+ blogbench.time.voluntary_context_switches 1.6e+07 +-+---------------------------------------------------------------+ |+.+ .++ +.+ +.+ ++. .+ + .++. | 1.4e+07 +-+ +.+++.++.+++.++ +.+ ++.++.+ ++.++.+ ++.+++ +.+ + +| 1.2e+07 +-+ | | | 1e+07 +-+ | OO OO O O OO OO OO OO OO OOO OO OO OOO OO | 8e+06 +-+ O O | | | 6e+06 +-+ | 4e+06 +-+ | | | 2e+06 +-+ | | | 0 +-+----O----------------------------------------------------------+ blogbench.time.file_system_outputs 8e+07 +-+-----------------------------------------------------------------+ OO OO O OO OOO OO OO OO OOO OO OO OO O OOO | 7e+07 +-+ O | 6e+07 +-+ | | | 5e+07 +-+ | | | 4e+07 +-+ .+ .++. +. +. .+ +. .+ .++ +. | |+.++ + +++.++.++.++.+++.+ + ++.++ + ++.++ + +.+ ++.++.+| 3e+07 +-+ | 2e+07 +-+ | | | 1e+07 +-+ | | | 0 +-+----O------------------------------------------------------------+ blogbench.write_score 8000 OO+-O-O--OO----------O-----------------------------------------------+ | O OO OO OO OO OO OO OO OO OO OO OO | 7000 +-+ | 6000 +-+ | | | 5000 +-+ | | | 4000 +-+++.++.++.++.++.++.+++.++.++.++.++.++.++.++.+++.++.++.++.++.++.++.+| | | 3000 +-+ | 2000 +-+ | | | 1000 +-+ | | | 0 +-+----O-------------------------------------------------------------+ [*] bisect-good sample [O] bisect-bad sample *************************************************************************************************** lkp-hsw-ep6: 56 threads Intel(R) Xeon(R) CPU E5-2695 v3 @ 2.30GHz with 256G memory ========================================================================================= bs/compiler/cpufreq_governor/disk/fs/ioengine/kconfig/nr_task/rootfs/runtime/rw/tbox_group/test_size/testcase/time_based/ucode: 4k/gcc-7/performance/2pmem/btrfs/mmap/x86_64-rhel-7.2/50%/debian-x86_64-2018-04-03.cgz/200s/randwrite/lkp-hsw-ep6/100G/fio-basic/tb/0x3d commit: 9b142115ed ("btrfs: dev-replace: remove pointless assert in write unlock") 5239834016 ("Btrfs: kill btrfs_clear_path_blocking") 9b142115ed359348 523983401644ebeb331c923c28 ---------------- -------------------------- fail:runs %reproduction fail:runs | | | :2 6% 0:2 perf-profile.children.cycles-pp.error_entry :2 50% 1:2 kmsg.pstore:crypto_comp_decompress_failed,ret= :2 50% 1:2 kmsg.pstore:decompression_failed 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. Thanks, Rong Chen

2 years, 4 months

2
1
0 / 0

088fe47ce9: tests/openmp/openmp_sample: Segmentation fault

by Rong Chen

FYI, we noticed the following commit (built with gcc-7): commit: 088fe47ce952542389e604e83f533811750aaf7c ("signal: Add calculate_sigpending()") base:https://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git master in testcase: perf_event_tests with following parameters: paranoid: disallow_cpu_events on test machine: Intel(R) Xeon(R) CPU E5-2699 v3 @ 2.30GHz with 128G memory caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace): * Checking OpenMP support + tests/openmp/openmp_test Testing OpenMP results... PASSED + tests/openmp/openmp_overflow + tests/openmp/openmp_sample * Checking proposed interface updates (not in any released kernel) To reproduce: git clonehttps://github.com/intel/lkp-tests.git cd lkp-tests bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email Thanks, Rong Chen

2 years, 4 months

2
2
0 / 0

93e221495a [ 63.598434] WARNING: CPU: 0 PID: 1 at lib/refcount.c:187 refcount_sub_and_test_checked

by kernel test robot

Greetings, 0day kernel testing robot got the below dmesg and the first bad commit is https://git.kernel.org/pub/scm/linux/kernel/git/frowand/linux.git v4.19-rc1--kfree_validate--v5 commit 93e221495a9fc099b183132e1fb3a28acc1b9dc8 Author: Frank Rowand <frank.rowand(a)sony.com> AuthorDate: Thu Oct 4 20:25:13 2018 -0700 Commit: Frank Rowand <frank.rowand(a)sony.com> CommitDate: Thu Oct 18 21:50:25 2018 -0700 of: overlay: add missing of_node_put() after add new node to changeset The refcount of a newly added overlay node decrements to one (instead of zero) when the overlay changeset is destroyed. This change will cause the final decrement be to zero. After applying this patch, new validation warnings will be reported from the devicetree unittest during boot due to a pre-existing devicetree bug. The warnings will be similar to: OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest4 This pre-existing devicetree bug will also trigger a WARN_ONCE() from refcount_sub_and_test_checked() when an overlay changeset is destroyed without having first been applied. This scenario occurs when an error in the overlay is detected during the overlay changeset creation: WARNING: CPU: 0 PID: 1 at lib/refcount.c:187 refcount_sub_and_test_checked+0xa8/0xbc refcount_t: underflow; use-after-free. (unwind_backtrace) from (show_stack+0x10/0x14) (show_stack) from (dump_stack+0x6c/0x8c) (dump_stack) from (__warn+0xdc/0x104) (__warn) from (warn_slowpath_fmt+0x44/0x6c) (warn_slowpath_fmt) from (refcount_sub_and_test_checked+0xa8/0xbc) (refcount_sub_and_test_checked) from (kobject_put+0x24/0x208) (kobject_put) from (of_changeset_destroy+0x2c/0xb4) (of_changeset_destroy) from (free_overlay_changeset+0x1c/0x9c) (free_overlay_changeset) from (of_overlay_remove+0x284/0x2cc) (of_overlay_remove) from (of_unittest_apply_revert_overlay_check.constprop.4+0xf8/0x1e8) (of_unittest_apply_revert_overlay_check.constprop.4) from (of_unittest_overlay+0x960/0xed8) (of_unittest_overlay) from (of_unittest+0x1cc4/0x2138) (of_unittest) from (do_one_initcall+0x4c/0x28c) (do_one_initcall) from (kernel_init_freeable+0x29c/0x378) (kernel_init_freeable) from (kernel_init+0x8/0x110) (kernel_init) from (ret_from_fork+0x14/0x2c) Signed-off-by: Frank Rowand <frank.rowand(a)sony.com> 86043f08e5 of: overlay: add tests to validate kfrees from overlay removal 93e221495a of: overlay: add missing of_node_put() after add new node to changeset 62e8f28bb1 of: unittest: initialize args before calling of_*parse_*() +----------------------------------------------------------+------------+------------+------------+ | | 86043f08e5 | 93e221495a | 62e8f28bb1 | +----------------------------------------------------------+------------+------------+------------+ | boot_successes | 33 | 0 | 11 | | boot_failures | 0 | 13 | | | WARNING:at_lib/refcount.c:#refcount_sub_and_test_checked | 0 | 13 | | | EIP:refcount_sub_and_test_checked | 0 | 13 | | +----------------------------------------------------------+------------+------------+------------+ [ 63.590314] OF: overlay: node_overlaps_later_cs: #6 overlaps with #7 @/testcase-data/overlay-node/test-bus/test-unittest8 [ 63.591587] OF: overlay: overlay #6 is not topmost [ 63.596131] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest11/test-unittest111 [ 63.597230] ------------[ cut here ]------------ [ 63.597726] refcount_t: underflow; use-after-free. [ 63.598434] WARNING: CPU: 0 PID: 1 at lib/refcount.c:187 refcount_sub_and_test_checked+0x111/0x130 [ 63.599756] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.19.0-rc1-00002-g93e2214 #1 [ 63.600651] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014 [ 63.601626] EIP: refcount_sub_and_test_checked+0x111/0x130 [ 63.602250] Code: b8 e8 33 59 c0 ff 6a 01 31 c9 ba 01 00 00 00 b8 30 64 da b8 c6 05 74 3b d4 b8 01 e8 19 59 c0 ff 68 94 3f ab b8 e8 6f 73 b1 ff <0f> 0b 6a 01 31 c9 ba 01 00 00 00 b8 18 64 da b8 e8 fa 58 c0 ff 83 [ 63.604436] EAX: 00000026 EBX: bc9466c4 ECX: 00000000 EDX: 00000000 [ 63.605165] ESI: 00000000 EDI: ffffffff EBP: bf6efe18 ESP: bf6efdf0 [ 63.605897] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068 EFLAGS: 00210296 [ 63.619093] CR0: 80050033 CR2: 00000000 CR3: 08f5a000 CR4: 00140690 [ 63.619843] DR0: 00000000 DR1: 00000000 DR2: 00000000 DR3: 00000000 [ 63.620573] DR6: fffe0ff0 DR7: 00000400 [ 63.621022] Call Trace: [ 63.621317] ? kfree+0x318/0x400 [ 63.621686] refcount_dec_and_test_checked+0xf/0x20 [ 63.622230] kobject_put+0x1a/0xe0 [ 63.622627] of_changeset_destroy+0x2c/0xa0 [ 63.623140] free_overlay_changeset+0x1c/0x90 [ 63.623655] of_overlay_remove+0x1e8/0x250 [ 63.624135] of_unittest_apply_revert_overlay_check+0xf2/0x1c0 [ 63.624935] of_unittest+0x17ea/0x1d38 [ 63.625380] ? dt_alloc_memory+0x18/0x18 [ 63.625822] do_one_initcall+0x11c/0x313 [ 63.638684] ? rcu_read_unlock_sched_notrace+0x24/0x33 [ 63.639298] ? trace_initcall_level+0xe2/0x106 [ 63.639850] kernel_init_freeable+0x10e/0x18d [ 63.640371] ? rest_init+0xb0/0xb0 [ 63.640764] kernel_init+0xd/0x100 [ 63.641157] ret_from_fork+0x2e/0x40 [ 63.641571] irq event stamp: 3315206 [ 63.641993] hardirqs last enabled at (3315205): [<b8530638>] _raw_spin_unlock_irq+0x28/0x90 [ 63.643023] hardirqs last disabled at (3315206): [<b74014b6>] trace_hardirqs_off_thunk+0xc/0x10 [ 63.644074] softirqs last enabled at (3315200): [<b85348c6>] __do_softirq+0x436/0x54e [ 63.645005] softirqs last disabled at (3315189): [<b741e685>] call_on_stack+0x45/0x50 [ 63.645941] ---[ end trace 3297e2f67e97bc24 ]--- [ 63.649245] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest11 # HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD git bisect start a6a1221ebe38ab852e6cb825ee0a0fe48048957c 84df9525b0c27f3ebc2ebb1864fa62a97fdedb7d -- git bisect good 011075e1a53725d4ebb47e485f6eb039b9bfe05b # 07:54 G 11 0 0 0 Merge 'linux-review/Nathan-Chancellor/scsi-3w-sas-9xxx-Use-unsigned-char-for-cdb/20181019-181224' into devel-spot-201810230033 git bisect good 9b4b01c1f34a4d0794cf81b90adc0773977c2368 # 07:54 G 11 0 0 0 Merge 'linux-review/Christian-Ehrhardt/KVM-x86-Only-clear-dr6-if-really-neccessary/20181017-103455' into devel-spot-201810230033 git bisect bad 9c5135d26d7780118d774228427a1d380b071fd3 # 07:54 B 0 13 27 0 Merge 'saeed/net-next-mlx5' into devel-spot-201810230033 git bisect good 2dc0e152792a874d6442dfcbf1e35c8da1a297ad # 07:54 G 11 0 0 0 Merge 'mtd/mtd/next' into devel-spot-201810230033 git bisect good 97fa7047807e6c626ee9fa94355c0c0c1f2cf186 # 07:54 G 11 0 0 0 Merge 'tip/x86/mm' into devel-spot-201810230033 git bisect bad f3cc93d321783c7bea7f2db1544dd025da340d6f # 07:55 B 0 13 27 0 Merge 'kvmarm/merge-resolution-4.20' into devel-spot-201810230033 git bisect good db4ceefd37f228a04b46daea7879ce04cc490b0a # 07:55 G 11 0 0 0 Merge 'regulator/for-4.20' into devel-spot-201810230033 git bisect bad 3a849d1d75f080b5a99156eb6ec53d1920574a09 # 07:55 B 0 11 25 0 Merge 'frowand/v4.19-rc1--kfree_validate--v5' into devel-spot-201810230033 git bisect bad 93e221495a9fc099b183132e1fb3a28acc1b9dc8 # 07:55 B 0 13 43 0 of: overlay: add missing of_node_put() after add new node to changeset git bisect good 86043f08e539a104e9c056b82f1096fd442d1d45 # 07:55 G 31 0 0 0 of: overlay: add tests to validate kfrees from overlay removal # first bad commit: [93e221495a9fc099b183132e1fb3a28acc1b9dc8] of: overlay: add missing of_node_put() after add new node to changeset git bisect good 86043f08e539a104e9c056b82f1096fd442d1d45 # 07:55 G 31 0 0 0 of: overlay: add tests to validate kfrees from overlay removal # extra tests on HEAD of linux-devel/devel-spot-201810230033 git bisect bad a6a1221ebe38ab852e6cb825ee0a0fe48048957c # 07:56 B 0 23 40 0 0day head guard for 'devel-spot-201810230033' # extra tests on tree/branch frowand/v4.19-rc1--kfree_validate--v5 git bisect good 62e8f28bb14b916f538268213b87d760b1ee6adc # 07:56 G 11 0 0 0 of: unittest: initialize args before calling of_*parse_*() --- 0-DAY kernel test infrastructure Open Source Technology Center https://lists.01.org/pipermail/lkp Intel Corporation

2 years, 4 months

1
0
0 / 0

[kernfs, sysfs, cgroup, intel_rdt] e62016662d: cgroup1: Unknown subsys name '__DEVEL__sane_behavior'

by kernel test robot

FYI, we noticed the following commit (built with gcc-7): commit: e62016662d0971f63dd4c1a482aeb7ca53df8aca ("kernfs, sysfs, cgroup, intel_rdt: Support fs_context") https://git.kernel.org/cgit/linux/kernel/git/next/linux-next.git master in testcase: phoronix-test-suite with following parameters: need_x: true test: tesseract-1.1.0 cpufreq_governor: performance test-description: The Phoronix Test Suite is the most comprehensive testing and benchmarking platform available that provides an extensible framework for which new tests can be easily added. test-url: http://www.phoronix-test-suite.com/ on test machine: 16 threads Intel(R) Xeon(R) CPU X5570 @ 2.93GHz with 48G memory caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace): user :notice: [ 26.169044 ] Kernel tests: Boot OK! kern :err : [ 26.316791 ] cgroup1: Unknown subsys name '__DEVEL__sane_behavior' kern :err : [ 26.493821 ] cgroup1: Unknown subsys name '__DEVEL__sane_behavior' kern :err : [ 26.666249 ] cgroup1: Unknown subsys name '__DEVEL__sane_behavior' kern :err : [ 26.814922 ] cgroup1: Unknown subsys name '__DEVEL__sane_behavior' kern :err : [ 27.068700 ] cgroup1: Unknown subsys name '__DEVEL__sane_behavior' To reproduce: git clone https://github.com/intel/lkp-tests.git cd lkp-tests bin/lkp install job.yaml # job file is attached in this email bin/lkp run job.yaml Thanks, Rong Chen

2 years, 4 months

1
0
0 / 0

Re: [LKP] 8cb682da6b ("rcu: Affinity forward-progress test to avoid .."): BUG: unable to handle kernel NULL pointer dereference at 00000000

by Rong Chen

On 10/22/2018 09:29 PM, Paul E. McKenney wrote: > On Mon, Oct 22, 2018 at 05:10:38PM +0800, Rong Chen wrote: >> >> On 10/18/2018 10:01 PM, Paul E. McKenney wrote: >>> On Thu, Oct 18, 2018 at 05:31:16PM +0800, kernel test robot wrote: >>>> On Tue, Oct 16, 2018 at 05:35:31AM -0700, Paul E. McKenney wrote: >>>>> On Tue, Oct 16, 2018 at 08:36:57AM +0800, kernel test robot wrote: >>>>>> Greetings, >>>>>> >>>>>> 0day kernel testing robot got the below dmesg and the first bad commit is >>>>>> >>>>>> https://git.kernel.org/pub/scm/linux/kernel/git/paulmck/linux-rcu.git dev.2018.10.09a >>>>>> >>>>>> commit 8cb682da6b49f65c3acf4255d045430cec116c52 >>>>>> Author: Paul E. McKenney <paulmck(a)linux.ibm.com> >>>>>> AuthorDate: Fri Sep 21 18:08:09 2018 -0700 >>>>>> Commit: Paul E. McKenney <paulmck(a)linux.ibm.com> >>>>>> CommitDate: Wed Oct 3 17:36:12 2018 -0700 >>>>>> >>>>>> rcu: Affinity forward-progress test to avoid housekeeping CPUs >>>>>> This commit affinities the forward-progress tests to avoid hogging a >>>>>> housekeeping CPU on the theory that the offloaded callbacks will be >>>>>> running on those housekeeping CPUs. >>>>>> Signed-off-by: Paul E. McKenney <paulmck(a)linux.ibm.com> >>>>>> >>>>>> 61fe6c84c4 MAINTAINERS: Update from @linux.vnet.ibm.com to @linux.ibm.com >>>>>> 8cb682da6b rcu: Affinity forward-progress test to avoid housekeeping CPUs >>>>>> +------------------------------------------+------------+------------+ >>>>>> | | 61fe6c84c4 | 8cb682da6b | >>>>>> +------------------------------------------+------------+------------+ >>>>>> | boot_successes | 37 | 0 | >>>>>> | boot_failures | 0 | 13 | >>>>>> | BUG:unable_to_handle_kernel | 0 | 13 | >>>>>> | Oops:#[##] | 0 | 13 | >>>>>> | EIP:__bitmap_weight | 0 | 13 | >>>>>> | Kernel_panic-not_syncing:Fatal_exception | 0 | 13 | >>>>>> +------------------------------------------+------------+------------+ >>>>>> >>>>>> [ 1.414107] rcu-torture: torture_stutter task started >>>>>> [ 1.415593] Kprobe smoke test: started >>>>>> [ 1.487589] Kprobe smoke test: passed successfully >>>>>> [ 1.488532] Key type blacklist registered >>>>>> [ 1.492230] rcu-torture: rcu_torture_fwd_progress task started >>>>>> [ 1.493190] BUG: unable to handle kernel NULL pointer dereference at 00000000 >>>>>> [ 1.494373] *pde = 00000000 >>>>>> [ 1.494857] Oops: 0000 [#1] SMP DEBUG_PAGEALLOC PTI >>>>>> [ 1.495277] CPU: 0 PID: 47 Comm: rcu_torture_fwd Tainted: G T 4.19.0-rc1-00167-g8cb682d #2 >>>>>> [ 1.495277] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014 >>>>>> [ 1.495277] EIP: __bitmap_weight+0x44/0x61 >>>>>> [ 1.495277] Code: 90 01 c3 83 c1 01 39 f1 75 ef f6 c2 1f 0f 95 c0 0f b6 c8 83 04 8d 7c 57 13 48 01 84 c0 74 15 f7 da 89 d1 b8 ff ff ff ff d3 e8 <23> 04 b7 f3 0f b8 c0 90 01 c3 89 d8 5b 5e 5f 5d c3 85 d2 0f 95 c0 >>>>>> [ 1.495277] EAX: 00000001 EBX: 00000000 ECX: ffffffff EDX: ffffffff >>>>>> [ 1.495277] ESI: 00000000 EDI: 00000000 EBP: 4df25ef0 ESP: 4df25ee4 >>>>>> [ 1.495277] DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068 EFLAGS: 00210a03 >>>>>> [ 1.495277] CR0: 80050033 CR2: 00000000 CR3: 0853e000 CR4: 000406d0 >>>>>> [ 1.495277] Call Trace: >>>>>> [ 1.495277] ? rcu_busted_torture_deferred_free+0xf/0xf >>>>> If you are using the "busted" torture type, you should expect failures. >>>>> But I must confess that I would not have expected this one. >>>>> >>>>>> [ 1.495277] ? rcu_bind_current_to_nocb+0x1d/0xaa >>>>>> [ 1.495277] ? rcu_busted_torture_deferred_free+0xf/0xf >>>>>> [ 1.495277] ? rcu_torture_fwd_prog+0x3a/0x7d5 >>>>> Ouch. It looks like I should disable forward-progress testing when >>>>> someone is using the "busted" torture type. >>>>> >>>>> First, how about the patch below? >>>> the patch still has the same error. >>> Just to make sure that I understand your setup, you are using >>> rcutorture.torture_type=busted in this test, right? >> It's a boot test and we didn't add rcutorture.torture_type=busted. >> >> Kernel command line: root=/dev/ram0 hung_task_panic=1 debug >> apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 >> net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 >> nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 >> drbd.minor_count=8 systemd.log_level=err ignore_loglevel >> console=tty0 earlyprintk=ttyS0,115200 console=ttyS0,115200 >> vga=normal rw link=/cephfs/kbuild/run-queue/kvm/i386-randconfig-b0-10141817/linux-devel:fixup-8cb682da6b49f65c3acf4255d045430cec116c52:1e8e4d7740d4c9bc2c88a2e5beb27b5ef5215f43/.vmlinuz-1e8e4d7740d4c9bc2c88a2e5beb27b5ef5215f43-20181019144822-106:openwrt-lkp-hsw01-103 >> branch=linux-devel/fixup-8cb682da6b49f65c3acf4255d045430cec116c52 BOOT_IMAGE=/pkg/linux/i386-randconfig-b0-10141817/gcc-4.9/1e8e4d7740d4c9bc2c88a2e5beb27b5ef5215f43/vmlinuz-4.19.0-rc1-00168-g1e8e4d7 >> drbd.minor_count=8 rcuperf.shutdown=0 >> >>>>> Second, I really do need destructive tests whose whole purpose is to >>>>> demonstrate that rcutorture can force failures to happen. So how should >>>>> I communicate to you guys which Kconfig options and boot parameters >>>>> are expected to result in failures? An RCU_DESTRUCTIVE_TEST Kconfig or >>>>> some such? >>>> an kconfig is fine, or we could ignore the errors if they are changeless, >>>> we will only check the result(SUCCESS/FAIL) if the test is destructive. >>> Ah, and I got distracted by the "busted" and what that would mean to the >>> forward-progress test. The actual bug happens earlier and much more >>> simply in kernels that have large var_cpumask_t structures and that have >>> RCU callback offloading. >>> >>> So how does the patch below (with or without the preceding one) work >>> for you? >>> >> The patch works. > Very good, and please accept my apologies for my earlier confusion. > May I add your Tested-by? Yes, It's my pleasure. Best Regards, Rong Chen

2 years, 4 months

1
0
0 / 0

Re: [LKP] 8cb682da6b ("rcu: Affinity forward-progress test to avoid .."): BUG: unable to handle kernel NULL pointer dereference at 00000000

by Rong Chen

On 10/18/2018 10:01 PM, Paul E. McKenney wrote: > On Thu, Oct 18, 2018 at 05:31:16PM +0800, kernel test robot wrote: >> On Tue, Oct 16, 2018 at 05:35:31AM -0700, Paul E. McKenney wrote: >>> On Tue, Oct 16, 2018 at 08:36:57AM +0800, kernel test robot wrote: >>>> Greetings, >>>> >>>> 0day kernel testing robot got the below dmesg and the first bad commit is >>>> >>>> https://git.kernel.org/pub/scm/linux/kernel/git/paulmck/linux-rcu.git dev.2018.10.09a >>>> >>>> commit 8cb682da6b49f65c3acf4255d045430cec116c52 >>>> Author: Paul E. McKenney <paulmck(a)linux.ibm.com> >>>> AuthorDate: Fri Sep 21 18:08:09 2018 -0700 >>>> Commit: Paul E. McKenney <paulmck(a)linux.ibm.com> >>>> CommitDate: Wed Oct 3 17:36:12 2018 -0700 >>>> >>>> rcu: Affinity forward-progress test to avoid housekeeping CPUs >>>> >>>> This commit affinities the forward-progress tests to avoid hogging a >>>> housekeeping CPU on the theory that the offloaded callbacks will be >>>> running on those housekeeping CPUs. >>>> >>>> Signed-off-by: Paul E. McKenney <paulmck(a)linux.ibm.com> >>>> >>>> 61fe6c84c4 MAINTAINERS: Update from @linux.vnet.ibm.com to @linux.ibm.com >>>> 8cb682da6b rcu: Affinity forward-progress test to avoid housekeeping CPUs >>>> +------------------------------------------+------------+------------+ >>>> | | 61fe6c84c4 | 8cb682da6b | >>>> +------------------------------------------+------------+------------+ >>>> | boot_successes | 37 | 0 | >>>> | boot_failures | 0 | 13 | >>>> | BUG:unable_to_handle_kernel | 0 | 13 | >>>> | Oops:#[##] | 0 | 13 | >>>> | EIP:__bitmap_weight | 0 | 13 | >>>> | Kernel_panic-not_syncing:Fatal_exception | 0 | 13 | >>>> +------------------------------------------+------------+------------+ >>>> >>>> [ 1.414107] rcu-torture: torture_stutter task started >>>> [ 1.415593] Kprobe smoke test: started >>>> [ 1.487589] Kprobe smoke test: passed successfully >>>> [ 1.488532] Key type blacklist registered >>>> [ 1.492230] rcu-torture: rcu_torture_fwd_progress task started >>>> [ 1.493190] BUG: unable to handle kernel NULL pointer dereference at 00000000 >>>> [ 1.494373] *pde = 00000000 >>>> [ 1.494857] Oops: 0000 [#1] SMP DEBUG_PAGEALLOC PTI >>>> [ 1.495277] CPU: 0 PID: 47 Comm: rcu_torture_fwd Tainted: G T 4.19.0-rc1-00167-g8cb682d #2 >>>> [ 1.495277] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014 >>>> [ 1.495277] EIP: __bitmap_weight+0x44/0x61 >>>> [ 1.495277] Code: 90 01 c3 83 c1 01 39 f1 75 ef f6 c2 1f 0f 95 c0 0f b6 c8 83 04 8d 7c 57 13 48 01 84 c0 74 15 f7 da 89 d1 b8 ff ff ff ff d3 e8 <23> 04 b7 f3 0f b8 c0 90 01 c3 89 d8 5b 5e 5f 5d c3 85 d2 0f 95 c0 >>>> [ 1.495277] EAX: 00000001 EBX: 00000000 ECX: ffffffff EDX: ffffffff >>>> [ 1.495277] ESI: 00000000 EDI: 00000000 EBP: 4df25ef0 ESP: 4df25ee4 >>>> [ 1.495277] DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068 EFLAGS: 00210a03 >>>> [ 1.495277] CR0: 80050033 CR2: 00000000 CR3: 0853e000 CR4: 000406d0 >>>> [ 1.495277] Call Trace: >>>> [ 1.495277] ? rcu_busted_torture_deferred_free+0xf/0xf >>> If you are using the "busted" torture type, you should expect failures. >>> But I must confess that I would not have expected this one. >>> >>>> [ 1.495277] ? rcu_bind_current_to_nocb+0x1d/0xaa >>>> [ 1.495277] ? rcu_busted_torture_deferred_free+0xf/0xf >>>> [ 1.495277] ? rcu_torture_fwd_prog+0x3a/0x7d5 >>> Ouch. It looks like I should disable forward-progress testing when >>> someone is using the "busted" torture type. >>> >>> First, how about the patch below? >> the patch still has the same error. > Just to make sure that I understand your setup, you are using > rcutorture.torture_type=busted in this test, right? It's a boot test and we didn't add rcutorture.torture_type=busted. Kernel command line: root=/dev/ram0 hung_task_panic=1 debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 drbd.minor_count=8 systemd.log_level=err ignore_loglevel console=tty0 earlyprintk=ttyS0,115200 console=ttyS0,115200 vga=normal rw link=/cephfs/kbuild/run-queue/kvm/i386-randconfig-b0-10141817/linux-devel:fixup-8cb682da6b49f65c3acf4255d045430cec116c52:1e8e4d7740d4c9bc2c88a2e5beb27b5ef5215f43/.vmlinuz-1e8e4d7740d4c9bc2c88a2e5beb27b5ef5215f43-20181019144822-106:openwrt-lkp-hsw01-103 branch=linux-devel/fixup-8cb682da6b49f65c3acf4255d045430cec116c52 BOOT_IMAGE=/pkg/linux/i386-randconfig-b0-10141817/gcc-4.9/1e8e4d7740d4c9bc2c88a2e5beb27b5ef5215f43/vmlinuz-4.19.0-rc1-00168-g1e8e4d7 drbd.minor_count=8 rcuperf.shutdown=0 > >>> Second, I really do need destructive tests whose whole purpose is to >>> demonstrate that rcutorture can force failures to happen. So how should >>> I communicate to you guys which Kconfig options and boot parameters >>> are expected to result in failures? An RCU_DESTRUCTIVE_TEST Kconfig or >>> some such? >> an kconfig is fine, or we could ignore the errors if they are changeless, >> we will only check the result(SUCCESS/FAIL) if the test is destructive. > Ah, and I got distracted by the "busted" and what that would mean to the > forward-progress test. The actual bug happens earlier and much more > simply in kernels that have large var_cpumask_t structures and that have > RCU callback offloading. > > So how does the patch below (with or without the preceding one) work > for you? > > Thanx, Paul The patch works. Best Regards, Rong Chen > > ------------------------------------------------------------------------- > > diff --git a/kernel/rcu/tree_plugin.h b/kernel/rcu/tree_plugin.h > index 117aeb582fdc..1d74bf8b47f3 100644 > --- a/kernel/rcu/tree_plugin.h > +++ b/kernel/rcu/tree_plugin.h > @@ -2595,7 +2595,7 @@ static bool init_nocb_callback_list(struct rcu_data *rdp) > */ > void rcu_bind_current_to_nocb(void) > { > - if (cpumask_weight(rcu_nocb_mask)) > + if (cpumask_available(rcu_nocb_mask) && cpumask_weight(rcu_nocb_mask)) > WARN_ON(sched_setaffinity(current->pid, rcu_nocb_mask)); > } > EXPORT_SYMBOL_GPL(rcu_bind_current_to_nocb); >

2 years, 4 months

1
0
0 / 0

[cpuidle] 23e8ceb9ce: will-it-scale.per_process_ops 8.9% improvement

by kernel test robot

Greeting, FYI, we noticed a 8.9% improvement of will-it-scale.per_process_ops due to commit: commit: 23e8ceb9ce766c81d62434053aef6e7efea6fcc3 ("cpuidle: menu: Compute first_idx when latency_req is known") https://git.kernel.org/cgit/linux/kernel/git/next/linux-next.git master in testcase: will-it-scale on test machine: 88 threads Intel(R) Xeon(R) CPU E5-2699 v4 @ 2.20GHz with 64G memory with following parameters: nr_task: 16 mode: process test: context_switch1 cpufreq_governor: performance test-description: Will It Scale takes a testcase and runs it from 1 through to n parallel copies to see if the testcase will scale. It builds both a process and threads based test in order to see any differences between the two. test-url: https://github.com/antonblanchard/will-it-scale Details are as below: --------------------------------------------------------------------------------------------------> To reproduce: git clone https://github.com/intel/lkp-tests.git cd lkp-tests bin/lkp install job.yaml # job file is attached in this email bin/lkp run job.yaml ========================================================================================= compiler/cpufreq_governor/kconfig/mode/nr_task/rootfs/tbox_group/test/testcase: gcc-7/performance/x86_64-rhel-7.2/process/16/debian-x86_64-2018-04-03.cgz/lkp-bdw-ep3d/context_switch1/will-it-scale commit: 5f26bdceb9 ("cpuidle: menu: Fix wakeup statistics updates for polling state") 23e8ceb9ce ("cpuidle: menu: Compute first_idx when latency_req is known") 5f26bdceb9c0a5e6 23e8ceb9ce766c81d62434053a ---------------- -------------------------- fail:runs %reproduction fail:runs | | | :4 50% 2:4 dmesg.WARNING:at_ip_fsnotify/0x 1:4 -25% :4 kmsg.DHCP/BOOTP:Reply_not_for_us_on_eth#,op[#]xid[#] %stddev %change %stddev \ | \ 343546 +8.9% 374136 will-it-scale.per_process_ops 5496750 +8.9% 5986197 will-it-scale.workload 8420559 +10.9% 9337969 vmstat.system.cs 89360 ± 2% +930.3% 920646 ± 3% vmstat.system.in 23.79 -8.3 15.53 ± 4% mpstat.cpu.idle% 69.01 +7.5 76.48 mpstat.cpu.sys% 7.19 +0.8 7.98 mpstat.cpu.usr% 7e+08 -100.0% 150525 ± 13% cpuidle.C1.time 8.899e+08 -100.0% 48417 cpuidle.C1.usage 5462325 ± 13% +17.7% 6429151 ± 4% cpuidle.POLL.time 14645584 ± 15% +4307.0% 6.454e+08 ± 3% cpuidle.POLL.usage 2982 ± 4% -19.9% 2388 ± 3% slabinfo.kmalloc-512.active_objs 2998 ± 5% -17.6% 2471 ± 3% slabinfo.kmalloc-512.num_objs 762.50 ± 9% -19.2% 616.25 ± 16% slabinfo.task_group.active_objs 762.50 ± 9% -19.2% 616.25 ± 16% slabinfo.task_group.num_objs 2883 +5.5% 3043 turbostat.Avg_MHz 8.899e+08 -100.0% 47808 turbostat.C1 9.55 -9.6 0.00 turbostat.C1% 5.92 ± 5% -89.8% 0.60 ± 41% turbostat.CPU%c1 27397006 +934.0% 2.833e+08 ± 3% turbostat.IRQ 151.20 +1.4% 153.26 turbostat.PkgWatt 60181 ± 3% +128.7% 137619 ± 2% sched_debug.cfs_rq:/.min_vruntime.stddev 80169 ± 17% +141.5% 193594 ± 6% sched_debug.cfs_rq:/.spread0.avg 170633 ± 15% +89.8% 323899 ± 5% sched_debug.cfs_rq:/.spread0.max 60168 ± 3% +128.7% 137608 ± 2% sched_debug.cfs_rq:/.spread0.stddev 17.88 ± 8% +16.6% 20.83 ± 3% sched_debug.cpu.cpu_load[4].min 984.49 ± 2% +9.5% 1078 ± 3% sched_debug.cpu.curr->pid.avg 53357881 +11.2% 59314624 sched_debug.cpu.nr_switches.avg 58617424 +15.3% 67562245 sched_debug.cpu.nr_switches.max 6233628 +62.8% 10147512 sched_debug.cpu.nr_switches.stddev 3.223e+12 +10.2% 3.551e+12 perf-stat.branch-instructions 1.44 +0.1 1.51 perf-stat.branch-miss-rate% 4.653e+10 +15.6% 5.376e+10 perf-stat.branch-misses 2.585e+09 +11.2% 2.874e+09 perf-stat.context-switches 1.37 -8.2% 1.26 perf-stat.cpi 0.01 ± 17% -0.0 0.01 ± 13% perf-stat.dTLB-load-miss-rate% 4.595e+12 +9.5% 5.031e+12 perf-stat.dTLB-loads 2.901e+12 +9.2% 3.167e+12 perf-stat.dTLB-stores 4.203e+10 -30.7% 2.912e+10 perf-stat.iTLB-loads 1.614e+13 +9.6% 1.768e+13 perf-stat.instructions 0.73 +8.9% 0.79 perf-stat.ipc 13.48 -13.5 0.00 perf-profile.calltrace.cycles-pp.intel_idle.cpuidle_enter_state.do_idle.cpu_startup_entry.start_secondary 14.14 -10.6 3.59 ± 8% perf-profile.calltrace.cycles-pp.cpuidle_enter_state.do_idle.cpu_startup_entry.start_secondary.secondary_startup_64 28.40 ± 2% -7.3 21.15 perf-profile.calltrace.cycles-pp.do_idle.cpu_startup_entry.start_secondary.secondary_startup_64 28.48 ± 2% -7.2 21.33 perf-profile.calltrace.cycles-pp.cpu_startup_entry.start_secondary.secondary_startup_64 28.48 ± 2% -7.1 21.34 perf-profile.calltrace.cycles-pp.secondary_startup_64 28.48 ± 2% -7.1 21.34 perf-profile.calltrace.cycles-pp.start_secondary.secondary_startup_64 1.37 ± 3% -0.1 1.25 perf-profile.calltrace.cycles-pp.cpumask_next_wrap.select_idle_sibling.select_task_rq_fair.try_to_wake_up.autoremove_wake_function 0.71 +0.0 0.74 ± 3% perf-profile.calltrace.cycles-pp.touch_atime.pipe_read.__vfs_read.vfs_read.ksys_read 0.56 ± 4% +0.0 0.61 perf-profile.calltrace.cycles-pp.finish_task_switch.__sched_text_start.schedule.pipe_wait.pipe_read 0.60 ± 3% +0.1 0.66 perf-profile.calltrace.cycles-pp.update_cfs_group.enqueue_entity.enqueue_task_fair.ttwu_do_activate.try_to_wake_up 0.67 +0.1 0.72 ± 2% perf-profile.calltrace.cycles-pp.prepare_to_wait.pipe_wait.pipe_read.__vfs_read.vfs_read 0.75 ± 2% +0.1 0.81 ± 2% perf-profile.calltrace.cycles-pp.__wake_up_common_lock.pipe_read.__vfs_read.vfs_read.ksys_read 0.58 ± 2% +0.1 0.65 ± 2% perf-profile.calltrace.cycles-pp.mutex_lock.pipe_write.__vfs_write.vfs_write.ksys_write 0.77 ± 3% +0.1 0.84 ± 2% perf-profile.calltrace.cycles-pp.file_has_perm.security_file_permission.vfs_write.ksys_write.do_syscall_64 0.98 +0.1 1.05 ± 2% perf-profile.calltrace.cycles-pp.selinux_file_permission.security_file_permission.vfs_read.ksys_read.do_syscall_64 0.99 +0.1 1.06 perf-profile.calltrace.cycles-pp.selinux_file_permission.security_file_permission.vfs_write.ksys_write.do_syscall_64 0.55 ± 3% +0.1 0.63 ± 3% perf-profile.calltrace.cycles-pp.mutex_lock.pipe_read.__vfs_read.vfs_read.ksys_read 0.71 ± 5% +0.1 0.80 perf-profile.calltrace.cycles-pp.copyin.copy_page_from_iter.pipe_write.__vfs_write.vfs_write 0.70 ± 3% +0.1 0.78 ± 2% perf-profile.calltrace.cycles-pp.file_has_perm.security_file_permission.vfs_read.ksys_read.do_syscall_64 0.54 ± 2% +0.1 0.62 perf-profile.calltrace.cycles-pp.update_cfs_group.dequeue_entity.dequeue_task_fair.__sched_text_start.schedule 1.12 +0.1 1.22 ± 2% perf-profile.calltrace.cycles-pp.native_write_msr 0.95 ± 4% +0.1 1.05 perf-profile.calltrace.cycles-pp.load_new_mm_cr3.switch_mm_irqs_off.switch_mm.__sched_text_start.schedule 1.16 ± 2% +0.1 1.27 ± 2% perf-profile.calltrace.cycles-pp.check_preempt_curr.ttwu_do_wakeup.try_to_wake_up.autoremove_wake_function.__wake_up_common 1.76 ± 2% +0.1 1.87 perf-profile.calltrace.cycles-pp.switch_mm_irqs_off.__sched_text_start.schedule_idle.do_idle.cpu_startup_entry 1.32 ± 2% +0.1 1.45 perf-profile.calltrace.cycles-pp.ttwu_do_wakeup.try_to_wake_up.autoremove_wake_function.__wake_up_common.__wake_up_common_lock 0.62 ± 2% +0.1 0.76 ± 3% perf-profile.calltrace.cycles-pp.tick_nohz_idle_enter.do_idle.cpu_startup_entry.start_secondary.secondary_startup_64 0.56 ± 7% +0.1 0.70 perf-profile.calltrace.cycles-pp.hrtimer_next_event_without.tick_nohz_get_sleep_length.menu_select.do_idle.cpu_startup_entry 1.89 +0.1 2.04 perf-profile.calltrace.cycles-pp.security_file_permission.vfs_write.ksys_write.do_syscall_64.entry_SYSCALL_64_after_hwframe 0.87 ± 4% +0.2 1.05 ± 2% perf-profile.calltrace.cycles-pp.find_next_bit.__next_timer_interrupt.get_next_timer_interrupt.tick_nohz_next_event.tick_nohz_get_sleep_length 1.07 ± 2% +0.2 1.25 perf-profile.calltrace.cycles-pp.update_curr.dequeue_entity.dequeue_task_fair.__sched_text_start.schedule 1.94 +0.2 2.12 perf-profile.calltrace.cycles-pp.copy_page_to_iter.pipe_read.__vfs_read.vfs_read.ksys_read 1.99 +0.2 2.18 perf-profile.calltrace.cycles-pp.security_file_permission.vfs_read.ksys_read.do_syscall_64.entry_SYSCALL_64_after_hwframe 0.69 ± 2% +0.2 0.90 ± 2% perf-profile.calltrace.cycles-pp.copyout.copy_page_to_iter.pipe_read.__vfs_read.vfs_read 1.57 ± 2% +0.2 1.79 ± 2% perf-profile.calltrace.cycles-pp.switch_mm_irqs_off.switch_mm.__sched_text_start.schedule.pipe_wait 1.43 +0.2 1.65 perf-profile.calltrace.cycles-pp.update_load_avg.dequeue_entity.dequeue_task_fair.__sched_text_start.schedule 1.62 +0.2 1.85 ± 3% perf-profile.calltrace.cycles-pp.__switch_to_asm 2.03 ± 2% +0.2 2.26 perf-profile.calltrace.cycles-pp.__switch_to 1.73 ± 2% +0.2 1.97 perf-profile.calltrace.cycles-pp.copy_page_from_iter.pipe_write.__vfs_write.vfs_write.ksys_write 1.67 ± 2% +0.2 1.91 ± 2% perf-profile.calltrace.cycles-pp.switch_mm.__sched_text_start.schedule.pipe_wait.pipe_read 1.12 ± 3% +0.3 1.37 perf-profile.calltrace.cycles-pp.pick_next_task_idle.__sched_text_start.schedule.pipe_wait.pipe_read 0.25 ±100% +0.3 0.54 ± 3% perf-profile.calltrace.cycles-pp.avc_has_perm.file_has_perm.security_file_permission.vfs_write.ksys_write 0.26 ±100% +0.3 0.56 ± 2% perf-profile.calltrace.cycles-pp.mutex_lock.pipe_wait.pipe_read.__vfs_read.vfs_read 1.49 ± 3% +0.3 1.79 perf-profile.calltrace.cycles-pp.__next_timer_interrupt.get_next_timer_interrupt.tick_nohz_next_event.tick_nohz_get_sleep_length.menu_select 0.26 ±100% +0.3 0.59 ± 6% perf-profile.calltrace.cycles-pp.__update_load_avg_se.update_load_avg.dequeue_entity.dequeue_task_fair.__sched_text_start 1.33 ± 2% +0.3 1.66 ± 2% perf-profile.calltrace.cycles-pp.update_load_avg.enqueue_entity.enqueue_task_fair.ttwu_do_activate.try_to_wake_up 0.40 ± 57% +0.4 0.75 perf-profile.calltrace.cycles-pp.__update_idle_core.pick_next_task_idle.__sched_text_start.schedule.pipe_wait 0.13 ±173% +0.4 0.54 ± 3% perf-profile.calltrace.cycles-pp.__update_load_avg_cfs_rq.update_load_avg.dequeue_entity.dequeue_task_fair.__sched_text_start 0.14 ±173% +0.4 0.56 ± 2% perf-profile.calltrace.cycles-pp.account_entity_dequeue.dequeue_entity.dequeue_task_fair.__sched_text_start.schedule 0.13 ±173% +0.4 0.58 ± 4% perf-profile.calltrace.cycles-pp.__update_load_avg_se.update_load_avg.enqueue_entity.enqueue_task_fair.ttwu_do_activate 4.33 +0.5 4.81 perf-profile.calltrace.cycles-pp.syscall_return_via_sysret 2.33 ± 3% +0.5 2.83 perf-profile.calltrace.cycles-pp.get_next_timer_interrupt.tick_nohz_next_event.tick_nohz_get_sleep_length.menu_select.do_idle 0.00 +0.5 0.52 perf-profile.calltrace.cycles-pp.update_rq_clock.try_to_wake_up.autoremove_wake_function.__wake_up_common.__wake_up_common_lock 0.64 ± 4% +0.5 1.18 ± 2% perf-profile.calltrace.cycles-pp.tick_nohz_idle_exit.do_idle.cpu_startup_entry.start_secondary.secondary_startup_64 0.00 +0.6 0.56 ± 4% perf-profile.calltrace.cycles-pp.__update_load_avg_cfs_rq.update_load_avg.enqueue_entity.enqueue_task_fair.ttwu_do_activate 2.71 ± 3% +0.6 3.28 perf-profile.calltrace.cycles-pp.tick_nohz_next_event.tick_nohz_get_sleep_length.menu_select.do_idle.cpu_startup_entry 4.37 +0.6 5.00 perf-profile.calltrace.cycles-pp.dequeue_entity.dequeue_task_fair.__sched_text_start.schedule.pipe_wait 3.83 +0.6 4.47 perf-profile.calltrace.cycles-pp.enqueue_entity.enqueue_task_fair.ttwu_do_activate.try_to_wake_up.autoremove_wake_function 6.87 +0.7 7.59 perf-profile.calltrace.cycles-pp.dequeue_task_fair.__sched_text_start.schedule.pipe_wait.pipe_read 6.18 +0.8 6.93 perf-profile.calltrace.cycles-pp.enqueue_task_fair.ttwu_do_activate.try_to_wake_up.autoremove_wake_function.__wake_up_common 3.43 ± 4% +0.8 4.21 perf-profile.calltrace.cycles-pp.tick_nohz_get_sleep_length.menu_select.do_idle.cpu_startup_entry.start_secondary 0.00 +0.8 0.83 ± 30% perf-profile.calltrace.cycles-pp.reschedule_interrupt.tick_nohz_idle_exit.do_idle.cpu_startup_entry.start_secondary 6.55 +0.9 7.49 perf-profile.calltrace.cycles-pp.ttwu_do_activate.try_to_wake_up.autoremove_wake_function.__wake_up_common.__wake_up_common_lock 5.05 ± 3% +1.2 6.25 perf-profile.calltrace.cycles-pp.menu_select.do_idle.cpu_startup_entry.start_secondary.secondary_startup_64 16.76 +1.6 18.40 perf-profile.calltrace.cycles-pp.__sched_text_start.schedule.pipe_wait.pipe_read.__vfs_read 17.00 +1.7 18.66 perf-profile.calltrace.cycles-pp.schedule.pipe_wait.pipe_read.__vfs_read.vfs_read 14.34 ± 2% +1.7 16.03 perf-profile.calltrace.cycles-pp.try_to_wake_up.autoremove_wake_function.__wake_up_common.__wake_up_common_lock.pipe_write 14.83 ± 2% +1.7 16.55 perf-profile.calltrace.cycles-pp.autoremove_wake_function.__wake_up_common.__wake_up_common_lock.pipe_write.__vfs_write 15.12 ± 2% +1.8 16.88 perf-profile.calltrace.cycles-pp.__wake_up_common.__wake_up_common_lock.pipe_write.__vfs_write.vfs_write 15.91 +1.8 17.67 perf-profile.calltrace.cycles-pp.__wake_up_common_lock.pipe_write.__vfs_write.vfs_write.ksys_write 18.89 +1.9 20.80 perf-profile.calltrace.cycles-pp.pipe_wait.pipe_read.__vfs_read.vfs_read.ksys_read 20.19 +2.2 22.43 perf-profile.calltrace.cycles-pp.pipe_write.__vfs_write.vfs_write.ksys_write.do_syscall_64 20.70 +2.3 22.99 perf-profile.calltrace.cycles-pp.__vfs_write.vfs_write.ksys_write.do_syscall_64.entry_SYSCALL_64_after_hwframe 24.89 +2.4 27.33 perf-profile.calltrace.cycles-pp.pipe_read.__vfs_read.vfs_read.ksys_read.do_syscall_64 25.49 +2.5 28.02 perf-profile.calltrace.cycles-pp.__vfs_read.vfs_read.ksys_read.do_syscall_64.entry_SYSCALL_64_after_hwframe 23.43 +2.5 25.96 perf-profile.calltrace.cycles-pp.vfs_write.ksys_write.do_syscall_64.entry_SYSCALL_64_after_hwframe 24.23 +2.6 26.85 perf-profile.calltrace.cycles-pp.ksys_write.do_syscall_64.entry_SYSCALL_64_after_hwframe 28.66 +2.8 31.48 perf-profile.calltrace.cycles-pp.vfs_read.ksys_read.do_syscall_64.entry_SYSCALL_64_after_hwframe 29.39 +2.9 32.30 perf-profile.calltrace.cycles-pp.ksys_read.do_syscall_64.entry_SYSCALL_64_after_hwframe 0.00 +3.2 3.15 ± 9% perf-profile.calltrace.cycles-pp.poll_idle.cpuidle_enter_state.do_idle.cpu_startup_entry.start_secondary 55.40 +5.7 61.09 perf-profile.calltrace.cycles-pp.do_syscall_64.entry_SYSCALL_64_after_hwframe 55.92 +5.8 61.68 perf-profile.calltrace.cycles-pp.entry_SYSCALL_64_after_hwframe 13.49 -13.5 0.00 perf-profile.children.cycles-pp.intel_idle 14.17 -10.6 3.61 ± 8% perf-profile.children.cycles-pp.cpuidle_enter_state 28.48 ± 2% -7.1 21.34 perf-profile.children.cycles-pp.secondary_startup_64 28.48 ± 2% -7.1 21.34 perf-profile.children.cycles-pp.start_secondary 28.48 ± 2% -7.1 21.34 perf-profile.children.cycles-pp.cpu_startup_entry 28.56 ± 2% -7.1 21.42 perf-profile.children.cycles-pp.do_idle 0.47 ± 4% -0.2 0.23 ± 5% perf-profile.children.cycles-pp.resched_curr 1.45 ± 3% -0.1 1.33 perf-profile.children.cycles-pp.cpumask_next_wrap 0.23 ± 7% -0.1 0.16 ± 9% perf-profile.children.cycles-pp.menu_reflect 0.08 ± 6% +0.0 0.09 perf-profile.children.cycles-pp.tsc_verify_tsc_adjust 0.06 ± 7% +0.0 0.07 ± 5% perf-profile.children.cycles-pp.can_stop_idle_tick 0.05 +0.0 0.07 ± 12% perf-profile.children.cycles-pp.pm_qos_read_value 0.10 ± 4% +0.0 0.12 ± 7% perf-profile.children.cycles-pp.ktime_get_coarse_real_ts64 0.08 ± 5% +0.0 0.10 ± 4% perf-profile.children.cycles-pp.arch_cpu_idle_enter 0.15 ± 2% +0.0 0.17 ± 2% perf-profile.children.cycles-pp.rcu_needs_cpu 0.08 ± 13% +0.0 0.11 ± 9% perf-profile.children.cycles-pp.tick_check_broadcast_expired 0.35 +0.0 0.39 perf-profile.children.cycles-pp.entry_SYSCALL_64_stage2 0.33 +0.0 0.36 ± 3% perf-profile.children.cycles-pp.anon_pipe_buf_release 0.71 +0.0 0.75 ± 2% perf-profile.children.cycles-pp.touch_atime 0.30 ± 7% +0.0 0.33 ± 3% perf-profile.children.cycles-pp.cpuidle_governor_latency_req 0.12 ± 5% +0.0 0.16 ± 4% perf-profile.children.cycles-pp.tick_nohz_tick_stopped 0.70 ± 3% +0.0 0.74 ± 2% perf-profile.children.cycles-pp.account_entity_dequeue 0.27 ± 7% +0.0 0.32 ± 4% perf-profile.children.cycles-pp.__x64_sys_read 1.72 +0.0 1.77 perf-profile.children.cycles-pp.set_next_entity 0.46 ± 3% +0.0 0.51 ± 4% perf-profile.children.cycles-pp.file_update_time 0.47 ± 4% +0.0 0.52 ± 3% perf-profile.children.cycles-pp.current_time 0.88 ± 2% +0.0 0.93 perf-profile.children.cycles-pp.fsnotify 0.66 ± 2% +0.1 0.71 ± 2% perf-profile.children.cycles-pp.copy_user_generic_unrolled 0.15 ± 3% +0.1 0.20 ± 3% perf-profile.children.cycles-pp.__enqueue_entity 0.00 +0.1 0.06 ± 9% perf-profile.children.cycles-pp.cpuidle_get_cpu_driver 0.68 ± 2% +0.1 0.74 ± 2% perf-profile.children.cycles-pp.prepare_to_wait 0.25 ± 10% +0.1 0.30 ± 3% perf-profile.children.cycles-pp.__hrtimer_next_event_base 0.23 ± 4% +0.1 0.29 ± 3% perf-profile.children.cycles-pp.rcu_eqs_enter 0.27 ± 3% +0.1 0.33 ± 2% perf-profile.children.cycles-pp.rcu_all_qs 0.43 ± 5% +0.1 0.49 ± 3% perf-profile.children.cycles-pp.__fsnotify_parent 1.02 +0.1 1.09 perf-profile.children.cycles-pp.___perf_sw_event 0.00 +0.1 0.07 ± 11% perf-profile.children.cycles-pp.native_smp_send_reschedule 0.22 ± 5% +0.1 0.29 ± 4% perf-profile.children.cycles-pp.activate_task 0.00 +0.1 0.08 ± 12% perf-profile.children.cycles-pp.interrupt_entry 0.52 ± 2% +0.1 0.60 perf-profile.children.cycles-pp.read_tsc 0.62 ± 3% +0.1 0.71 perf-profile.children.cycles-pp.__might_fault 0.74 ± 5% +0.1 0.82 perf-profile.children.cycles-pp.copyin 0.40 ± 5% +0.1 0.48 perf-profile.children.cycles-pp.hrtimer_get_next_event 0.21 ± 3% +0.1 0.30 ± 4% perf-profile.children.cycles-pp.sched_ttwu_pending 0.76 ± 4% +0.1 0.85 perf-profile.children.cycles-pp.ktime_get 0.96 ± 2% +0.1 1.05 ± 2% perf-profile.children.cycles-pp.avc_has_perm 0.87 ± 5% +0.1 0.96 perf-profile.children.cycles-pp.finish_task_switch 1.03 +0.1 1.13 ± 3% perf-profile.children.cycles-pp.__fdget_pos 0.07 ± 6% +0.1 0.17 ± 28% perf-profile.children.cycles-pp.__task_rq_lock 0.52 ± 3% +0.1 0.61 perf-profile.children.cycles-pp._cond_resched 0.00 +0.1 0.10 ± 12% perf-profile.children.cycles-pp.x2apic_send_IPI 0.77 ± 2% +0.1 0.88 perf-profile.children.cycles-pp.__might_sleep 0.21 ± 3% +0.1 0.32 ± 4% perf-profile.children.cycles-pp.call_cpuidle 1.17 ± 2% +0.1 1.30 ± 2% perf-profile.children.cycles-pp.check_preempt_curr 0.08 ± 15% +0.1 0.20 ± 29% perf-profile.children.cycles-pp.native_queued_spin_lock_slowpath 1.99 +0.1 2.12 perf-profile.children.cycles-pp.selinux_file_permission 1.34 ± 2% +0.1 1.47 perf-profile.children.cycles-pp.ttwu_do_wakeup 0.64 ± 2% +0.1 0.78 ± 3% perf-profile.children.cycles-pp.tick_nohz_idle_enter 1.22 +0.1 1.35 perf-profile.children.cycles-pp.___might_sleep 2.00 +0.1 2.14 perf-profile.children.cycles-pp.update_cfs_group 1.48 ± 3% +0.1 1.62 perf-profile.children.cycles-pp._raw_spin_unlock_irqrestore 0.57 ± 7% +0.1 0.71 perf-profile.children.cycles-pp.hrtimer_next_event_without 1.53 ± 2% +0.2 1.68 ± 2% perf-profile.children.cycles-pp.file_has_perm 2.91 +0.2 3.07 perf-profile.children.cycles-pp.load_new_mm_cr3 0.35 ± 3% +0.2 0.51 ± 5% perf-profile.children.cycles-pp.rcu_idle_exit 1.50 +0.2 1.67 perf-profile.children.cycles-pp._raw_spin_lock_irqsave 1.41 ± 2% +0.2 1.60 ± 2% perf-profile.children.cycles-pp.__update_load_avg_cfs_rq 0.94 ± 3% +0.2 1.14 ± 2% perf-profile.children.cycles-pp.update_rq_clock 0.71 ± 3% +0.2 0.91 ± 7% perf-profile.children.cycles-pp._raw_spin_lock 1.98 +0.2 2.17 perf-profile.children.cycles-pp.copy_page_to_iter 1.62 +0.2 1.82 ± 3% perf-profile.children.cycles-pp.__update_load_avg_se 1.28 ± 4% +0.2 1.48 perf-profile.children.cycles-pp.native_sched_clock 0.71 ± 2% +0.2 0.93 ± 2% perf-profile.children.cycles-pp.copyout 0.33 ± 4% +0.2 0.54 perf-profile.children.cycles-pp.cpumask_next 1.34 ± 4% +0.2 1.56 perf-profile.children.cycles-pp.sched_clock 0.55 ± 4% +0.2 0.78 perf-profile.children.cycles-pp.__update_idle_core 0.00 +0.2 0.23 ± 8% perf-profile.children.cycles-pp.__x2apic_send_IPI_dest 2.32 ± 3% +0.2 2.55 perf-profile.children.cycles-pp.update_curr 1.67 +0.2 1.91 perf-profile.children.cycles-pp.mutex_lock 0.66 ± 5% +0.2 0.89 perf-profile.children.cycles-pp.copy_user_enhanced_fast_string 1.78 ± 2% +0.2 2.02 perf-profile.children.cycles-pp.copy_page_from_iter 1.93 ± 3% +0.2 2.18 ± 2% perf-profile.children.cycles-pp.find_next_bit 0.00 +0.2 0.25 ± 4% perf-profile.children.cycles-pp.native_apic_msr_eoi_write 1.74 ± 2% +0.3 1.99 ± 3% perf-profile.children.cycles-pp.switch_mm 1.15 ± 3% +0.3 1.41 perf-profile.children.cycles-pp.pick_next_task_idle 1.48 ± 4% +0.3 1.74 perf-profile.children.cycles-pp.sched_clock_cpu 0.00 +0.3 0.26 ± 5% perf-profile.children.cycles-pp.smp_reschedule_interrupt 2.27 ± 2% +0.3 2.55 perf-profile.children.cycles-pp.__switch_to 1.23 +0.3 1.52 ± 2% perf-profile.children.cycles-pp.native_write_msr 3.92 +0.3 4.25 perf-profile.children.cycles-pp.security_file_permission 0.03 ±100% +0.3 0.38 perf-profile.children.cycles-pp.native_irq_return_iret 1.71 ± 3% +0.4 2.07 perf-profile.children.cycles-pp.__next_timer_interrupt 4.77 +0.4 5.15 perf-profile.children.cycles-pp.switch_mm_irqs_off 1.79 +0.5 2.25 ± 2% perf-profile.children.cycles-pp.__switch_to_asm 2.34 ± 3% +0.5 2.85 perf-profile.children.cycles-pp.get_next_timer_interrupt 4.93 +0.6 5.49 perf-profile.children.cycles-pp.syscall_return_via_sysret 2.73 ± 3% +0.6 3.32 perf-profile.children.cycles-pp.tick_nohz_next_event 3.78 +0.6 4.36 perf-profile.children.cycles-pp.update_load_avg 4.47 +0.6 5.11 perf-profile.children.cycles-pp.dequeue_entity 3.93 +0.7 4.62 perf-profile.children.cycles-pp.enqueue_entity 6.90 +0.7 7.63 perf-profile.children.cycles-pp.dequeue_task_fair 6.20 +0.8 6.96 perf-profile.children.cycles-pp.enqueue_task_fair 3.46 ± 4% +0.8 4.24 perf-profile.children.cycles-pp.tick_nohz_get_sleep_length 6.55 +1.0 7.51 perf-profile.children.cycles-pp.ttwu_do_activate 0.06 ± 7% +1.0 1.05 ± 3% perf-profile.children.cycles-pp.reschedule_interrupt 0.67 ± 3% +1.0 1.68 ± 2% perf-profile.children.cycles-pp.tick_nohz_idle_exit 5.08 ± 3% +1.2 6.29 perf-profile.children.cycles-pp.menu_select 17.25 +1.6 18.90 perf-profile.children.cycles-pp.schedule 14.39 ± 2% +1.7 16.10 perf-profile.children.cycles-pp.try_to_wake_up 14.85 ± 2% +1.7 16.59 perf-profile.children.cycles-pp.autoremove_wake_function 15.24 ± 2% +1.8 17.01 perf-profile.children.cycles-pp.__wake_up_common 16.70 +1.8 18.53 perf-profile.children.cycles-pp.__wake_up_common_lock 18.94 +1.9 20.86 perf-profile.children.cycles-pp.pipe_wait 22.95 +2.1 25.07 perf-profile.children.cycles-pp.__sched_text_start 20.25 +2.2 22.49 perf-profile.children.cycles-pp.pipe_write 20.73 +2.3 23.02 perf-profile.children.cycles-pp.__vfs_write 24.95 +2.5 27.41 perf-profile.children.cycles-pp.pipe_read 25.53 +2.5 28.05 perf-profile.children.cycles-pp.__vfs_read 23.48 +2.5 26.02 perf-profile.children.cycles-pp.vfs_write 24.26 +2.6 26.88 perf-profile.children.cycles-pp.ksys_write 28.73 +2.8 31.56 perf-profile.children.cycles-pp.vfs_read 29.43 +2.9 32.34 perf-profile.children.cycles-pp.ksys_read 0.12 ± 11% +3.0 3.16 ± 9% perf-profile.children.cycles-pp.poll_idle 55.47 +5.7 61.16 perf-profile.children.cycles-pp.do_syscall_64 56.01 +5.8 61.76 perf-profile.children.cycles-pp.entry_SYSCALL_64_after_hwframe 13.47 -13.5 0.00 perf-profile.self.cycles-pp.intel_idle 0.47 ± 5% -0.3 0.22 ± 5% perf-profile.self.cycles-pp.resched_curr 0.72 ± 2% -0.1 0.61 ± 4% perf-profile.self.cycles-pp.copy_page_to_iter 0.18 ± 8% -0.0 0.14 ± 10% perf-profile.self.cycles-pp.menu_reflect 0.19 ± 7% -0.0 0.14 ± 3% perf-profile.self.cycles-pp.cpuidle_enter_state 0.05 +0.0 0.07 ± 7% perf-profile.self.cycles-pp.pm_qos_read_value 0.06 ± 7% +0.0 0.07 ± 5% perf-profile.self.cycles-pp.can_stop_idle_tick 0.11 ± 3% +0.0 0.13 ± 5% perf-profile.self.cycles-pp.__fdget_pos 0.18 ± 2% +0.0 0.21 ± 2% perf-profile.self.cycles-pp.touch_atime 0.14 ± 3% +0.0 0.16 ± 4% perf-profile.self.cycles-pp.rcu_needs_cpu 0.08 ± 6% +0.0 0.10 ± 7% perf-profile.self.cycles-pp.hrtimer_next_event_without 0.08 ± 8% +0.0 0.11 ± 10% perf-profile.self.cycles-pp.tick_check_broadcast_expired 0.25 ± 2% +0.0 0.28 perf-profile.self.cycles-pp._cond_resched 0.12 ± 9% +0.0 0.15 ± 3% perf-profile.self.cycles-pp.tick_nohz_idle_enter 0.23 ± 6% +0.0 0.26 ± 2% perf-profile.self.cycles-pp.current_time 0.39 ± 2% +0.0 0.42 ± 2% perf-profile.self.cycles-pp.file_has_perm 0.34 +0.0 0.37 perf-profile.self.cycles-pp.entry_SYSCALL_64_stage2 0.23 +0.0 0.26 ± 6% perf-profile.self.cycles-pp.ksys_read 0.17 ± 2% +0.0 0.20 ± 4% perf-profile.self.cycles-pp.switch_mm 0.14 ± 7% +0.0 0.17 ± 2% perf-profile.self.cycles-pp.sched_clock_cpu 0.23 ± 5% +0.0 0.27 ± 5% perf-profile.self.cycles-pp.__x64_sys_read 0.39 +0.0 0.43 perf-profile.self.cycles-pp.prepare_to_wait 0.38 ± 2% +0.0 0.42 ± 3% perf-profile.self.cycles-pp.__wake_up_common 0.29 ± 3% +0.0 0.32 ± 3% perf-profile.self.cycles-pp.security_file_permission 0.20 ± 5% +0.0 0.24 ± 5% perf-profile.self.cycles-pp.sched_ttwu_pending 0.10 ± 4% +0.0 0.14 ± 5% perf-profile.self.cycles-pp.tick_nohz_get_sleep_length 0.10 ± 7% +0.0 0.14 ± 3% perf-profile.self.cycles-pp.tick_nohz_tick_stopped 0.16 ± 2% +0.0 0.20 ± 3% perf-profile.self.cycles-pp.tick_nohz_next_event 0.11 ± 4% +0.0 0.16 ± 5% perf-profile.self.cycles-pp.__enqueue_entity 0.61 ± 3% +0.0 0.66 perf-profile.self.cycles-pp.copy_user_generic_unrolled 0.43 ± 6% +0.0 0.48 ± 2% perf-profile.self.cycles-pp.__vfs_write 0.14 ± 6% +0.0 0.18 ± 2% perf-profile.self.cycles-pp.get_next_timer_interrupt 0.39 ± 3% +0.0 0.43 ± 4% perf-profile.self.cycles-pp.vfs_read 0.00 +0.1 0.05 perf-profile.self.cycles-pp.schedule_idle 0.54 +0.1 0.59 perf-profile.self.cycles-pp.dequeue_entity 0.24 ± 3% +0.1 0.30 ± 6% perf-profile.self.cycles-pp.ksys_write 0.37 ± 5% +0.1 0.42 ± 3% perf-profile.self.cycles-pp.vfs_write 0.24 ± 5% +0.1 0.30 ± 2% perf-profile.self.cycles-pp.pipe_wait 0.18 ± 4% +0.1 0.24 perf-profile.self.cycles-pp.__hrtimer_next_event_base 0.90 ± 2% +0.1 0.96 perf-profile.self.cycles-pp.___perf_sw_event 0.22 ± 3% +0.1 0.28 ± 4% perf-profile.self.cycles-pp.rcu_eqs_enter 0.43 ± 2% +0.1 0.49 ± 3% perf-profile.self.cycles-pp.copy_page_from_iter 0.19 ± 4% +0.1 0.25 perf-profile.self.cycles-pp.rcu_all_qs 0.50 ± 7% +0.1 0.56 ± 3% perf-profile.self.cycles-pp.__vfs_read 0.56 ± 2% +0.1 0.62 ± 3% perf-profile.self.cycles-pp.entry_SYSCALL_64_after_hwframe 0.38 ± 4% +0.1 0.45 ± 3% perf-profile.self.cycles-pp.__fsnotify_parent 0.21 ± 3% +0.1 0.28 ± 5% perf-profile.self.cycles-pp.activate_task 1.34 ± 2% +0.1 1.41 perf-profile.self.cycles-pp.reweight_entity 0.00 +0.1 0.07 ± 10% perf-profile.self.cycles-pp.native_smp_send_reschedule 0.24 ± 3% +0.1 0.32 ± 4% perf-profile.self.cycles-pp.update_rq_clock 0.70 ± 3% +0.1 0.78 ± 5% perf-profile.self.cycles-pp.enqueue_task_fair 0.61 ± 4% +0.1 0.69 ± 2% perf-profile.self.cycles-pp._raw_spin_lock 0.00 +0.1 0.08 ± 12% perf-profile.self.cycles-pp.interrupt_entry 0.49 ± 2% +0.1 0.57 ± 2% perf-profile.self.cycles-pp.read_tsc 0.81 ± 2% +0.1 0.90 ± 2% perf-profile.self.cycles-pp.dequeue_task_fair 0.73 +0.1 0.82 ± 2% perf-profile.self.cycles-pp.mutex_lock 0.20 ± 4% +0.1 0.29 ± 5% perf-profile.self.cycles-pp.rcu_idle_exit 0.58 ± 5% +0.1 0.68 ± 2% perf-profile.self.cycles-pp.finish_task_switch 0.00 +0.1 0.09 ± 11% perf-profile.self.cycles-pp.x2apic_send_IPI 0.95 ± 2% +0.1 1.04 ± 2% perf-profile.self.cycles-pp.avc_has_perm 0.78 +0.1 0.87 ± 2% perf-profile.self.cycles-pp.pipe_write 1.34 +0.1 1.44 perf-profile.self.cycles-pp.selinux_file_permission 0.71 +0.1 0.81 perf-profile.self.cycles-pp.__might_sleep 0.20 ± 2% +0.1 0.32 ± 3% perf-profile.self.cycles-pp.call_cpuidle 0.12 ± 6% +0.1 0.24 ± 3% perf-profile.self.cycles-pp.ttwu_do_activate 1.13 ± 2% +0.1 1.24 ± 3% perf-profile.self.cycles-pp.pipe_read 0.87 +0.1 0.99 ± 5% perf-profile.self.cycles-pp.do_syscall_64 0.08 ± 15% +0.1 0.20 ± 29% perf-profile.self.cycles-pp.native_queued_spin_lock_slowpath 1.94 +0.1 2.08 perf-profile.self.cycles-pp.update_cfs_group 1.17 +0.1 1.30 perf-profile.self.cycles-pp.___might_sleep 2.52 +0.1 2.66 perf-profile.self.cycles-pp.__sched_text_start 1.33 ± 2% +0.1 1.48 perf-profile.self.cycles-pp._raw_spin_unlock_irqrestore 2.90 +0.2 3.06 perf-profile.self.cycles-pp.load_new_mm_cr3 1.44 +0.2 1.59 perf-profile.self.cycles-pp._raw_spin_lock_irqsave 0.73 ± 2% +0.2 0.89 perf-profile.self.cycles-pp.enqueue_entity 1.16 ± 3% +0.2 1.32 perf-profile.self.cycles-pp.select_task_rq_fair 0.12 ± 7% +0.2 0.29 ± 4% perf-profile.self.cycles-pp.cpumask_next 1.64 ± 2% +0.2 1.81 ± 2% perf-profile.self.cycles-pp.find_next_bit 0.81 ± 3% +0.2 0.99 perf-profile.self.cycles-pp.__next_timer_interrupt 1.39 ± 2% +0.2 1.58 ± 2% perf-profile.self.cycles-pp.__update_load_avg_cfs_rq 1.56 +0.2 1.76 ± 3% perf-profile.self.cycles-pp.__update_load_avg_se 1.31 ± 2% +0.2 1.51 perf-profile.self.cycles-pp.update_load_avg 1.11 ± 4% +0.2 1.31 perf-profile.self.cycles-pp.update_curr 1.23 ± 3% +0.2 1.43 perf-profile.self.cycles-pp.native_sched_clock 1.86 +0.2 2.08 perf-profile.self.cycles-pp.switch_mm_irqs_off 0.77 ± 2% +0.2 0.99 ± 5% perf-profile.self.cycles-pp.do_idle 0.65 ± 5% +0.2 0.88 ± 2% perf-profile.self.cycles-pp.copy_user_enhanced_fast_string 0.00 +0.2 0.24 ± 5% perf-profile.self.cycles-pp.native_apic_msr_eoi_write 2.19 ± 2% +0.3 2.47 perf-profile.self.cycles-pp.__switch_to 1.22 ± 2% +0.3 1.51 ± 2% perf-profile.self.cycles-pp.native_write_msr 0.70 ± 4% +0.3 1.00 ± 5% perf-profile.self.cycles-pp.try_to_wake_up 1.22 ± 4% +0.3 1.56 perf-profile.self.cycles-pp.menu_select 0.03 ±100% +0.3 0.38 perf-profile.self.cycles-pp.native_irq_return_iret 1.79 +0.5 2.25 ± 2% perf-profile.self.cycles-pp.__switch_to_asm 0.13 ± 8% +0.5 0.64 ± 3% perf-profile.self.cycles-pp.tick_nohz_idle_exit 4.92 +0.6 5.48 perf-profile.self.cycles-pp.syscall_return_via_sysret 0.08 ± 6% +2.9 2.94 ± 9% perf-profile.self.cycles-pp.poll_idle will-it-scale.per_process_ops 385000 +-+----------------------------------------------------------------+ 380000 +-+ O O | O O O OO O | 375000 +-O O O O O O O OO O O | 370000 +-+ O O O O O O O O | | O O | 365000 +-+ | 360000 +-+ | 355000 +-+ | | | 350000 +-+ | 345000 +-+ .+. .+.++.+.+.+. .+. | |. .+. + +.+.+ +. .+. +.+. .+. .+. .+.+.+.+.++ +.| 340000 +-+ + + + + + ++.+ | 335000 +-+----------------------------------------------------------------+ will-it-scale.workload 6.2e+06 +-+---------------------------------------------------------------+ | O | 6.1e+06 +-+ O O | O O O O O O O | 6e+06 +-O O OO O O O OO O O O OO | 5.9e+06 +-+ O O OO O | | | 5.8e+06 +-+ | | | 5.7e+06 +-+ | 5.6e+06 +-+ | | .+ .+. | 5.5e+06 +-+ .+.+.+.+ + +.+.+. .+. .+.++. | |.+.+.++ ++.+.+.+.+.++.+.+.+.+.++.+ + +.| 5.4e+06 +-+---------------------------------------------------------------+ [*] bisect-good sample [O] bisect-bad sample 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. Thanks, Rong Chen

2 years, 4 months

1
0
0 / 0

[drivers/base] f198a21af6: WARNING:at_kernel/locking/lockdep.c:#__bfs

by kernel test robot

FYI, we noticed the following commit (built with gcc-7): commit: f198a21af6464e0d2590ead870e215e82265c202 ("[PATCH v2 3/7] drivers/base: Probe devices concurrently if requested by the driver") url: https://github.com/0day-ci/linux/commits/Bart-Van-Assche/Increase-SCSI-di... base: https://git.kernel.org/cgit/linux/kernel/git/mkp/scsi.git for-next in testcase: boot on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -m 256M caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace): +-----------------------------------------------+------------+------------+ | | 7f2b0462b0 | f198a21af6 | +-----------------------------------------------+------------+------------+ | boot_successes | 0 | 0 | | boot_failures | 14 | 12 | | WARNING:at_drivers/base.c:#device_bind_driver | 14 | | | RIP:device_bind_driver | 14 | | | WARNING:at_drivers/base.c:#driver_sysfs_add | 14 | | | RIP:driver_sysfs_add | 14 | | | WARNING:at_drivers/base.c:#driver_bound | 14 | | | RIP:driver_bound | 14 | | | WARNING:at_drivers/base.c:#device_is_bound | 14 | | | RIP:device_is_bound | 14 | | | WARNING:at_kernel/locking/lockdep.c:#__bfs | 0 | 12 | | RIP:__bfs | 0 | 12 | | BUG:unable_to_handle_kernel | 0 | 12 | | Oops:#[##] | 0 | 12 | | Kernel_panic-not_syncing:Fatal_exception | 0 | 12 | +-----------------------------------------------+------------+------------+ [ 8.833473] WARNING: CPU: 0 PID: 102 at kernel/locking/lockdep.c:966 __bfs+0x134/0x1d2 [ 8.834745] CPU: 0 PID: 102 Comm: kworker/u2:2 Not tainted 4.19.0-rc1-00236-gf198a21 #1 [ 8.835770] Workqueue: events_unbound async_run_entry_fn [ 8.836549] RIP: 0010:__bfs+0x134/0x1d2 [ 8.837071] Code: 92 fc ff 0f 0b 48 8b 13 48 39 d3 0f 84 76 ff ff ff 48 89 d0 4c 8b 05 91 3f 5a 02 48 2d c0 87 e1 83 48 c1 f8 06 4c 39 c0 72 02 <0f> 0b 48 8b 72 10 8b 3d 86 3f 64 02 39 7e 2c 74 6a 4c 39 c0 72 02 [ 8.837165] RSP: 0018:ffffc900000fbb50 EFLAGS: 00010016 [ 8.837165] RAX: 0000000001f079e1 RBX: ffffffff83da8160 RCX: ffffc900000fbb00 [ 8.837165] RDX: 0000000000000000 RSI: 0000000000000000 RDI: ffffc900000fbbb0 [ 8.837165] RBP: 0000000000000000 R08: 00000000000005b4 R09: 0000000000000000 [ 8.837165] R10: ffffc900000fbc38 R11: fefefefefefefeff R12: ffffc900000fbba8 [ 8.837165] R13: 0000000000000000 R14: ffffffff837288e0 R15: ffffc900000fbbb0 [ 8.837165] FS: 0000000000000000(0000) GS:ffffffff82e89000(0000) knlGS:0000000000000000 [ 8.837165] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 8.837165] CR2: 00007ff12232d820 CR3: 0000000002e62000 CR4: 00000000000406b0 [ 8.837165] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [ 8.837165] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [ 8.837165] Call Trace: [ 8.837165] ? noop_count+0x6/0x6 [ 8.837165] ? check_usage_backwards+0xa1/0x109 [ 8.837165] ? __save_stack_trace+0xaf/0xbe [ 8.837165] ? print_shortest_lock_dependencies+0x177/0x177 [ 8.837165] ? mark_lock+0x2c9/0x4f1 [ 8.837165] ? mark_lock+0x2c9/0x4f1 [ 8.837165] ? __lock_acquire+0x38d/0xeea [ 8.837165] ? lock_acquire+0x121/0x152 [ 8.837165] ? __driver_probe_device_async+0x23/0x4d [ 8.837165] ? __mutex_lock+0x7d/0x7b7 [ 8.837165] ? __driver_probe_device_async+0x23/0x4d [ 8.837165] ? __driver_probe_device_async+0x23/0x4d [ 8.837165] ? __lock_acquire+0x2b3/0xeea [ 8.837165] ? __driver_probe_device_async+0x23/0x4d [ 8.837165] ? __mutex_lock+0x5/0x7b7 [ 8.837165] ? __driver_probe_device_async+0x23/0x4d [ 8.837165] ? async_run_entry_fn+0x38/0xc9 [ 8.837165] ? process_one_work+0x355/0x5ce [ 8.837165] ? worker_thread+0x2c/0x49f [ 8.837165] ? worker_thread+0x338/0x49f [ 8.837165] ? process_scheduled_works+0x2c/0x2c [ 8.837165] ? kthread+0x143/0x14b [ 8.837165] ? __kthread_create_on_node+0x1aa/0x1aa [ 8.837165] ? ret_from_fork+0x3a/0x50 [ 8.837165] irq event stamp: 2537 [ 8.837165] hardirqs last enabled at (2537): [<ffffffff821053c6>] _raw_spin_unlock_irq+0x29/0x38 [ 8.837165] hardirqs last disabled at (2536): [<ffffffff821051b8>] _raw_spin_lock_irq+0x15/0x41 [ 8.837165] softirqs last enabled at (0): [<ffffffff810aa993>] copy_process+0x942/0x2c26 [ 8.837165] softirqs last disabled at (0): [<0000000000000000>] (null) [ 8.837165] ---[ end trace 61a2ed02c9ffe423 ]--- To reproduce: git clone https://github.com/intel/lkp-tests.git cd lkp-tests bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email Thanks, Rong Chen

2 years, 4 months

1
0
0 / 0

[drivers/base] 7f2b0462b0: WARNING:at_drivers/base.c:#device_bind_driver

by kernel test robot

FYI, we noticed the following commit (built with gcc-5): commit: 7f2b0462b0fba71eff87f23b6a189fd8c5cc1e9f ("[PATCH v2 2/7] drivers/base: Verify struct device locking requirements at runtime") url: https://github.com/0day-ci/linux/commits/Bart-Van-Assche/Increase-SCSI-di... base: https://git.kernel.org/cgit/linux/kernel/git/mkp/scsi.git for-next in testcase: trinity with following parameters: runtime: 300s test-description: Trinity is a linux system call fuzz tester. test-url: http://codemonkey.org.uk/projects/trinity/ on test machine: qemu-system-x86_64 -enable-kvm -cpu Haswell,+smep,+smap -smp 2 -m 512M caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace): +-----------------------------------------------+------------+------------+ | | 0cb1e5c559 | 7f2b0462b0 | +-----------------------------------------------+------------+------------+ | boot_successes | 12 | 0 | | boot_failures | 0 | 12 | | WARNING:at_drivers/base.c:#device_bind_driver | 0 | 12 | | RIP:device_bind_driver | 0 | 12 | | WARNING:at_drivers/base.c:#driver_sysfs_add | 0 | 12 | | RIP:driver_sysfs_add | 0 | 12 | | WARNING:at_drivers/base.c:#driver_bound | 0 | 12 | | RIP:driver_bound | 0 | 12 | | WARNING:at_drivers/base.c:#device_is_bound | 0 | 12 | | RIP:device_is_bound | 0 | 12 | +-----------------------------------------------+------------+------------+ [ 36.530203] WARNING: CPU: 0 PID: 13 at drivers/base/dd.c:430 device_bind_driver+0x67/0x70 [ 36.548291] Modules linked in: [ 36.553994] CPU: 0 PID: 13 Comm: kworker/0:1 Not tainted 4.19.0-rc1-00235-g7f2b046 #1 [ 36.568178] Workqueue: events deferred_probe_work_func [ 36.577520] RIP: 0010:device_bind_driver+0x67/0x70 [ 36.586425] Code: 2d 48 8b 83 e0 00 00 00 48 85 c0 74 21 48 8b b8 98 00 00 00 48 89 da be 08 00 00 00 48 81 c7 e0 01 00 00 e8 20 4f a7 ff eb 04 <0f> 0b eb bb 89 e8 5b 5d c3 e8 98 41 3e 00 83 3d 31 bf 04 01 00 41 [ 36.619812] RSP: 0000:ffff88000f803bd0 EFLAGS: 00010246 [ 36.629403] RAX: 0000000000000000 RBX: ffff88001c78e800 RCX: 0000000000000000 [ 36.641969] RDX: 0000000000000000 RSI: ffff88001c78e8c0 RDI: 0000000000000246 [ 36.654809] RBP: ffff88001c78d000 R08: ffff8800003fcf68 R09: 00000000a58c6bd0 [ 36.667706] R10: ffff88000f803ba8 R11: ffffffff821697ef R12: 0000000000000003 [ 36.680701] R13: 0000000000000000 R14: ffff88001c7820b0 R15: ffff88001c7820b0 [ 36.694141] FS: 0000000000000000(0000) GS:ffff88001ba00000(0000) knlGS:0000000000000000 [ 36.708561] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 36.719134] CR2: 0000000000000000 CR3: 0000000002466001 CR4: 00000000000206f0 [ 36.731832] Call Trace: [ 36.736825] ? phy_attach_direct+0x10c/0x1f2 [ 36.744536] ? phylink_connect_phy+0x5b/0x86 [ 36.752576] ? dsa_slave_create+0x2f6/0x3ba [ 36.760321] ? dsa_register_switch+0x6e7/0x769 [ 36.768451] ? dev_get_by_name+0x73/0x7b [ 36.775842] ? mdio_probe+0x2e/0x4b [ 36.782127] ? really_probe+0x1ac/0x395 [ 36.789420] ? driver_allows_async_probing+0x2c/0x2c [ 36.798466] ? driver_probe_device+0x130/0x170 [ 36.806761] ? driver_allows_async_probing+0x2c/0x2c [ 36.815543] ? bus_for_each_drv+0x83/0xa5 [ 36.823158] ? __device_attach+0xad/0x140 [ 36.830608] ? bus_probe_device+0x2d/0x97 [ 36.838033] ? deferred_probe_work_func+0x97/0xc0 [ 36.846776] ? process_one_work+0x272/0x48e [ 36.854527] ? process_scheduled_works+0x27/0x2c [ 36.863110] ? worker_thread+0x20c/0x2bf [ 36.870304] ? rescuer_thread+0x2f0/0x2f0 [ 36.877697] ? kthread+0x120/0x128 [ 36.884076] ? kthread_park+0x77/0x77 [ 36.890754] ? ret_from_fork+0x24/0x30 [ 36.897814] ---[ end trace 658073a7d0192606 ]--- To reproduce: git clone https://github.com/intel/lkp-tests.git cd lkp-tests bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email Thanks, lkp

2 years, 4 months

1
0
0 / 0

2021

2020

2019

2018

2017

2016

2015

2014

2013

LKP October 2018