December 2019 - LKP - Ml01.01.Org

[pipe] 3c0edea9b2: lmbench3.PIPE.bandwidth.MB/sec -17.0% regression

by kernel test robot

Greeting, FYI, we noticed a -17.0% regression of lmbench3.PIPE.bandwidth.MB/sec due to commit: commit: 3c0edea9b29f9be6c093f236f762202b30ac9431 ("pipe: Remove sync on wake_ups") https://git.kernel.org/cgit/linux/kernel/git/torvalds/linux.git master in testcase: lmbench3 on test machine: 16 threads Intel(R) Xeon(R) CPU D-1541 @ 2.10GHz with 48G memory with following parameters: test_memory_size: 50% nr_threads: 100% mode: development test: PIPE cpufreq_governor: performance ucode: 0x7000019 test-url: http://www.bitmover.com/lmbench/ If you fix the issue, kindly add following tag Reported-by: kernel test robot <rong.a.chen(a)intel.com> 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_threads/rootfs/tbox_group/test/test_memory_size/testcase/ucode: gcc-7/performance/x86_64-rhel-7.6/development/100%/debian-x86_64-2019-11-14.cgz/lkp-bdw-de1/PIPE/50%/lmbench3/0x7000019 commit: cefa80ced5 ("pipe: Increase the writer-wakeup threshold to reduce context-switch count") 3c0edea9b2 ("pipe: Remove sync on wake_ups") cefa80ced57a2917 3c0edea9b29f9be6c093f236f76 ---------------- --------------------------- %stddev %change %stddev \ | \ 21204 -17.0% 17604 lmbench3.PIPE.bandwidth.MB/sec 14.06 +91.9% 26.99 lmbench3.PIPE.latency.us 134.47 +25.9% 169.28 ± 7% lmbench3.time.elapsed_time 134.47 +25.9% 169.28 ± 7% lmbench3.time.elapsed_time.max 3334392 ± 9% -78.2% 725930 ± 4% lmbench3.time.involuntary_context_switches 1230 ± 2% +25.4% 1542 lmbench3.time.system_time 66167960 ± 2% -24.0% 50257054 lmbench3.time.voluntary_context_switches 5.83 -1.0 4.80 mpstat.cpu.all.usr% 539839 ± 2% -17.4% 445879 ± 6% vmstat.system.cs 39.29 -2.5% 38.31 boot-time.boot 563.99 -2.7% 548.62 boot-time.idle 6437 -1.7% 6330 proc-vmstat.nr_mapped 683646 +5.9% 724211 ± 2% proc-vmstat.pgfault 1330273 ± 37% +1281.6% 18379318 turbostat.C1 0.74 ± 65% +2.6 3.37 ± 6% turbostat.C1% 4392819 ± 5% +31.9% 5792490 ± 5% turbostat.IRQ 16257279 ± 65% +462.7% 91474668 cpuidle.C1.time 1331447 ± 37% +1280.5% 18380948 cpuidle.C1.usage 5997091 ± 32% +266.2% 21964255 cpuidle.POLL.time 3142700 ± 5% +121.5% 6962552 cpuidle.POLL.usage 0.00 +9e+11% 8988 ±111% sched_debug.cfs_rq:/.MIN_vruntime.avg 0.00 +7.7e+12% 76535 ± 86% sched_debug.cfs_rq:/.MIN_vruntime.max 0.00 ± 15% +1.2e+28% 22897 ± 92% sched_debug.cfs_rq:/.MIN_vruntime.stddev 17.75 ± 16% +66.7% 29.58 ± 20% sched_debug.cfs_rq:/.load_avg.min 155.25 ± 8% -22.5% 120.28 ± 7% sched_debug.cfs_rq:/.load_avg.stddev 0.00 +9e+11% 8988 ±111% sched_debug.cfs_rq:/.max_vruntime.avg 0.00 +7.7e+12% 76535 ± 86% sched_debug.cfs_rq:/.max_vruntime.max 0.00 ± 15% +1.2e+28% 22897 ± 92% sched_debug.cfs_rq:/.max_vruntime.stddev 63.45 ± 22% -41.5% 37.15 ± 32% sched_debug.cfs_rq:/.runnable_load_avg.stddev 247.25 ± 20% +93.6% 478.77 ± 16% sched_debug.cfs_rq:/.util_avg.min 380.01 ± 9% -27.2% 276.67 ± 7% sched_debug.cfs_rq:/.util_avg.stddev 342.89 ± 3% -15.9% 288.48 ± 6% sched_debug.cfs_rq:/.util_est_enqueued.avg 997.25 ± 3% -23.2% 766.29 ± 15% sched_debug.cfs_rq:/.util_est_enqueued.max 294.50 ± 4% -30.6% 204.35 ± 14% sched_debug.cfs_rq:/.util_est_enqueued.stddev 534028 ± 9% -37.7% 332865 ± 17% sched_debug.cpu.avg_idle.avg 896297 ± 5% -29.2% 634560 ± 8% sched_debug.cpu.avg_idle.max 260841 ± 4% -28.5% 186459 ± 8% sched_debug.cpu.avg_idle.stddev 1597 ± 2% +31.5% 2099 ± 5% sched_debug.cpu.curr->pid.avg 0.95 ± 11% +39.9% 1.33 ± 11% sched_debug.cpu.nr_running.avg 0.59 ± 11% +20.8% 0.71 ± 9% sched_debug.cpu.nr_running.stddev 525011 ± 8% +16.5% 611478 ± 2% sched_debug.cpu.nr_switches.stddev 36.67 ± 12% +51.6% 55.58 ± 18% sched_debug.cpu.nr_uninterruptible.max -37.75 +68.8% -63.73 sched_debug.cpu.nr_uninterruptible.min 20.71 ± 16% +52.8% 31.65 ± 9% sched_debug.cpu.nr_uninterruptible.stddev 524055 ± 8% +16.4% 610231 ± 2% sched_debug.cpu.sched_count.stddev 179082 ± 15% +431.0% 950904 ± 4% sched_debug.cpu.sched_goidle.avg 941875 ± 8% +90.8% 1797084 sched_debug.cpu.sched_goidle.max 57580 ± 47% +1330.6% 823745 ± 6% sched_debug.cpu.sched_goidle.min 261290 ± 8% +16.8% 305219 ± 2% sched_debug.cpu.sched_goidle.stddev 2351907 ± 2% -22.8% 1815202 ± 5% sched_debug.cpu.ttwu_count.avg 3121048 ± 4% -14.7% 2661346 ± 2% sched_debug.cpu.ttwu_count.max 2204420 ± 2% -23.6% 1683662 ± 6% sched_debug.cpu.ttwu_count.min 263284 ± 8% +15.9% 305082 ± 3% sched_debug.cpu.ttwu_count.stddev 2145030 ± 3% -94.7% 113807 ± 2% sched_debug.cpu.ttwu_local.avg 2170062 ± 3% -94.2% 125644 ± 10% sched_debug.cpu.ttwu_local.max 2117317 ± 3% -94.8% 109613 ± 3% sched_debug.cpu.ttwu_local.min 13155 ± 17% -70.2% 3923 ± 89% sched_debug.cpu.ttwu_local.stddev 8071 ± 4% +15.3% 9304 ± 11% softirqs.CPU0.RCU 13782 ± 12% +105.3% 28301 ± 6% softirqs.CPU0.SCHED 57292 ± 5% +20.8% 69210 ± 6% softirqs.CPU0.TIMER 10409 ± 12% +146.9% 25705 ± 5% softirqs.CPU1.SCHED 8100 ± 2% +18.5% 9598 ± 10% softirqs.CPU10.RCU 10535 ± 9% +131.8% 24423 ± 7% softirqs.CPU10.SCHED 63176 ± 7% +12.3% 70916 ± 4% softirqs.CPU10.TIMER 10881 ± 9% +139.9% 26107 ± 8% softirqs.CPU11.SCHED 59833 ± 4% +19.4% 71424 ± 14% softirqs.CPU11.TIMER 10829 ± 2% +129.5% 24855 ± 8% softirqs.CPU12.SCHED 56830 ± 4% +21.9% 69297 ± 10% softirqs.CPU12.TIMER 8338 ± 7% +15.6% 9636 ± 13% softirqs.CPU13.RCU 9965 ± 19% +164.7% 26377 ± 8% softirqs.CPU13.SCHED 7913 ± 5% +18.8% 9400 ± 13% softirqs.CPU14.RCU 10301 ± 13% +137.8% 24499 ± 13% softirqs.CPU14.SCHED 58108 ± 4% +23.3% 71658 ± 3% softirqs.CPU14.TIMER 10402 ± 9% +141.5% 25126 ± 7% softirqs.CPU15.SCHED 60348 ± 2% +17.2% 70757 ± 10% softirqs.CPU15.TIMER 12252 ± 8% +116.1% 26482 ± 5% softirqs.CPU2.SCHED 60066 ± 4% +21.8% 73163 ± 12% softirqs.CPU2.TIMER 8195 ± 4% +14.2% 9358 ± 7% softirqs.CPU3.RCU 11267 ± 17% +123.3% 25161 ± 3% softirqs.CPU3.SCHED 57990 ± 5% +25.0% 72490 ± 13% softirqs.CPU3.TIMER 11242 ± 12% +134.0% 26309 ± 9% softirqs.CPU4.SCHED 62003 ± 7% +18.1% 73208 softirqs.CPU4.TIMER 10623 ± 21% +150.0% 26564 ± 8% softirqs.CPU5.SCHED 64978 ± 9% +16.4% 75636 ± 9% softirqs.CPU5.TIMER 9820 ± 20% +159.9% 25525 ± 7% softirqs.CPU6.SCHED 60525 ± 13% +25.3% 75836 ± 8% softirqs.CPU6.TIMER 8310 ± 9% +15.4% 9587 ± 7% softirqs.CPU7.RCU 10202 ± 6% +155.3% 26044 ± 11% softirqs.CPU7.SCHED 61012 +20.3% 73383 ± 8% softirqs.CPU7.TIMER 7810 ± 6% +28.2% 10012 ± 12% softirqs.CPU8.RCU 11163 ± 3% +135.9% 26339 ± 11% softirqs.CPU8.SCHED 57386 ± 5% +22.0% 70029 ± 7% softirqs.CPU8.TIMER 11643 ± 15% +121.4% 25780 ± 3% softirqs.CPU9.SCHED 138325 ± 2% +13.1% 156403 ± 10% softirqs.RCU 175325 ± 2% +135.9% 413606 ± 6% softirqs.SCHED 967685 ± 3% +18.5% 1146565 ± 7% softirqs.TIMER 21179 ± 4% +15.9% 24541 ± 6% interrupts.CAL:Function_call_interrupts 1346 ± 4% +16.0% 1561 ± 10% interrupts.CPU0.CAL:Function_call_interrupts 255151 ± 7% +33.5% 340712 ± 7% interrupts.CPU0.LOC:Local_timer_interrupts 1597 ± 17% +536.7% 10169 ± 3% interrupts.CPU0.RES:Rescheduling_interrupts 262988 ± 3% +29.4% 340284 ± 7% interrupts.CPU1.LOC:Local_timer_interrupts 1255 ± 15% +656.0% 9492 interrupts.CPU1.RES:Rescheduling_interrupts 259958 ± 5% +31.0% 340654 ± 7% interrupts.CPU10.LOC:Local_timer_interrupts 1448 ± 21% +526.9% 9079 interrupts.CPU10.RES:Rescheduling_interrupts 264562 ± 2% +28.7% 340366 ± 7% interrupts.CPU11.LOC:Local_timer_interrupts 1176 ± 9% +682.6% 9207 ± 2% interrupts.CPU11.RES:Rescheduling_interrupts 1202 ± 17% +31.8% 1584 ± 3% interrupts.CPU12.CAL:Function_call_interrupts 256269 ± 7% +33.0% 340874 ± 7% interrupts.CPU12.LOC:Local_timer_interrupts 1240 ± 11% +644.6% 9232 interrupts.CPU12.RES:Rescheduling_interrupts 258695 ± 6% +31.8% 340891 ± 7% interrupts.CPU13.LOC:Local_timer_interrupts 1329 ± 34% +605.9% 9383 interrupts.CPU13.RES:Rescheduling_interrupts 1161 ± 16% +39.3% 1618 ± 7% interrupts.CPU14.CAL:Function_call_interrupts 259395 ± 5% +31.3% 340636 ± 7% interrupts.CPU14.LOC:Local_timer_interrupts 1313 ± 26% +603.5% 9240 interrupts.CPU14.RES:Rescheduling_interrupts 256597 ± 7% +32.6% 340354 ± 7% interrupts.CPU15.LOC:Local_timer_interrupts 1293 ± 10% +615.1% 9250 ± 2% interrupts.CPU15.RES:Rescheduling_interrupts 259187 ± 5% +31.2% 340034 ± 7% interrupts.CPU2.LOC:Local_timer_interrupts 1482 ± 18% +566.1% 9873 ± 3% interrupts.CPU2.RES:Rescheduling_interrupts 264129 ± 2% +28.8% 340116 ± 7% interrupts.CPU3.LOC:Local_timer_interrupts 1387 ± 19% +584.8% 9503 interrupts.CPU3.RES:Rescheduling_interrupts 1249 ± 19% +19.5% 1493 ± 3% interrupts.CPU4.CAL:Function_call_interrupts 256073 ± 7% +32.8% 340112 ± 7% interrupts.CPU4.LOC:Local_timer_interrupts 1372 ± 18% +594.0% 9521 ± 4% interrupts.CPU4.RES:Rescheduling_interrupts 258411 ± 6% +31.6% 340115 ± 7% interrupts.CPU5.LOC:Local_timer_interrupts 1267 ± 17% +655.9% 9582 ± 2% interrupts.CPU5.RES:Rescheduling_interrupts 258716 ± 5% +31.3% 339719 ± 7% interrupts.CPU6.LOC:Local_timer_interrupts 1220 ± 7% +666.4% 9350 ± 2% interrupts.CPU6.RES:Rescheduling_interrupts 1192 ± 15% +33.0% 1586 ± 8% interrupts.CPU7.CAL:Function_call_interrupts 255847 ± 7% +32.8% 339836 ± 7% interrupts.CPU7.LOC:Local_timer_interrupts 1217 ± 13% +687.4% 9588 ± 2% interrupts.CPU7.RES:Rescheduling_interrupts 1369 ± 5% +14.4% 1566 ± 6% interrupts.CPU8.CAL:Function_call_interrupts 256492 ± 7% +32.9% 340775 ± 7% interrupts.CPU8.LOC:Local_timer_interrupts 1222 ± 11% +688.9% 9640 ± 7% interrupts.CPU8.RES:Rescheduling_interrupts 263589 ± 3% +29.1% 340310 ± 7% interrupts.CPU9.LOC:Local_timer_interrupts 1486 ± 22% +518.1% 9189 ± 2% interrupts.CPU9.RES:Rescheduling_interrupts 4146065 ± 5% +31.3% 5445793 ± 7% interrupts.LOC:Local_timer_interrupts 21310 ± 11% +610.0% 151304 interrupts.RES:Rescheduling_interrupts 41.41 -8.2% 38.01 ± 3% perf-stat.i.MPKI 3.181e+09 -6.2% 2.984e+09 ± 6% perf-stat.i.branch-instructions 1.81 ± 2% -0.1 1.69 perf-stat.i.branch-miss-rate% 41240020 -15.2% 34981946 ± 8% perf-stat.i.branch-misses 5.891e+08 ± 2% -10.6% 5.264e+08 ± 6% perf-stat.i.cache-misses 5.891e+08 ± 2% -10.6% 5.264e+08 ± 6% perf-stat.i.cache-references 549435 ± 2% -17.1% 455328 ± 7% perf-stat.i.context-switches 1.76 +9.8% 1.93 perf-stat.i.cpi 8164 ± 30% +1194.2% 105659 ± 7% perf-stat.i.cpu-migrations 0.10 ± 6% +0.2 0.27 ± 3% perf-stat.i.dTLB-load-miss-rate% 3249270 ± 8% +223.7% 10518062 ± 6% perf-stat.i.dTLB-load-misses 4.53e+09 -11.1% 4.025e+09 ± 5% perf-stat.i.dTLB-loads 0.05 ± 6% +0.0 0.09 ± 3% perf-stat.i.dTLB-store-miss-rate% 1269803 ± 4% +34.4% 1706370 ± 7% perf-stat.i.dTLB-store-misses 2.468e+09 -22.9% 1.904e+09 ± 6% perf-stat.i.dTLB-stores 51.76 ± 6% +6.1 57.84 ± 2% perf-stat.i.iTLB-load-miss-rate% 2394143 ± 12% +28.6% 3079996 ± 5% perf-stat.i.iTLB-load-misses 2553375 ± 2% +46.1% 3730425 ± 6% perf-stat.i.iTLB-loads 1.464e+10 -8.7% 1.337e+10 ± 5% perf-stat.i.instructions 0.62 -9.2% 0.56 perf-stat.i.ipc 4962 -15.3% 4204 ± 5% perf-stat.i.minor-faults 4962 -15.3% 4204 ± 5% perf-stat.i.page-faults 40.24 -2.1% 39.38 perf-stat.overall.MPKI 1.30 ± 2% -0.1 1.17 ± 2% perf-stat.overall.branch-miss-rate% 1.73 +11.8% 1.93 perf-stat.overall.cpi 43.00 +14.2% 49.10 perf-stat.overall.cycles-between-cache-misses 0.07 ± 9% +0.2 0.26 ± 2% perf-stat.overall.dTLB-load-miss-rate% 0.05 ± 4% +0.0 0.09 perf-stat.overall.dTLB-store-miss-rate% 48.21 ± 5% -3.0 45.24 perf-stat.overall.iTLB-load-miss-rate% 6211 ± 12% -30.1% 4340 perf-stat.overall.instructions-per-iTLB-miss 0.58 -10.5% 0.52 perf-stat.overall.ipc 3.157e+09 -6.0% 2.966e+09 ± 6% perf-stat.ps.branch-instructions 40940076 -15.1% 34774814 ± 8% perf-stat.ps.branch-misses 5.846e+08 ± 2% -10.5% 5.232e+08 ± 5% perf-stat.ps.cache-misses 5.846e+08 ± 2% -10.5% 5.232e+08 ± 5% perf-stat.ps.cache-references 545185 ± 2% -17.0% 452472 ± 7% perf-stat.ps.context-switches 8100 ± 30% +1196.1% 104990 ± 7% perf-stat.ps.cpu-migrations 3224891 ± 8% +224.1% 10452308 ± 6% perf-stat.ps.dTLB-load-misses 4.495e+09 -11.0% 4e+09 ± 5% perf-stat.ps.dTLB-loads 1260065 ± 4% +34.6% 1695722 ± 7% perf-stat.ps.dTLB-store-misses 2.449e+09 -22.7% 1.892e+09 ± 6% perf-stat.ps.dTLB-stores 2375668 ± 12% +28.8% 3060737 ± 5% perf-stat.ps.iTLB-load-misses 2533621 ± 2% +46.3% 3706931 ± 6% perf-stat.ps.iTLB-loads 1.453e+10 -8.5% 1.328e+10 ± 5% perf-stat.ps.instructions 4930 -15.2% 4181 ± 5% perf-stat.ps.minor-faults 4931 -15.2% 4181 ± 5% perf-stat.ps.page-faults 1.964e+12 ± 2% +14.3% 2.244e+12 perf-stat.total.instructions 10.67 ± 18% -10.7 0.00 perf-profile.calltrace.cycles-pp.activate_task.ttwu_do_activate.try_to_wake_up.autoremove_wake_function.__wake_up_common 10.60 ± 18% -10.6 0.00 perf-profile.calltrace.cycles-pp.enqueue_task_fair.activate_task.ttwu_do_activate.try_to_wake_up.autoremove_wake_function 21.89 ± 6% -9.8 12.08 ± 17% perf-profile.calltrace.cycles-pp.copy_page_to_iter.pipe_read.new_sync_read.vfs_read.ksys_read 21.03 ± 4% -9.4 11.63 ± 33% perf-profile.calltrace.cycles-pp.copy_page_from_iter.pipe_write.new_sync_write.vfs_write.ksys_write 8.95 ± 18% -9.0 0.00 perf-profile.calltrace.cycles-pp.enqueue_entity.enqueue_task_fair.activate_task.ttwu_do_activate.try_to_wake_up 18.52 ± 5% -8.8 9.70 ± 32% perf-profile.calltrace.cycles-pp.copyin.copy_page_from_iter.pipe_write.new_sync_write.vfs_write 18.21 ± 5% -8.6 9.58 ± 32% perf-profile.calltrace.cycles-pp.copy_user_enhanced_fast_string.copyin.copy_page_from_iter.pipe_write.new_sync_write 19.21 ± 7% -8.4 10.80 ± 16% perf-profile.calltrace.cycles-pp.copyout.copy_page_to_iter.pipe_read.new_sync_read.vfs_read 18.95 ± 7% -8.3 10.68 ± 16% perf-profile.calltrace.cycles-pp.copy_user_enhanced_fast_string.copyout.copy_page_to_iter.pipe_read.new_sync_read 7.84 ± 18% -7.8 0.00 perf-profile.calltrace.cycles-pp.__account_scheduler_latency.enqueue_entity.enqueue_task_fair.activate_task.ttwu_do_activate 7.68 ± 17% -7.7 0.00 perf-profile.calltrace.cycles-pp.__wake_up_common.pipe_read.new_sync_read.vfs_read.ksys_read 7.60 ± 17% -7.6 0.00 perf-profile.calltrace.cycles-pp.autoremove_wake_function.__wake_up_common.pipe_read.new_sync_read.vfs_read 7.51 ± 17% -7.5 0.00 perf-profile.calltrace.cycles-pp.try_to_wake_up.autoremove_wake_function.__wake_up_common.pipe_read.new_sync_read 6.83 ± 17% -6.8 0.00 perf-profile.calltrace.cycles-pp.__wake_up_common.pipe_write.new_sync_write.vfs_write.ksys_write 6.51 ± 17% -6.5 0.00 perf-profile.calltrace.cycles-pp.autoremove_wake_function.__wake_up_common.pipe_write.new_sync_write.vfs_write 6.43 ± 17% -6.4 0.00 perf-profile.calltrace.cycles-pp.try_to_wake_up.autoremove_wake_function.__wake_up_common.pipe_write.new_sync_write 6.27 ± 18% -6.3 0.00 perf-profile.calltrace.cycles-pp.stack_trace_save_tsk.__account_scheduler_latency.enqueue_entity.enqueue_task_fair.activate_task 5.90 ± 18% -5.9 0.00 perf-profile.calltrace.cycles-pp.arch_stack_walk.stack_trace_save_tsk.__account_scheduler_latency.enqueue_entity.enqueue_task_fair 5.77 ± 18% -5.8 0.00 perf-profile.calltrace.cycles-pp.ttwu_do_activate.try_to_wake_up.autoremove_wake_function.__wake_up_common.pipe_read 4.95 ± 18% -4.9 0.00 perf-profile.calltrace.cycles-pp.ttwu_do_activate.try_to_wake_up.autoremove_wake_function.__wake_up_common.pipe_write 0.91 ± 4% -0.5 0.41 ± 58% perf-profile.calltrace.cycles-pp.alloc_pages_current.pipe_write.new_sync_write.vfs_write.ksys_write 0.73 ± 11% +0.4 1.17 ± 5% perf-profile.calltrace.cycles-pp._raw_spin_lock_irq.pipe_read.new_sync_read.vfs_read.ksys_read 0.35 ±173% +3.3 3.62 ± 45% perf-profile.calltrace.cycles-pp.free_pcppages_bulk.free_unref_page.pipe_read.new_sync_read.vfs_read 0.00 +14.4 14.43 ±154% perf-profile.calltrace.cycles-pp.intel_idle.cpuidle_enter_state.cpuidle_enter.do_idle.cpu_startup_entry 0.00 +15.1 15.06 ±154% perf-profile.calltrace.cycles-pp.cpuidle_enter_state.cpuidle_enter.do_idle.cpu_startup_entry.start_secondary 0.00 +15.1 15.13 ±154% perf-profile.calltrace.cycles-pp.cpuidle_enter.do_idle.cpu_startup_entry.start_secondary.secondary_startup_64 2.68 ±111% +15.2 17.90 ± 37% perf-profile.calltrace.cycles-pp.mutex_spin_on_owner.__mutex_lock.pipe_write.new_sync_write.vfs_write 2.72 ±111% +15.5 18.19 ± 37% perf-profile.calltrace.cycles-pp.__mutex_lock.pipe_write.new_sync_write.vfs_write.ksys_write 0.00 +15.7 15.75 ±153% perf-profile.calltrace.cycles-pp.do_idle.cpu_startup_entry.start_secondary.secondary_startup_64 0.00 +15.8 15.75 ±153% perf-profile.calltrace.cycles-pp.cpu_startup_entry.start_secondary.secondary_startup_64 0.00 +15.8 15.75 ±153% perf-profile.calltrace.cycles-pp.start_secondary.secondary_startup_64 0.00 +16.2 16.17 ±153% perf-profile.calltrace.cycles-pp.secondary_startup_64 2.86 ±113% +16.3 19.18 ± 24% perf-profile.calltrace.cycles-pp.mutex_spin_on_owner.__mutex_lock.pipe_read.new_sync_read.vfs_read 2.90 ±113% +16.5 19.44 ± 24% perf-profile.calltrace.cycles-pp.__mutex_lock.pipe_read.new_sync_read.vfs_read.ksys_read 37.47 ± 6% -17.1 20.39 ± 23% perf-profile.children.cycles-pp.copy_user_enhanced_fast_string 14.56 ± 17% -14.1 0.45 ± 51% perf-profile.children.cycles-pp.__wake_up_common 14.43 ± 15% -14.0 0.41 ± 50% perf-profile.children.cycles-pp.try_to_wake_up 14.12 ± 17% -13.8 0.33 ± 57% perf-profile.children.cycles-pp.autoremove_wake_function 11.05 ± 16% -10.8 0.27 ± 57% perf-profile.children.cycles-pp.ttwu_do_activate 11.02 ± 16% -10.7 0.27 ± 57% perf-profile.children.cycles-pp.activate_task 10.95 ± 16% -10.7 0.27 ± 57% perf-profile.children.cycles-pp.enqueue_task_fair 22.01 ± 6% -9.9 12.13 ± 17% perf-profile.children.cycles-pp.copy_page_to_iter 21.16 ± 4% -9.5 11.69 ± 33% perf-profile.children.cycles-pp.copy_page_from_iter 9.27 ± 16% -9.0 0.22 ± 57% perf-profile.children.cycles-pp.enqueue_entity 18.59 ± 5% -8.9 9.73 ± 32% perf-profile.children.cycles-pp.copyin 19.27 ± 7% -8.4 10.84 ± 16% perf-profile.children.cycles-pp.copyout 8.10 ± 16% -7.9 0.19 ± 57% perf-profile.children.cycles-pp.__account_scheduler_latency 7.89 ± 15% -7.3 0.61 ± 47% perf-profile.children.cycles-pp.pipe_wait 7.24 ± 14% -6.9 0.37 ± 48% perf-profile.children.cycles-pp.__schedule 7.12 ± 15% -6.8 0.29 ± 59% perf-profile.children.cycles-pp.schedule 6.48 ± 16% -6.3 0.16 ± 57% perf-profile.children.cycles-pp.stack_trace_save_tsk 6.21 ± 16% -6.0 0.15 ± 57% perf-profile.children.cycles-pp.arch_stack_walk 3.81 ± 16% -3.7 0.09 ± 59% perf-profile.children.cycles-pp.unwind_next_frame 2.79 ± 15% -2.7 0.11 ± 59% perf-profile.children.cycles-pp.dequeue_task_fair 2.13 ± 14% -2.1 0.08 ± 57% perf-profile.children.cycles-pp.select_task_rq_fair 1.69 ± 14% -1.6 0.06 ± 59% perf-profile.children.cycles-pp.select_idle_sibling 1.48 ± 15% -1.4 0.07 ± 58% perf-profile.children.cycles-pp.update_curr 1.19 ± 16% -1.2 0.03 ±100% perf-profile.children.cycles-pp.reweight_entity 1.25 ± 12% -1.2 0.10 ± 59% perf-profile.children.cycles-pp.pick_next_task_fair 1.20 ± 12% -1.1 0.08 ± 58% perf-profile.children.cycles-pp.update_load_avg 1.11 ± 16% -1.1 0.06 ± 63% perf-profile.children.cycles-pp.dequeue_entity 1.09 ± 19% -1.0 0.13 ± 19% perf-profile.children.cycles-pp._raw_spin_lock_irqsave 0.99 ± 12% -0.9 0.05 ± 58% perf-profile.children.cycles-pp.switch_mm_irqs_off 1.05 ± 6% -0.6 0.41 ± 38% perf-profile.children.cycles-pp.security_file_permission 1.67 ± 3% -0.6 1.09 ± 46% perf-profile.children.cycles-pp.__might_fault 1.81 -0.6 1.24 ± 20% perf-profile.children.cycles-pp.___might_sleep 0.74 ± 8% -0.5 0.26 ± 46% perf-profile.children.cycles-pp.free_unref_page_prepare 0.95 ± 4% -0.5 0.49 ± 32% perf-profile.children.cycles-pp.alloc_pages_current 1.38 ± 3% -0.5 0.91 ± 41% perf-profile.children.cycles-pp.__might_sleep 0.79 ± 3% -0.4 0.35 ± 21% perf-profile.children.cycles-pp.syscall_return_via_sysret 0.79 ± 5% -0.4 0.36 ± 21% perf-profile.children.cycles-pp._cond_resched 0.66 ± 4% -0.4 0.24 ± 39% perf-profile.children.cycles-pp.selinux_file_permission 0.51 ± 11% -0.4 0.10 ± 57% perf-profile.children.cycles-pp.generic_pipe_buf_confirm 0.47 ± 45% -0.4 0.07 ± 58% perf-profile.children.cycles-pp.__mutex_unlock_slowpath 0.46 ± 45% -0.4 0.07 ± 59% perf-profile.children.cycles-pp.wake_up_q 0.80 ± 7% -0.4 0.40 ± 32% perf-profile.children.cycles-pp.free_unref_page_commit 0.68 ± 8% -0.4 0.31 ± 20% perf-profile.children.cycles-pp.entry_SYSCALL_64 0.40 ± 8% -0.3 0.10 ± 79% perf-profile.children.cycles-pp.native_write_msr 0.43 ± 8% -0.3 0.16 ± 19% perf-profile.children.cycles-pp.__fdget_pos 0.41 ± 7% -0.3 0.15 ± 20% perf-profile.children.cycles-pp.__fget_light 0.45 ± 9% -0.2 0.20 ± 25% perf-profile.children.cycles-pp.__put_page 0.42 ± 7% -0.2 0.18 ± 13% perf-profile.children.cycles-pp.__x86_indirect_thunk_rax 0.36 ± 3% -0.2 0.17 ± 28% perf-profile.children.cycles-pp.rcu_all_qs 0.28 ± 12% -0.2 0.10 ± 58% perf-profile.children.cycles-pp.file_has_perm 0.40 ± 9% -0.2 0.24 ± 14% perf-profile.children.cycles-pp.mutex_lock 0.23 ± 9% -0.1 0.08 ± 58% perf-profile.children.cycles-pp.fsnotify 0.21 ± 14% -0.1 0.08 ± 57% perf-profile.children.cycles-pp.avc_has_perm 0.26 ± 10% -0.1 0.13 ± 57% perf-profile.children.cycles-pp.__inc_numa_state 0.15 ± 11% -0.1 0.03 ±100% perf-profile.children.cycles-pp.__fsnotify_parent 0.21 ± 8% -0.1 0.09 ± 57% perf-profile.children.cycles-pp.get_task_policy 0.20 ± 6% -0.1 0.09 ± 27% perf-profile.children.cycles-pp.__page_cache_release 0.17 ± 15% -0.1 0.06 ± 58% perf-profile.children.cycles-pp.mem_cgroup_uncharge 0.21 ± 2% -0.1 0.12 ± 28% perf-profile.children.cycles-pp.touch_atime 0.15 ± 9% -0.1 0.06 ± 58% perf-profile.children.cycles-pp.free_pcp_prepare 0.13 ± 20% -0.1 0.05 ± 58% perf-profile.children.cycles-pp.prep_new_page 0.12 ± 8% -0.1 0.06 ± 58% perf-profile.children.cycles-pp.policy_nodemask 0.13 ± 9% -0.1 0.07 ± 58% perf-profile.children.cycles-pp.atime_needs_update 0.11 ± 9% -0.1 0.05 ± 58% perf-profile.children.cycles-pp.file_update_time 0.10 ± 7% -0.1 0.04 ± 58% perf-profile.children.cycles-pp.__inode_security_revalidate 0.10 ± 15% -0.1 0.04 ± 58% perf-profile.children.cycles-pp.should_fail_alloc_page 0.09 ± 8% -0.1 0.04 ± 58% perf-profile.children.cycles-pp.policy_node 0.09 ± 7% -0.0 0.05 ± 58% perf-profile.children.cycles-pp.current_time 0.14 ± 5% -0.0 0.12 ± 9% perf-profile.children.cycles-pp.__wake_up_common_lock 0.07 ± 17% +0.1 0.13 ± 45% perf-profile.children.cycles-pp.scheduler_tick 0.00 +0.1 0.08 ± 26% perf-profile.children.cycles-pp.osq_lock 0.03 ±173% +0.2 0.20 ± 40% perf-profile.children.cycles-pp.__mod_zone_page_state 0.00 +0.3 0.27 ±130% perf-profile.children.cycles-pp.irq_exit 0.00 +0.4 0.42 ±125% perf-profile.children.cycles-pp.start_kernel 1.56 ± 6% +0.5 2.08 ± 12% perf-profile.children.cycles-pp._raw_spin_lock_irq 0.34 ± 8% +0.7 1.09 ±108% perf-profile.children.cycles-pp.smp_apic_timer_interrupt 0.49 ±110% +3.2 3.65 ± 44% perf-profile.children.cycles-pp.free_pcppages_bulk 0.08 ±133% +14.4 14.51 ±153% perf-profile.children.cycles-pp.intel_idle 0.09 ±132% +15.4 15.54 ±153% perf-profile.children.cycles-pp.cpuidle_enter_state 0.09 ±132% +15.5 15.54 ±153% perf-profile.children.cycles-pp.cpuidle_enter 0.10 ±134% +15.7 15.75 ±153% perf-profile.children.cycles-pp.start_secondary 0.11 ±132% +16.1 16.17 ±153% perf-profile.children.cycles-pp.secondary_startup_64 0.11 ±132% +16.1 16.17 ±153% perf-profile.children.cycles-pp.cpu_startup_entry 0.11 ±132% +16.1 16.18 ±153% perf-profile.children.cycles-pp.do_idle 5.57 ±112% +31.8 37.39 ± 31% perf-profile.children.cycles-pp.mutex_spin_on_owner 5.83 ±110% +32.1 37.97 ± 30% perf-profile.children.cycles-pp.__mutex_lock 37.23 ± 6% -17.0 20.18 ± 24% perf-profile.self.cycles-pp.copy_user_enhanced_fast_string 1.59 ± 15% -1.5 0.04 ± 58% perf-profile.self.cycles-pp.unwind_next_frame 2.09 ± 6% -1.1 0.99 ± 18% perf-profile.self.cycles-pp.do_syscall_64 3.12 -0.9 2.21 ± 43% perf-profile.self.cycles-pp.get_page_from_freelist 1.27 ± 5% -0.7 0.53 ± 38% perf-profile.self.cycles-pp.pipe_write 0.74 ± 17% -0.7 0.03 ±100% perf-profile.self.cycles-pp.update_curr 1.21 ± 10% -0.7 0.53 ± 26% perf-profile.self.cycles-pp.pipe_read 0.69 ± 14% -0.7 0.03 ±100% perf-profile.self.cycles-pp.__schedule 1.59 ± 3% -0.6 1.01 ± 40% perf-profile.self.cycles-pp.__alloc_pages_nodemask 1.77 -0.6 1.21 ± 20% perf-profile.self.cycles-pp.___might_sleep 1.09 ± 6% -0.5 0.58 ± 30% perf-profile.self.cycles-pp.copy_page_to_iter 0.71 ± 8% -0.5 0.22 ± 44% perf-profile.self.cycles-pp.free_unref_page_prepare 0.61 ± 12% -0.5 0.12 ± 18% perf-profile.self.cycles-pp._raw_spin_lock_irqsave 0.79 ± 3% -0.4 0.35 ± 21% perf-profile.self.cycles-pp.syscall_return_via_sysret 0.99 ± 4% -0.4 0.59 ± 39% perf-profile.self.cycles-pp.copy_page_from_iter 0.46 ± 11% -0.4 0.08 ± 57% perf-profile.self.cycles-pp.generic_pipe_buf_confirm 1.22 ± 3% -0.4 0.85 ± 41% perf-profile.self.cycles-pp.__might_sleep 0.68 ± 8% -0.4 0.31 ± 20% perf-profile.self.cycles-pp.entry_SYSCALL_64 0.55 ± 6% -0.4 0.20 ± 37% perf-profile.self.cycles-pp.selinux_file_permission 0.69 ± 8% -0.3 0.36 ± 23% perf-profile.self.cycles-pp.free_unref_page 0.43 ± 7% -0.3 0.11 ± 57% perf-profile.self.cycles-pp.__wake_up_common 0.47 ± 5% -0.3 0.17 ± 40% perf-profile.self.cycles-pp.__might_fault 0.39 ± 9% -0.3 0.10 ± 79% perf-profile.self.cycles-pp.native_write_msr 0.55 ± 7% -0.3 0.28 ± 28% perf-profile.self.cycles-pp.free_unref_page_commit 0.53 ± 8% -0.3 0.26 ± 40% perf-profile.self.cycles-pp.alloc_pages_current 0.40 ± 8% -0.3 0.15 ± 19% perf-profile.self.cycles-pp.__fget_light 0.27 ± 12% -0.2 0.04 ± 59% perf-profile.self.cycles-pp.entry_SYSCALL_64_after_hwframe 0.37 ± 8% -0.2 0.17 ± 10% perf-profile.self.cycles-pp.__x86_indirect_thunk_rax 0.32 ± 3% -0.2 0.15 ± 30% perf-profile.self.cycles-pp._cond_resched 0.22 ± 6% -0.1 0.08 ± 62% perf-profile.self.cycles-pp.copyin 0.17 ± 7% -0.1 0.03 ±100% perf-profile.self.cycles-pp.new_sync_write 0.18 ± 9% -0.1 0.04 ± 58% perf-profile.self.cycles-pp.new_sync_read 0.27 ± 5% -0.1 0.13 ± 36% perf-profile.self.cycles-pp.rcu_all_qs 0.21 ± 8% -0.1 0.08 ± 58% perf-profile.self.cycles-pp.fsnotify 0.21 ± 14% -0.1 0.08 ± 57% perf-profile.self.cycles-pp.avc_has_perm 0.14 ± 8% -0.1 0.03 ±100% perf-profile.self.cycles-pp.__fsnotify_parent 0.23 ± 10% -0.1 0.11 ± 57% perf-profile.self.cycles-pp.__inc_numa_state 0.20 ± 9% -0.1 0.09 ± 58% perf-profile.self.cycles-pp.get_task_policy 0.18 ± 8% -0.1 0.09 ± 25% perf-profile.self.cycles-pp.__page_cache_release 0.18 ± 4% -0.1 0.09 ± 26% perf-profile.self.cycles-pp.copyout 0.13 ± 13% -0.1 0.05 ± 58% perf-profile.self.cycles-pp.mem_cgroup_uncharge 0.14 ± 6% -0.1 0.06 ± 58% perf-profile.self.cycles-pp.free_pcp_prepare 0.12 ± 21% -0.1 0.05 ± 58% perf-profile.self.cycles-pp.prep_new_page 0.12 ± 14% -0.1 0.06 ± 14% perf-profile.self.cycles-pp.__put_page 0.09 ± 9% -0.1 0.03 ±100% perf-profile.self.cycles-pp.policy_node 0.11 ± 7% -0.1 0.05 ± 58% perf-profile.self.cycles-pp.policy_nodemask 0.10 ± 9% -0.1 0.04 ± 58% perf-profile.self.cycles-pp.atime_needs_update 0.09 ± 12% -0.0 0.04 ± 58% perf-profile.self.cycles-pp.security_file_permission 0.00 +0.1 0.08 ± 26% perf-profile.self.cycles-pp.osq_lock 0.03 ±173% +0.2 0.19 ± 42% perf-profile.self.cycles-pp.__mod_zone_page_state 0.06 ±128% +0.3 0.38 ± 25% perf-profile.self.cycles-pp.__mutex_lock 1.50 ± 7% +0.6 2.06 ± 12% perf-profile.self.cycles-pp._raw_spin_lock_irq 0.18 ±128% +1.1 1.29 ± 36% perf-profile.self.cycles-pp.free_pcppages_bulk 0.08 ±133% +14.4 14.50 ±153% perf-profile.self.cycles-pp.intel_idle 5.54 ±112% +31.7 37.20 ± 31% perf-profile.self.cycles-pp.mutex_spin_on_owner lmbench3.time.voluntary_context_switches 8.5e+07 +-+---------------------------------------------------------------+ | + | 8e+07 +-+ : | 7.5e+07 +-+ : : + + | |+ : :+ : :+ .+ .+ .+ .+ + | 7e+07 +-++. .++ + +.+ +.+ ++. .++ + + +.++ :+ : .++.++. | 6.5e+07 +-+ ++ +.+ ++ + +.+ + +| | +. : | 6e+07 +-+ + | 5.5e+07 +-+ | O O O O O O O O O | 5e+07 +-O O O O O O O O O O O OO OO OO OO | 4.5e+07 +-+ O O O O O | | | 4e+07 +-+---------------------------------------------------------------+ lmbench3.PIPE.latency.us 28 +-+--------------------------------------------------------------------+ O OO OO O OO O OO OO O OO OO O OO O OO OO O OO OO O O | 26 +-+ | 24 +-+ | | | 22 +-+ | | | 20 +-+ | | | 18 +-+ | 16 +-+ | | | 14 +-++.++.+.++.+.++.++.+.++.++.+.++.+.++.++.+.++.++.+.++.++.+.++.+.++.++.| | | 12 +-+--------------------------------------------------------------------+ [*] 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, 5 months

3
5
0 / 0

[rcu] ed93dfc6bc: stress-ng.icache.ops_per_sec -15.0% regression

by kernel test robot

Greeting, FYI, we noticed a -15.0% regression of stress-ng.icache.ops_per_sec due to commit: commit: ed93dfc6bc0084485ccad1ff6bd2ea81ab2c03cd ("rcu: Confine ->core_needs_qs accesses to the corresponding CPU") https://git.kernel.org/cgit/linux/kernel/git/torvalds/linux.git master in testcase: stress-ng on test machine: 96 threads Intel(R) Xeon(R) Gold 6252 CPU @ 2.10GHz with 192G memory with following parameters: nr_threads: 100% disk: 1HDD testtime: 1s class: cpu-cache cpufreq_governor: performance ucode: 0x500002c If you fix the issue, kindly add following tag Reported-by: kernel test robot <rong.a.chen(a)intel.com> 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 ========================================================================================= class/compiler/cpufreq_governor/disk/kconfig/nr_threads/rootfs/tbox_group/testcase/testtime/ucode: cpu-cache/gcc-7/performance/1HDD/x86_64-rhel-7.6/100%/debian-x86_64-2019-11-14.cgz/lkp-csl-2sp5/stress-ng/1s/0x500002c commit: 516e5ae0c9 ("rcu: Reset CPU hints when reporting a quiescent state") ed93dfc6bc ("rcu: Confine ->core_needs_qs accesses to the corresponding CPU") 516e5ae0c9401629 ed93dfc6bc0084485ccad1ff6bd ---------------- --------------------------- %stddev %change %stddev \ | \ 39049 -15.0% 33189 ± 14% stress-ng.icache.ops_per_sec 7784 -36.6% 4939 ± 9% stress-ng.membarrier.ops 7648 -37.3% 4793 ± 9% stress-ng.membarrier.ops_per_sec 41517989 -14.4% 35523501 ± 13% stress-ng.time.minor_page_faults 245.82 ± 3% -7.5% 227.44 ± 5% stress-ng.time.system_time 48037 -29.8% 33703 ± 7% stress-ng.time.voluntary_context_switches 2047 +1.6% 2081 boot-time.idle 41603210 -14.4% 35609851 ± 13% proc-vmstat.pgfault 5619 -10.3% 5039 ± 3% vmstat.system.cs 72138 ± 96% -96.6% 2469 ± 41% sched_debug.cpu.avg_idle.min 2.20 +15.1% 2.53 ± 10% sched_debug.cpu.clock.stddev 2.20 +14.9% 2.53 ± 10% sched_debug.cpu.clock_task.stddev 41320 ± 43% +57.0% 64886 ± 24% numa-meminfo.node0.Inactive 40882 ± 44% +57.0% 64182 ± 24% numa-meminfo.node0.Inactive(anon) 40672 ± 48% +59.0% 64687 ± 27% numa-meminfo.node0.Mapped 43343 ± 46% +53.9% 66694 ± 25% numa-meminfo.node0.Shmem 9465 ± 40% +66.8% 15790 ± 26% numa-vmstat.node0.nr_inactive_anon 9494 ± 44% +68.8% 16023 ± 28% numa-vmstat.node0.nr_mapped 10835 ± 46% +53.4% 16620 ± 25% numa-vmstat.node0.nr_shmem 9477 ± 40% +66.7% 15796 ± 26% numa-vmstat.node0.nr_zone_inactive_anon 120.00 ± 59% -55.8% 53.00 ± 64% interrupts.CPU4.RES:Rescheduling_interrupts 62.00 ± 56% -57.7% 26.25 ± 6% interrupts.CPU41.RES:Rescheduling_interrupts 51.50 ± 65% -55.8% 22.75 ± 18% interrupts.CPU52.RES:Rescheduling_interrupts 112.50 ± 66% -71.6% 32.00 ± 66% interrupts.CPU76.RES:Rescheduling_interrupts 74.50 ± 35% -72.8% 20.25 ± 41% interrupts.CPU92.RES:Rescheduling_interrupts 5.04 ±116% -5.0 0.00 perf-profile.calltrace.cycles-pp.release_pages.tlb_flush_mmu.tlb_finish_mmu.exit_mmap.mmput 5.04 ±116% -5.0 0.00 perf-profile.calltrace.cycles-pp.tlb_finish_mmu.exit_mmap.mmput.do_exit.do_group_exit 5.04 ±116% -5.0 0.00 perf-profile.calltrace.cycles-pp.tlb_flush_mmu.tlb_finish_mmu.exit_mmap.mmput.do_exit 5.04 ±116% -5.0 0.00 perf-profile.children.cycles-pp.release_pages 5.04 ±116% -5.0 0.00 perf-profile.children.cycles-pp.tlb_finish_mmu 5.04 ±116% -5.0 0.00 perf-profile.children.cycles-pp.tlb_flush_mmu 5994 ± 2% -15.7% 5054 ± 2% perf-stat.i.context-switches 1466 ± 8% -32.9% 983.03 ± 8% perf-stat.i.cpu-migrations 68.06 +1.4 69.47 perf-stat.i.iTLB-load-miss-rate% 0.03 ± 3% -0.0 0.03 ± 7% perf-stat.overall.dTLB-store-miss-rate% 5741 ± 2% -15.8% 4835 ± 2% perf-stat.ps.context-switches 1407 ± 8% -33.0% 942.99 ± 8% perf-stat.ps.cpu-migrations 5131 ± 40% +186.8% 14716 ± 5% softirqs.CPU0.RCU 6347 ± 30% +185.8% 18142 ± 8% softirqs.CPU1.RCU 4632 ± 13% +203.4% 14054 ± 3% softirqs.CPU10.RCU 3655 ± 3% +269.1% 13494 ± 5% softirqs.CPU11.RCU 4011 ± 12% +251.5% 14100 ± 8% softirqs.CPU12.RCU 4456 ± 28% +216.3% 14097 ± 12% softirqs.CPU13.RCU 3751 ± 4% +259.1% 13470 ± 3% softirqs.CPU14.RCU 3909 ± 9% +247.0% 13566 ± 2% softirqs.CPU15.RCU 3976 ± 6% +235.5% 13340 ± 4% softirqs.CPU16.RCU 4211 ± 20% +245.1% 14533 ± 14% softirqs.CPU17.RCU 6063 ± 63% +124.9% 13636 ± 8% softirqs.CPU18.RCU 4362 ± 32% +230.8% 14434 ± 3% softirqs.CPU19.RCU 3999 ± 3% +256.5% 14258 ± 7% softirqs.CPU2.RCU 5407 ± 29% +176.8% 14966 ± 5% softirqs.CPU20.RCU 4034 ± 14% +254.7% 14309 ± 7% softirqs.CPU21.RCU 3920 ± 8% +245.4% 13544 softirqs.CPU22.RCU 3829 ± 5% +260.7% 13811 ± 4% softirqs.CPU23.RCU 6202 ± 57% +133.9% 14509 ± 6% softirqs.CPU24.RCU 3782 ± 7% +250.6% 13263 ± 3% softirqs.CPU25.RCU 3600 ± 4% +274.9% 13499 ± 8% softirqs.CPU26.RCU 3486 ± 2% +268.7% 12855 ± 2% softirqs.CPU27.RCU 3990 ± 9% +244.4% 13744 ± 5% softirqs.CPU28.RCU 4249 ± 19% +229.1% 13984 ± 13% softirqs.CPU29.RCU 3661 ± 2% +270.9% 13578 ± 5% softirqs.CPU3.RCU 3580 +260.4% 12904 softirqs.CPU30.RCU 3960 ± 14% +220.8% 12703 ± 2% softirqs.CPU31.RCU 15192 ± 8% -9.2% 13790 softirqs.CPU31.TIMER 5046 ± 32% +167.6% 13504 ± 5% softirqs.CPU32.RCU 3461 ± 7% +264.3% 12607 ± 3% softirqs.CPU33.RCU 3569 ± 7% +254.4% 12652 ± 3% softirqs.CPU34.RCU 3719 ± 13% +275.8% 13978 ± 16% softirqs.CPU35.RCU 3433 ± 6% +275.9% 12906 ± 5% softirqs.CPU36.RCU 3749 ± 6% +256.2% 13353 ± 7% softirqs.CPU37.RCU 3596 ± 10% +258.4% 12889 ± 5% softirqs.CPU38.RCU 4498 ± 32% +178.7% 12535 ± 4% softirqs.CPU39.RCU 4117 ± 7% +229.3% 13558 ± 6% softirqs.CPU4.RCU 3547 ± 6% +310.3% 14554 ± 8% softirqs.CPU40.RCU 3652 ± 10% +265.1% 13336 ± 3% softirqs.CPU41.RCU 3388 ± 4% +287.0% 13114 ± 8% softirqs.CPU42.RCU 3314 ± 4% +278.3% 12538 ± 5% softirqs.CPU43.RCU 3386 ± 3% +278.1% 12803 ± 4% softirqs.CPU44.RCU 3497 ± 10% +270.3% 12948 ± 5% softirqs.CPU45.RCU 3592 ± 5% +252.5% 12660 ± 2% softirqs.CPU46.RCU 3511 ± 5% +248.9% 12249 ± 3% softirqs.CPU47.RCU 3579 ± 5% +291.3% 14006 ± 8% softirqs.CPU48.RCU 3496 ± 9% +280.6% 13307 ± 4% softirqs.CPU49.RCU 3772 ± 2% +256.2% 13436 softirqs.CPU5.RCU 3703 ± 4% +262.6% 13428 ± 2% softirqs.CPU50.RCU 3512 ± 6% +271.1% 13034 ± 5% softirqs.CPU51.RCU 4027 ± 21% +229.3% 13261 ± 6% softirqs.CPU52.RCU 3517 ± 6% +324.2% 14919 ± 20% softirqs.CPU53.RCU 3730 ± 5% +255.0% 13244 ± 3% softirqs.CPU54.RCU 3542 ± 4% +277.3% 13364 ± 2% softirqs.CPU55.RCU 3591 ± 8% +279.1% 13615 ± 5% softirqs.CPU56.RCU 4717 ± 46% +173.3% 12894 ± 2% softirqs.CPU57.RCU 3311 ± 3% +304.6% 13399 ± 7% softirqs.CPU58.RCU 3613 ± 7% +278.9% 13694 ± 2% softirqs.CPU59.RCU 4223 ± 12% +224.0% 13685 ± 2% softirqs.CPU6.RCU 3909 ± 25% +232.4% 12994 ± 5% softirqs.CPU60.RCU 4343 ± 28% +207.2% 13343 ± 3% softirqs.CPU61.RCU 3477 ± 7% +281.6% 13270 ± 4% softirqs.CPU62.RCU 3362 ± 4% +328.8% 14419 ± 9% softirqs.CPU63.RCU 4015 ± 16% +236.2% 13499 ± 5% softirqs.CPU64.RCU 4687 ± 44% +179.5% 13100 ± 4% softirqs.CPU65.RCU 3714 ± 11% +267.0% 13631 ± 11% softirqs.CPU66.RCU 3438 ± 5% +287.9% 13339 ± 3% softirqs.CPU67.RCU 3819 ± 13% +251.3% 13415 ± 6% softirqs.CPU68.RCU 3302 ± 5% +305.5% 13389 ± 4% softirqs.CPU69.RCU 3919 ± 9% +244.2% 13491 ± 6% softirqs.CPU7.RCU 3456 ± 3% +292.4% 13563 ± 7% softirqs.CPU70.RCU 3709 ± 13% +295.1% 14655 ± 5% softirqs.CPU71.RCU 3105 ± 6% +332.1% 13420 ± 6% softirqs.CPU72.RCU 3317 ± 8% +289.3% 12917 ± 3% softirqs.CPU73.RCU 3286 ± 3% +281.8% 12544 ± 3% softirqs.CPU74.RCU 3243 ± 4% +282.8% 12414 ± 4% softirqs.CPU75.RCU 3195 ± 3% +285.6% 12322 ± 3% softirqs.CPU76.RCU 3243 ± 6% +274.0% 12131 ± 3% softirqs.CPU77.RCU 3322 ± 6% +273.0% 12391 ± 5% softirqs.CPU78.RCU 3207 ± 3% +293.8% 12629 ± 5% softirqs.CPU79.RCU 4374 ± 13% +215.9% 13817 softirqs.CPU8.RCU 3409 ± 11% +271.5% 12666 softirqs.CPU80.RCU 3475 ± 14% +253.7% 12290 ± 3% softirqs.CPU81.RCU 3625 ± 22% +239.7% 12315 ± 2% softirqs.CPU82.RCU 3192 ± 4% +307.2% 13000 ± 11% softirqs.CPU83.RCU 3328 ± 13% +266.4% 12194 ± 3% softirqs.CPU84.RCU 3380 ± 10% +262.0% 12238 ± 5% softirqs.CPU85.RCU 3199 ± 7% +300.8% 12822 ± 10% softirqs.CPU86.RCU 3378 ± 11% +261.3% 12205 ± 5% softirqs.CPU87.RCU 3442 ± 7% +264.2% 12538 ± 6% softirqs.CPU88.RCU 3242 ± 7% +279.7% 12313 ± 6% softirqs.CPU89.RCU 4646 ± 25% +187.7% 13366 ± 5% softirqs.CPU9.RCU 3078 ± 5% +294.0% 12129 ± 5% softirqs.CPU90.RCU 3091 ± 5% +296.0% 12242 ± 5% softirqs.CPU91.RCU 3247 ± 6% +296.6% 12876 ± 11% softirqs.CPU92.RCU 3253 ± 4% +272.5% 12120 ± 5% softirqs.CPU93.RCU 3180 ± 4% +280.6% 12103 ± 3% softirqs.CPU94.RCU 3395 ± 9% +274.1% 12701 ± 6% softirqs.CPU95.RCU 364674 +250.4% 1277851 softirqs.RCU stress-ng.time.voluntary_context_switches 50000 +-+-----------------------------------------------------------------+ 45000 +-+ ++.++ ++ ++++++++++.++++++++ + +++++++++ +++ + +++.++ +| | : : :: : | 40000 +-+ : O O:: O : O O | 35000 +-+O: : O: O OOOOO O O OOOO O O | | :O :OO :OO : OOO OOO O OOOOO OO | 30000 +-+ : : :: : O O | 25000 +-+ : : :: : | 20000 +-+: : : : : | |: : : : : : | 15000 +-+: : : : : | 10000 OO+: : : : : | |: : : : : : | 5000 +-+: : : : : | 0 +-O-O--O------------------------------------------------------------+ stress-ng.membarrier.ops_per_sec 8000 +-+------------------------------------------------------------------+ | +.+++ ++ +++++++.++++++++.+++ + ++.+++++++ +.++ + + ++.+ ++| 7000 +-+ : : :: : + | 6000 +-+ : : :: : | | : O O:: OOO O O O O | 5000 +-+O: : O: :OOOO O O O O O O | | : O : :O : O O OO O O O | 4000 +-+ : :OO ::O : O O OOOO O O O | |: : : : : : | 3000 +-+: : : : : | 2000 +-+: : : : : | |: : : : : : | 1000 +-+: : : : : | OO : : : : : | 0 +-O-O--O-------------------------------------------------------------+ [*] 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, 5 months

3
5
0 / 0

d8bc22f0b6 ("net: WireGuard secure network tunnel"): BUG: kernel reboot-without-warning in boot stage

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/zx2c4/linux.git jd/wireguard-azinc commit d8bc22f0b63068476818bfb607a34487676815e6 Author: Jason A. Donenfeld <Jason(a)zx2c4.com> AuthorDate: Wed Nov 20 20:38:49 2019 +0100 Commit: Jason A. Donenfeld <Jason(a)zx2c4.com> CommitDate: Thu Dec 5 16:55:58 2019 +0100 net: WireGuard secure network tunnel WireGuard is a layer 3 secure networking tunnel made specifically for the kernel, that aims to be much simpler and easier to audit than IPsec. Extensive documentation and description of the protocol and considerations, along with formal proofs of the cryptography, are available at: * https://www.wireguard.com/ * https://www.wireguard.com/papers/wireguard.pdf This commit implements WireGuard as a simple network device driver, accessible in the usual RTNL way used by virtual network drivers. It makes use of the udp_tunnel APIs, GRO, GSO, NAPI, and the usual set of networking subsystem APIs. It has a somewhat novel multicore queueing system designed for maximum throughput and minimal latency of encryption operations, but it is implemented modestly using workqueues and NAPI. Configuration is done via generic Netlink, and following a review from the Netlink maintainer a year ago, several high profile userspace tools have already implemented the API. This commit also comes with several different tests, both in-kernel tests and out-of-kernel tests based on network namespaces, taking profit of the fact that sockets used by WireGuard intentionally stay in the namespace the WireGuard interface was originally created, exactly like the semantics of userspace tun devices. See wireguard.com/netns/ for pictures and examples. The source code is fairly short, but rather than combining everything into a single file, WireGuard is developed as cleanly separable files, making auditing and comprehension easier. Things are laid out as follows: * noise.[ch], cookie.[ch], messages.h: These implement the bulk of the cryptographic aspects of the protocol, and are mostly data-only in nature, taking in buffers of bytes and spitting out buffers of bytes. They also handle reference counting for their various shared pieces of data, like keys and key lists. * ratelimiter.[ch]: Used as an integral part of cookie.[ch] for ratelimiting certain types of cryptographic operations in accordance with particular WireGuard semantics. * allowedips.[ch], peerlookup.[ch]: The main lookup structures of WireGuard, the former being trie-like with particular semantics, an integral part of the design of the protocol, and the latter just being nice helper functions around the various hashtables we use. * device.[ch]: Implementation of functions for the netdevice and for rtnl, responsible for maintaining the life of a given interface and wiring it up to the rest of WireGuard. * peer.[ch]: Each interface has a list of peers, with helper functions available here for creation, destruction, and reference counting. * socket.[ch]: Implementation of functions related to udp_socket and the general set of kernel socket APIs, for sending and receiving ciphertext UDP packets, and taking care of WireGuard-specific sticky socket routing semantics for the automatic roaming. * netlink.[ch]: Userspace API entry point for configuring WireGuard peers and devices. The API has been implemented by several userspace tools and network management utility, and the WireGuard project distributes the basic wg(8) tool. * queueing.[ch]: Shared function on the rx and tx path for handling the various queues used in the multicore algorithms. * send.c: Handles encrypting outgoing packets in parallel on multiple cores, before sending them in order on a single core, via workqueues and ring buffers. Also handles sending handshake and cookie messages as part of the protocol, in parallel. * receive.c: Handles decrypting incoming packets in parallel on multiple cores, before passing them off in order to be ingested via the rest of the networking subsystem with GRO via the typical NAPI poll function. Also handles receiving handshake and cookie messages as part of the protocol, in parallel. * timers.[ch]: Uses the timer wheel to implement protocol particular event timeouts, and gives a set of very simple event-driven entry point functions for callers. * main.c, version.h: Initialization and deinitialization of the module. * selftest/*.h: Runtime unit tests for some of the most security sensitive functions. * tools/testing/selftests/wireguard/netns.sh: Aforementioned testing script using network namespaces. This commit aims to be as self-contained as possible, implementing WireGuard as a standalone module not needing much special handling or coordination from the network subsystem. I expect for future optimizations to the network stack to positively improve WireGuard, and vice-versa, but for the time being, this exists as intentionally standalone. We introduce a menu option for CONFIG_WIREGUARD, as well as providing a verbose debug log and self-tests via CONFIG_WIREGUARD_DEBUG. Signed-off-by: Jason A. Donenfeld <Jason(a)zx2c4.com> Cc: David Miller <davem(a)davemloft.net> Cc: Greg KH <gregkh(a)linuxfoundation.org> Cc: Linus Torvalds <torvalds(a)linux-foundation.org> Cc: Herbert Xu <herbert(a)gondor.apana.org.au> Cc: linux-crypto(a)vger.kernel.org Cc: linux-kernel(a)vger.kernel.org Cc: netdev(a)vger.kernel.org aaa35ee980 crypto: arch - conditionalize crypto api in arch glue for lib code d8bc22f0b6 net: WireGuard secure network tunnel bd75d5ec36 [DO NOT UPSTREAM] integration tree maintainer scripts +-------------------------------------------------+------------+------------+------------+ | | aaa35ee980 | d8bc22f0b6 | bd75d5ec36 | +-------------------------------------------------+------------+------------+------------+ | boot_successes | 39 | 0 | 0 | | boot_failures | 0 | 19 | 24 | | BUG:kernel_reboot-without-warning_in_boot_stage | 0 | 19 | 24 | +-------------------------------------------------+------------+------------+------------+ If you fix the issue, kindly add following tag Reported-by: kernel test robot <lkp(a)intel.com> needed_size: 0x0000000007c00000 trampoline_32bit: 0x000000000009d000 Decompressing Linux... Parsing ELF... done. Booting the kernel. BUG: kernel reboot-without-warning in boot stage Linux version 5.4.0-10273-gd8bc22f0b6306 #1 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/yocto-vm-yocto/x86_64-randconfig-c001-20191205/zx2c4:jd:wireguard-azinc:d8bc22f0b63068476818bfb607a34487676815e6:bisect-BUG:_kernel_reboot-without-warning_in_boot_stage/.vmlinuz-d8bc22f0b63068476818bfb607a34487676815e6-20191206202648-4:yocto-vm-yocto-cfb84499a0ac branch=zx2c4/jd/wireguard-azinc BOOT_IMAGE=/pkg/linux/x86_64-randconfig-c001-20191205/gcc-7/d8bc22f0b63068476818bfb607a34487676815e6/vmlinuz-5.4.0-10273-gd8bc22f0b6306 rcuperf.shutdown=0 watchdog_thresh=60 # HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD git bisect start bd75d5ec36a5b9bb8028bcfc6754b6a32e6c2261 596cf45cbf6e4fa7bcb0df33e373a7d062b644b5 -- git bisect good aaa35ee980a42670d5d91d5e54589d9471fb3ed7 # 15:10 G 31 0 0 0 crypto: arch - conditionalize crypto api in arch glue for lib code git bisect bad d8bc22f0b63068476818bfb607a34487676815e6 # 15:21 B 0 19 57 0 net: WireGuard secure network tunnel # first bad commit: [d8bc22f0b63068476818bfb607a34487676815e6] net: WireGuard secure network tunnel git bisect good aaa35ee980a42670d5d91d5e54589d9471fb3ed7 # 19:07 G 31 0 0 0 crypto: arch - conditionalize crypto api in arch glue for lib code # extra tests with debug options git bisect good d8bc22f0b63068476818bfb607a34487676815e6 # 19:39 G 10 0 0 2 net: WireGuard secure network tunnel # extra tests on head commit of zx2c4/jd/wireguard-azinc git bisect bad bd75d5ec36a5b9bb8028bcfc6754b6a32e6c2261 # 00:42 B 0 24 54 0 [DO NOT UPSTREAM] integration tree maintainer scripts # bad: [bd75d5ec36a5b9bb8028bcfc6754b6a32e6c2261] [DO NOT UPSTREAM] integration tree maintainer scripts # extra tests on revert first bad commit git bisect good 69d6861b57da29459a51c7ea0cdd4406bf3647ca # 01:46 G 11 0 0 0 Revert "net: WireGuard secure network tunnel" # good: [69d6861b57da29459a51c7ea0cdd4406bf3647ca] Revert "net: WireGuard secure network tunnel" --- 0-DAY kernel test infrastructure Open Source Technology Center https://lists.01.org/hyperkitty/list/lkp@lists.01.org Intel Corporation

2 years, 5 months

1
0
0 / 0

[x86] 2fdaa9d430: INFO:trying_to_register_non-static_key

by kernel test robot

FYI, we noticed the following commit (built with gcc-7): commit: 2fdaa9d4301cd46ef0e82989afe7051c679e6d05 ("x86: mm: convert dump_pagetables to use walk_page_range") https://git.kernel.org/cgit/linux/kernel/git/next/linux-next.git master in testcase: boot on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 8G caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace): +----------------------------------------+------------+------------+ | | be0d18d4f9 | 2fdaa9d430 | +----------------------------------------+------------+------------+ | boot_successes | 33 | 0 | | boot_failures | 0 | 11 | | INFO:trying_to_register_non-static_key | 0 | 11 | +----------------------------------------+------------+------------+ If you fix the issue, kindly add following tag Reported-by: kernel test robot <rong.a.chen(a)intel.com> [ 24.871026] INFO: trying to register non-static key. [ 24.871608] the code is fine but needs lockdep annotation. [ 24.872240] turning off the locking correctness validator. [ 24.872880] CPU: 0 PID: 1 Comm: swapper Tainted: G T 5.4.0-14407-g2fdaa9d4301cd #2 [ 24.874021] Call Trace: [ 24.874980] dump_stack+0x23/0x2c [ 24.875383] register_lock_class+0xcca/0xce0 [ 24.875886] ? arch_stack_walk+0x9a/0x130 [ 24.876357] ? is_dynamic_key+0x190/0x190 [ 24.876828] ? ret_from_fork+0x24/0x30 [ 24.877269] ? stack_trace_save+0xab/0x100 [ 24.877749] __lock_acquire+0xe0/0x2760 [ 24.878202] ? __list_add_valid+0x3a/0x130 [ 24.878867] ? save_trace+0x473/0x5a0 [ 24.879298] ? mark_lock+0x760/0x760 [ 24.879720] lock_acquire+0x15a/0x290 [ 24.880153] ? __walk_page_range+0x1264/0x1570 [ 24.880672] _raw_spin_lock+0x4f/0xd0 [ 24.881105] ? __walk_page_range+0x1264/0x1570 [ 24.881621] __walk_page_range+0x1264/0x1570 [ 24.882123] walk_page_range_novma+0x100/0x1b0 [ 24.882774] ? walk_page_range+0x2a0/0x2a0 [ 24.883255] ? __read_once_size_nocheck+0x30/0x30 [ 24.883920] ? ret_from_fork+0x24/0x30 [ 24.884360] ? __orc_find+0x8a/0xe0 [ 24.884772] ptdump_walk_pgd+0x74/0xe0 [ 24.885212] ptdump_walk_pgd_level_core+0x164/0x240 [ 24.885780] ? hugetlb_get_unmapped_area+0x840/0x840 [ 24.886414] ? __orc_find+0x8a/0xe0 [ 24.886886] ? put_dec_trunc8+0x49/0x180 [ 24.887346] ? ptdump_walk_pgd_level_core+0x240/0x240 [ 24.887934] ? __kasan_check_write+0x2e/0x40 [ 24.888433] ptdump_walk_pgd_level_user_core+0xfa/0x180 [ 24.889160] ? note_page+0x1020/0x1020 [ 24.889603] ? ptdump_walk_pgd+0x3b/0xe0 [ 24.890065] ? pti_clone_pgtable+0x2e7/0x4a0 [ 24.890827] ? ptdump_walk_user_pgd_level_checkwx+0x4a/0x90 [ 24.891470] ? rest_init+0x200/0x200 [ 24.891891] ptdump_walk_user_pgd_level_checkwx+0x85/0x90 [ 24.892516] pti_finalize+0x99/0xe6 [ 24.892926] kernel_init+0x68/0x1d0 [ 24.893338] ? rest_init+0x200/0x200 [ 24.893758] ret_from_fork+0x24/0x30 [ 24.894448] x86/mm: Checked W+X mappings: passed, no W+X pages found. [ 24.895234] Run /init as init process [ 25.017155] systemd[1]: RTC configured in localtime, applying delta of 480 minutes to system time. [ 25.041104] random: systemd: uninitialized urandom read (16 bytes read) [ 25.044181] random: systemd: uninitialized urandom read (16 bytes read) [ 25.117619] random: systemd: uninitialized urandom read (16 bytes read) Starting Remount Root and Kernel File Systems... Mounting Debug File System... [ 25.272048] random: fast init done Starting Journal Service... Mounting RPC Pipe File System... Starting Load Kernel Modules... Mounting Configuration File System... Starting Apply Kernel Variables... [ 25.439662] _warn_unseeded_randomness: 173 callbacks suppressed [ 25.439675] random: get_random_u64 called from arch_rnd+0x41/0x70 with crng_init=1 [ 25.441546] random: get_random_u64 called from randomize_stack_top+0x6a/0xe0 with crng_init=1 [ 25.442775] random: get_random_u32 called from arch_align_stack+0x72/0x90 with crng_init=1 Starting Create Static Device Nodes in /dev... Starting udev Coldplug all Devices... Starting Load/Save Random Seed... Starting Preprocess NFS configuration... Starting udev Kernel Device Manager... Starting Raise network interfaces... Starting Flush Journal to Persistent Storage... Starting Create Volatile Files and Directories... Starting RPC bind portmap service... Starting Update UTMP about System Boot/Shutdown... Starting Network Time Synchronization... [ 26.526563] _warn_unseeded_randomness: 353 callbacks suppressed [ 26.526582] random: get_random_bytes called from flow_hash_from_keys+0x45d/0x480 with crng_init=1 [ 26.529625] random: get_random_bytes called from addrconf_dad_kick+0xf7/0x1c0 with crng_init=1 [ 26.688707] random: get_random_u32 called from bucket_table_alloc+0xd3/0x260 with crng_init=1 [ 28.506874] _warn_unseeded_randomness: 21 callbacks suppressed [ 28.506890] random: get_random_u64 called from copy_process+0x4a3/0x4860 with crng_init=1 Starting Permit User Sessions... [ 28.553873] random: get_random_u64 called from arch_rnd+0x41/0x70 with crng_init=1 [ 28.555049] random: get_random_u64 called from randomize_stack_top+0x6a/0xe0 with crng_init=1 Starting System Logging Service... Starting LKP bootstrap... Starting LSB: Start and stop bmc-watchdog... Starting LSB: Execute the kexec -e command to reboot system... Starting OpenBSD Secure Shell server... Starting /etc/rc.local Compatibility... Starting Login Service... [ 28.860837] rc.local[469]: mkdir: cannot create directory '/var/lock/lkp-bootstrap.lock': File exists Starting LSB: Load kernel image with kexec... [ 29.527639] _warn_unseeded_randomness: 465 callbacks suppressed [ 29.528295] random: get_random_u64 called from arch_rnd+0x41/0x70 with crng_init=1 [ 29.531253] random: get_random_u64 called from randomize_stack_top+0x6a/0xe0 with crng_init=1 [ 29.533026] random: get_random_u32 called from arch_align_stack+0x72/0x90 with crng_init=1 LKP: HOSTNAME vm-snb-ssd-53d351239021, MAC 52:54:00:12:34:56, kernel 5.4.0-14407-g2fdaa9d4301cd 2, serial console /dev/ttyS0 [ 30.534319] _warn_unseeded_randomness: 135 callbacks suppressed [ 30.534330] random: get_random_u32 called from cache_alloc_refill+0x9c3/0x1600 with crng_init=1 [ 30.585486] random: get_random_u64 called from arch_rnd+0x41/0x70 with crng_init=1 [ 30.586609] random: get_random_u64 called from randomize_stack_top+0x6a/0xe0 with crng_init=1 To reproduce: # build kernel cd linux cp config-5.4.0-14407-g2fdaa9d4301cd .config make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 olddefconfig prepare modules_prepare bzImage 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, 5 months

1
0
0 / 0

[block] 48d9b0d431: BUG:kernel_NULL_pointer_dereference,address

by kernel test robot

FYI, we noticed the following commit (built with gcc-7): commit: 48d9b0d43105e0da2b7c135eedd24e51234fb5e4 ("block: account statistics for passthrough requests") https://git.kernel.org/cgit/linux/kernel/git/torvalds/linux.git master in testcase: rcuperf with following parameters: runtime: 300s perf_type: tasks on test machine: qemu-system-i386 -enable-kvm -cpu SandyBridge -smp 2 -m 8G caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace): +-------------------------------------------------------+------------+------------+ | | 8148f0b564 | 48d9b0d431 | +-------------------------------------------------------+------------+------------+ | boot_successes | 8 | 0 | | boot_failures | 0 | 8 | | BUG:kernel_NULL_pointer_dereference,address | 0 | 8 | | Oops:#[##] | 0 | 8 | | EIP:blk_account_io_completion | 0 | 8 | | EIP:ide_output_data | 0 | 8 | | Kernel_panic-not_syncing:Fatal_exception_in_interrupt | 0 | 8 | +-------------------------------------------------------+------------+------------+ If you fix the issue, kindly add following tag Reported-by: kernel test robot <rong.a.chen(a)intel.com> [ 14.392111] BUG: kernel NULL pointer dereference, address: 000002ac [ 14.392607] #PF: supervisor write access in kernel mode [ 14.392607] #PF: error_code(0x0002) - not-present page [ 14.392607] *pde = 00000000 [ 14.392607] Oops: 0002 [#1] [ 14.392607] CPU: 0 PID: 237 Comm: kworker/0:1H Not tainted 5.4.0-rc2-00011-g48d9b0d43105e #1 [ 14.392607] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014 [ 14.392607] Workqueue: kblockd drive_rq_insert_work [ 14.392607] EIP: blk_account_io_completion+0x7a/0xf0 [ 14.392607] Code: 89 54 24 08 31 d2 89 4c 24 04 31 c9 c7 04 24 02 00 00 00 c1 ee 09 e8 f5 21 a6 ff e8 70 5c a7 ff 8b 53 60 8d 04 bd 00 00 00 00 <01> b4 02 ac 02 00 00 8b 9a 88 02 00 00 85 db 74 11 85 d2 74 51 8b [ 14.392607] EAX: 00000000 EBX: f5b80000 ECX: 00000000 EDX: 00000000 [ 14.392607] ESI: 00000000 EDI: 00000000 EBP: f3031e70 ESP: f3031e54 [ 14.392607] DS: 007b ES: 007b FS: 0000 GS: 0000 SS: 0068 EFLAGS: 00010046 [ 14.392607] CR0: 80050033 CR2: 000002ac CR3: 03c25000 CR4: 000406d0 [ 14.392607] Call Trace: [ 14.392607] <IRQ> [ 14.392607] ? blk_account_io_completion+0x38/0xf0 [ 14.392607] blk_update_request+0x85/0x420 [ 14.392607] ? _raw_spin_unlock_irqrestore+0x4f/0x60 [ 14.392607] ? trace_hardirqs_off+0xca/0xe0 [ 14.392607] ? _raw_spin_unlock_irqrestore+0x4f/0x60 [ 14.392607] ? complete+0x41/0x50 [ 14.392607] ide_end_rq+0x38/0xa0 [ 14.392607] ide_complete_rq+0x3d/0x70 [ 14.392607] cdrom_newpc_intr+0x258/0xba0 [ 14.392607] ? find_held_lock+0x2f/0xa0 [ 14.392607] ? cdrom_analyze_sense_data+0x1b0/0x1b0 [ 14.392607] ide_intr+0x135/0x250 [ 14.392607] __handle_irq_event_percpu+0x3e/0x250 [ 14.392607] handle_irq_event_percpu+0x1f/0x50 [ 14.392607] handle_irq_event+0x32/0x60 [ 14.392607] ? handle_fasteoi_irq+0x160/0x160 [ 14.392607] handle_level_irq+0x6c/0x110 [ 14.392607] handle_irq+0x72/0xa0 [ 14.392607] </IRQ> [ 14.392607] do_IRQ+0x45/0xad [ 14.392607] common_interrupt+0x115/0x11c [ 14.392607] EIP: ide_output_data+0xb7/0x140 [ 14.392607] Code: 00 00 00 0f b7 03 66 89 07 83 c3 02 39 d3 75 f3 83 c4 14 5b 5e 5f 5d c3 8d 76 00 8b 4d ec d1 e9 80 7d f0 00 75 15 89 de 89 fa <f3> 66 6f 83 c4 14 5b 5e 5f 5d c3 8d b6 00 00 00 00 85 c9 74 ee 49 [ 14.392607] EAX: 00000000 EBX: f5b800ac ECX: 00000000 EDX: 00000170 [ 14.392607] ESI: f5b800b8 EDI: 00000170 EBP: f6a57dac ESP: f6a57d8c [ 14.392607] DS: 007b ES: 007b FS: 0000 GS: 0000 SS: 0068 EFLAGS: 00010246 [ 14.392607] ? print_usage_bug+0x9b/0x1f0 [ 14.392607] ? ide_output_data+0xb7/0x140 [ 14.392607] ? ide_set_handler+0x42/0x50 [ 14.392607] ide_transfer_pc+0x11c/0x2a0 [ 14.392607] ? ide_check_atapi_device+0x130/0x130 [ 14.392607] ? ide_pc_intr+0x3d0/0x3d0 [ 14.392607] ide_issue_pc+0x160/0x250 [ 14.392607] ? ide_check_atapi_device+0x130/0x130 [ 14.392607] ide_cd_do_request+0x18e/0x3d0 [ 14.392607] ide_issue_rq+0x13a/0x6a0 [ 14.392607] ? _raw_spin_unlock_irq+0x22/0x30 [ 14.392607] ? lockdep_hardirqs_on+0xe4/0x190 [ 14.392607] ? _raw_spin_unlock_irq+0x22/0x30 [ 14.392607] ? trace_hardirqs_on+0x38/0xe0 [ 14.392607] ? drive_rq_insert_work+0x7a/0xf0 [ 14.392607] drive_rq_insert_work+0x8a/0xf0 [ 14.392607] process_one_work+0x207/0x500 [ 14.392607] ? process_one_work+0x16b/0x500 [ 14.392607] worker_thread+0x39/0x400 [ 14.392607] kthread+0xee/0x110 [ 14.392607] ? process_one_work+0x500/0x500 [ 14.392607] ? kthread_create_on_node+0x30/0x30 [ 14.392607] ret_from_fork+0x1e/0x28 [ 14.392607] Modules linked in: [ 14.392607] CR2: 00000000000002ac [ 14.392607] ---[ end trace 2efe0b990b41cd59 ]--- To reproduce: # build kernel cd linux cp config-5.4.0-rc2-00011-g48d9b0d43105e .config make HOSTCC=gcc-7 CC=gcc-7 ARCH=i386 olddefconfig prepare modules_prepare bzImage 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, 5 months

1
0
0 / 0

[perf] 9dc510db74: perf-sanity-tests.Read_samples_using_the_mmap_interface.fail

by kernel test robot

FYI, we noticed the following commit (built with gcc-7): commit: 9dc510db74b1b873853747098e1cd66b71e63210 ("[PATCH v7] perf: Sharing PMU counters across compatible events ") url: https://github.com/0day-ci/linux/commits/Song-Liu/perf-Sharing-PMU-counte... in testcase: perf-sanity-tests with following parameters: perf_compiler: gcc ucode: 0x27 on test machine: 8 threads Intel(R) Core(TM) i7-4770 CPU @ 3.40GHz with 8G memory caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace): make: Leaving directory '/usr/src/perf_selftests-x86_64-rhel-7.6-9dc510db74b1b873853747098e1cd66b71e63210/tools/perf' 2019-12-05 16:40:18 cd /usr/src/perf_selftests-x86_64-rhel-7.6-9dc510db74b1b873853747098e1cd66b71e63210/tools/perf 2019-12-05 16:40:18 mkdir -p /pkg 2019-12-05 16:40:18 mkdir -p /kbuild/obj/consumer/x86_64-rhel-7.6 2019-12-05 16:40:18 cp /pkg/linux/x86_64-rhel-7.6/gcc-7/9dc510db74b1b873853747098e1cd66b71e63210/vmlinux.xz /tmp 2019-12-05 16:40:18 unxz -k /tmp/vmlinux.xz 2019-12-05 16:40:21 cp /tmp/vmlinux /kbuild/obj/consumer/x86_64-rhel-7.6 ignored_by_lkp: BPF filter ignored_by_lkp: LLVM search and compile ignored_by_lkp: Add vfs_getname probe to get syscall args filenames ignored_by_lkp: Use vfs_getname probe to get syscall args filenames ignored_by_lkp: Check open filename arg using perf trace + vfs_getname ignored_by_lkp: builtin clang support 2019-12-05 16:40:21 sudo /usr/src/perf_selftests-x86_64-rhel-7.6-9dc510db74b1b873853747098e1cd66b71e63210/tools/perf/perf test 1 1: vmlinux symtab matches kallsyms : Ok 2019-12-05 16:40:21 sudo /usr/src/perf_selftests-x86_64-rhel-7.6-9dc510db74b1b873853747098e1cd66b71e63210/tools/perf/perf test 2 2: Detect openat syscall event : Ok 2019-12-05 16:40:21 sudo /usr/src/perf_selftests-x86_64-rhel-7.6-9dc510db74b1b873853747098e1cd66b71e63210/tools/perf/perf test 3 3: Detect openat syscall event on all cpus : Ok 2019-12-05 16:40:21 sudo /usr/src/perf_selftests-x86_64-rhel-7.6-9dc510db74b1b873853747098e1cd66b71e63210/tools/perf/perf test 4 4: Read samples using the mmap interface : FAILED! 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, 5 months

1
0
0 / 0

2fdaa9d430 ("x86: mm: convert dump_pagetables to use .."): [ 15.826062] INFO: trying to register non-static key.

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/next/linux-next.git master commit 2fdaa9d4301cd46ef0e82989afe7051c679e6d05 Author: Steven Price <steven.price(a)arm.com> AuthorDate: Tue Nov 19 11:47:28 2019 +1100 Commit: Stephen Rothwell <sfr(a)canb.auug.org.au> CommitDate: Mon Dec 2 13:15:44 2019 +1100 x86: mm: convert dump_pagetables to use walk_page_range Make use of the new functionality in walk_page_range to remove the arch page walking code and use the generic code to walk the page tables. The effective permissions are passed down the chain using new fields in struct pg_state. The KASAN optimisation is implemented by including test_p?d callbacks which can decide to skip an entire tree of entries Link: http://lkml.kernel.org/r/20191028135910.33253-21-steven.price@arm.com Signed-off-by: Steven Price <steven.price(a)arm.com> Cc: Andrey Ryabinin <aryabinin(a)virtuozzo.com> Cc: Alexander Potapenko <glider(a)google.com> Cc: Dmitry Vyukov <dvyukov(a)google.com> Cc: Ingo Molnar <mingo(a)elte.hu> Cc: "H. Peter Anvin" <hpa(a)zytor.com> Cc: Thomas Gleixner <tglx(a)linutronix.de> Cc: Albert Ou <aou(a)eecs.berkeley.edu> Cc: Alexandre Ghiti <alex(a)ghiti.fr> Cc: Andy Lutomirski <luto(a)kernel.org> Cc: Ard Biesheuvel <ard.biesheuvel(a)linaro.org> Cc: Arnd Bergmann <arnd(a)arndb.de> Cc: Benjamin Herrenschmidt <benh(a)kernel.crashing.org> Cc: Borislav Petkov <bp(a)alien8.de> Cc: Catalin Marinas <catalin.marinas(a)arm.com> Cc: Christian Borntraeger <borntraeger(a)de.ibm.com> Cc: Dave Hansen <dave.hansen(a)linux.intel.com> Cc: Dave Jiang <dave.jiang(a)intel.com> Cc: David S. Miller <davem(a)davemloft.net> Cc: Heiko Carstens <heiko.carstens(a)de.ibm.com> Cc: James Hogan <jhogan(a)kernel.org> Cc: James Morse <james.morse(a)arm.com> Cc: "Liang, Kan" <kan.liang(a)linux.intel.com> Cc: Mark Rutland <mark.rutland(a)arm.com> Cc: Matthew Wilcox <mawilcox(a)microsoft.com> Cc: Michael Ellerman <mpe(a)ellerman.id.au> Cc: Naoya Horiguchi <n-horiguchi(a)ah.jp.nec.com> Cc: Palmer Dabbelt <palmer(a)sifive.com> Cc: Paul Burton <paul.burton(a)mips.com> Cc: Paul Mackerras <paulus(a)samba.org> Cc: Paul Walmsley <paul.walmsley(a)sifive.com> Cc: Peter Zijlstra <peterz(a)infradead.org> Cc: Ralf Baechle <ralf(a)linux-mips.org> Cc: Russell King <linux(a)armlinux.org.uk> Cc: Shiraz Hashim <shashim(a)codeaurora.org> Cc: Vasily Gorbik <gor(a)linux.ibm.com> Cc: Vineet Gupta <vgupta(a)synopsys.com> Cc: Will Deacon <will(a)kernel.org> Cc: Zong Li <zong.li(a)sifive.com> Signed-off-by: Andrew Morton <akpm(a)linux-foundation.org> Signed-off-by: Stephen Rothwell <sfr(a)canb.auug.org.au> be0d18d4f9 mm/ptdump: fix a -Wold-style-declaration warning 2fdaa9d430 x86: mm: convert dump_pagetables to use walk_page_range +----------------------------------------+------------+------------+ | | be0d18d4f9 | 2fdaa9d430 | +----------------------------------------+------------+------------+ | boot_successes | 33 | 0 | | boot_failures | 0 | 11 | | INFO:trying_to_register_non-static_key | 0 | 11 | +----------------------------------------+------------+------------+ If you fix the issue, kindly add following tag Reported-by: kernel test robot <lkp(a)intel.com> [ 15.773658] Write protecting the kernel read-only data: 59392k [ 15.779777] Freeing unused kernel image (text/rodata gap) memory: 2040K [ 15.782847] Freeing unused kernel image (rodata/data gap) memory: 1428K [ 15.824309] x86/mm: Checked W+X mappings: passed, no W+X pages found. [ 15.825244] x86/mm: Checking user space page tables [ 15.826062] INFO: trying to register non-static key. [ 15.826762] the code is fine but needs lockdep annotation. [ 15.827496] turning off the locking correctness validator. [ 15.828254] CPU: 0 PID: 1 Comm: swapper Tainted: G T 5.4.0-14407-g2fdaa9d4301cd #2 [ 15.829482] Call Trace: [ 15.830430] dump_stack+0x23/0x2c [ 15.830892] register_lock_class+0xcca/0xce0 [ 15.831465] ? arch_stack_walk+0x9a/0x130 [ 15.832004] ? is_dynamic_key+0x190/0x190 [ 15.832549] ? ret_from_fork+0x24/0x30 [ 15.833098] ? stack_trace_save+0xab/0x100 [ 15.833648] __lock_acquire+0xe0/0x2760 [ 15.834193] ? __list_add_valid+0x3a/0x130 [ 15.834763] ? save_trace+0x473/0x5a0 [ 15.835260] ? mark_lock+0x760/0x760 [ 15.835750] lock_acquire+0x15a/0x290 [ 15.836244] ? __walk_page_range+0x1264/0x1570 [ 15.836860] _raw_spin_lock+0x4f/0xd0 [ 15.837439] ? __walk_page_range+0x1264/0x1570 [ 15.838081] __walk_page_range+0x1264/0x1570 [ 15.838704] walk_page_range_novma+0x100/0x1b0 [ 15.839295] ? walk_page_range+0x2a0/0x2a0 [ 15.839846] ? __read_once_size_nocheck+0x30/0x30 [ 15.840607] ? ret_from_fork+0x24/0x30 [ 15.841155] ? __orc_find+0x8a/0xe0 [ 15.841625] ptdump_walk_pgd+0x74/0xe0 [ 15.842157] ptdump_walk_pgd_level_core+0x164/0x240 [ 15.842850] ? hugetlb_get_unmapped_area+0x840/0x840 [ 15.843512] ? __orc_find+0x8a/0xe0 [ 15.843984] ? put_dec_trunc8+0x49/0x180 [ 15.844512] ? ptdump_walk_pgd_level_core+0x240/0x240 [ 15.845230] ? __kasan_check_write+0x2e/0x40 [ 15.845800] ptdump_walk_pgd_level_user_core+0xfa/0x180 [ 15.849123] ? note_page+0x1020/0x1020 [ 15.849632] ? ptdump_walk_pgd+0x3b/0xe0 [ 15.850192] ? pti_clone_pgtable+0x2e7/0x4a0 [ 15.851025] ? ptdump_walk_user_pgd_level_checkwx+0x4a/0x90 [ 15.851838] ? rest_init+0x200/0x200 [ 15.852349] ptdump_walk_user_pgd_level_checkwx+0x85/0x90 [ 15.853124] pti_finalize+0x99/0xe6 [ 15.853640] kernel_init+0x68/0x1d0 [ 15.854145] ? rest_init+0x200/0x200 [ 15.854692] ret_from_fork+0x24/0x30 [ 15.855514] x86/mm: Checked W+X mappings: passed, no W+X pages found. [ 15.856465] Run /init as init process [ 15.873689] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input4 [ 15.875091] evbug: Connected device: input4 (ImExPS/2 Generic Explorer Mouse at isa0060/serio1/input0) [ 15.880927] random: init: uninitialized urandom read (12 bytes read) # HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD git bisect start c5db92909beddadddb705b92d3388ea50b01e1a2 v5.4 -- git bisect good f988172904a8e7574affe85eec99eb91ac7e1a8f # 13:00 G 10 0 0 0 Merge remote-tracking branch 'ide/master' git bisect good dc2e543da554ae5703f5eb71483263821b4d20c5 # 13:37 G 10 0 0 0 Merge remote-tracking branch 'swiotlb/linux-next' git bisect good 34e7643d8e9b7340458e5a5e3f0f7cee03e74e29 # 13:58 G 11 0 1 1 Merge remote-tracking branch 'drivers-x86/for-next' git bisect good 7384a7a3ebb07042fa916e7713e26adb1070abd3 # 14:35 G 10 0 0 0 Merge remote-tracking branch 'ktest/for-next' git bisect good 176b423b0aa4366e64649ff2722e0a70d5b0044d # 15:14 G 10 0 0 0 Merge remote-tracking branch 'fsi/next' git bisect good bd7ecfa19f1476dab116865714617818702e8f3b # 15:36 G 10 0 0 0 Merge remote-tracking branch 'devfreq/devfreq-next' git bisect good 42f74766c89ff3af608f390d93fe9a3f5ccf729f # 16:27 G 10 0 0 0 Merge branch 'akpm-current/current' git bisect bad 335567f88239b6a8bc2d5abbd03a03cab7edfb90 # 16:46 B 0 4 20 0 Merge branch 'akpm/master' git bisect good 4fe538ac5d48f1d5f446c441897fd3a724aee02c # 17:43 G 10 0 0 0 gpio: pca953x: tighten up indentation git bisect good a4bcd5e480e6c7083229f4ab55d53a388cb5dc93 # 18:19 G 10 0 0 0 mm-add-generic-ptdump-v15 git bisect bad e9dc62ea765e9ab2b39429fb6fb89a53ef0d5c9f # 19:06 B 0 8 24 0 microblaze: use pgtable-nopmd instead of 4level-fixup git bisect bad 541728e05f903a6ef29da05efbf51eb460f56296 # 19:55 B 0 8 24 0 mm: ptdump: reduce level numbers by 1 in note_page() git bisect bad 2fdaa9d4301cd46ef0e82989afe7051c679e6d05 # 20:10 B 0 1 17 0 x86: mm: convert dump_pagetables to use walk_page_range git bisect good be0d18d4f90559ce4dc75f3893b343a24f4e5865 # 20:38 G 10 0 0 0 mm/ptdump: fix a -Wold-style-declaration warning # first bad commit: [2fdaa9d4301cd46ef0e82989afe7051c679e6d05] x86: mm: convert dump_pagetables to use walk_page_range git bisect good be0d18d4f90559ce4dc75f3893b343a24f4e5865 # 20:44 G 32 0 0 0 mm/ptdump: fix a -Wold-style-declaration warning # extra tests with debug options git bisect good 2fdaa9d4301cd46ef0e82989afe7051c679e6d05 # 21:08 G 10 0 10 10 x86: mm: convert dump_pagetables to use walk_page_range --- 0-DAY kernel test infrastructure Open Source Technology Center https://lists.01.org/hyperkitty/list/lkp@lists.01.org Intel Corporation

2 years, 5 months

1
0
0 / 0

[f*xattr] ab91fe640f: ltp.open13.fail

by kernel test robot

FYI, we noticed the following commit (built with gcc-7): commit: ab91fe640fca6de988a888b2de89d58014d120d4 ("f*xattr: allow O_PATH descriptors") https://git.kernel.org/cgit/linux/kernel/git/mszeredi/vfs.git for-viro in testcase: ltp with following parameters: disk: 1HDD fs: ext4 test: syscalls_part4 test-description: The LTP testsuite contains a collection of tools for testing the Linux kernel and related features. test-url: http://linux-test-project.github.io/ on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 8G caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace): If you fix the issue, kindly add following tag Reported-by: kernel test robot <rong.a.chen(a)intel.com> 2019-12-04 23:59:11 ln -sf /usr/bin/genisoimage /usr/bin/mkisofs 2019-12-04 23:59:11 ./runltp -f syscalls_part4 -d /fs/vda/tmpdir INFO: creating /lkp/benchmarks/ltp/output directory INFO: creating /lkp/benchmarks/ltp/results directory Checking for required user/group ids 'nobody' user id and group found. 'bin' user id and group found. 'daemon' user id and group found. Users group found. Sys group found. Required users/groups exist. If some fields are empty or look unusual you may have an old version. Compare to the current minimal requirements in Documentation/Changes. /etc/os-release PRETTY_NAME="Debian GNU/Linux 9 (stretch)" NAME="Debian GNU/Linux" VERSION_ID="9" VERSION="9 (stretch)" ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" uname: Linux vm-snb-e46599e64487 5.4.0-00007-gab91fe640fca6 #1 SMP Wed Dec 4 22:30:33 CST 2019 x86_64 GNU/Linux /proc/cmdline ip=::::vm-snb-e46599e64487::dhcp root=/dev/ram0 user=lkp job=/lkp/jobs/scheduled/vm-snb-e46599e64487/ltp-1HDD-ext4-syscalls_part4-debian-x86_64-2019-09-23.cgz-ab91fe640fca-20191204-39209-1a1j3q-2.yaml ARCH=x86_64 kconfig=x86_64-rhel-7.6 branch=linux-devel/devel-hourly-2019120318 commit=ab91fe640fca6de988a888b2de89d58014d120d4 BOOT_IMAGE=/pkg/linux/x86_64-rhel-7.6/gcc-7/ab91fe640fca6de988a888b2de89d58014d120d4/vmlinuz-5.4.0-00007-gab91fe640fca6 max_uptime=3600 RESULT_ROOT=/result/ltp/1HDD-ext4-syscalls_part4/vm-snb/debian-x86_64-2019-09-23.cgz/x86_64-rhel-7.6/gcc-7/ab91fe640fca6de988a888b2de89d58014d120d4/3 LKP_SERVER=inn 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 rcuperf.shutdown=0 watchdog_thresh=60 Gnu C gcc (Debian 6.3.0-18+deb9u1) 6.3.0 20170516 Clang Gnu make 4.1 util-linux 2.29.2 mount linux 2.29.2 (libmount 2.29.2: selinux, btrfs, assert, debug) modutils 23 e2fsprogs 1.43.4 Linux C Library > libc.2.24 Dynamic linker (ldd) 2.24 Procps 3.3.12 Net-tools 2.10-alpha iproute2 iproute2-ss161212 iputils iputils-s20161105 Kbd 116: Sh-utils 8.26 Modules Loaded ext4 mbcache jbd2 dm_mod intel_rapl_msr intel_rapl_common crct10dif_pclmul sr_mod cdrom crc32_pclmul sg ata_generic pata_acpi crc32c_intel ghash_clmulni_intel ppdev bochs_drm drm_vram_helper ttm snd_pcm aesni_intel crypto_simd drm_kms_helper cryptd glue_helper snd_timer snd ata_piix joydev syscopyarea sysfillrect soundcore sysimgblt fb_sys_fops pcspkr serio_raw drm libata i2c_piix4 floppy parport_pc parport ip_tables free reports: total used free shared buff/cache available Mem: 8149364 284180 5697720 11732 2167464 5587004 Swap: 0 0 0 cpuinfo: Architecture: x86_64 CPU op-mode(s): 32-bit, 64-bit Byte Order: Little Endian CPU(s): 2 On-line CPU(s) list: 0,1 Thread(s) per core: 1 Core(s) per socket: 1 Socket(s): 2 NUMA node(s): 1 Vendor ID: GenuineIntel CPU family: 6 Model: 42 Model name: Intel Xeon E312xx (Sandy Bridge) Stepping: 1 CPU MHz: 2194.916 BogoMIPS: 4389.83 Hypervisor vendor: KVM Virtualization type: full L1d cache: 32K L1i cache: 32K L2 cache: 4096K L3 cache: 16384K NUMA node0 CPU(s): 0,1 Flags: fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush mmx fxsr sse sse2 syscall nx rdtscp lm constant_tsc rep_good nopl xtopology cpuid tsc_known_freq pni pclmulqdq ssse3 cx16 sse4_1 sse4_2 x2apic popcnt tsc_deadline_timer aes xsave avx hypervisor lahf_lm cpuid_fault pti xsaveopt arat AppArmor disabled SELinux mode: unknown no big block device was specified on commandline. Tests which require a big block device are disabled. You can specify it with option -z COMMAND: /lkp/benchmarks/ltp/bin/ltp-pan -e -S -a 2288 -n 2288 -p -f /fs/vda/tmpdir/ltp-ceD91s8sBi/alltests -l /lkp/benchmarks/ltp/results/LTP_RUN_ON-2019_12_04-23h_59m_11s.log -C /lkp/benchmarks/ltp/output/LTP_RUN_ON-2019_12_04-23h_59m_11s.failed -T /lkp/benchmarks/ltp/output/LTP_RUN_ON-2019_12_04-23h_59m_11s.tconf LOG File: /lkp/benchmarks/ltp/results/LTP_RUN_ON-2019_12_04-23h_59m_11s.log FAILED COMMAND File: /lkp/benchmarks/ltp/output/LTP_RUN_ON-2019_12_04-23h_59m_11s.failed TCONF COMMAND File: /lkp/benchmarks/ltp/output/LTP_RUN_ON-2019_12_04-23h_59m_11s.tconf Running tests....... ... <<<test_start>>> tag=open13 stime=1575475168 cmdline="open13" contacts="" analysis=exit <<<test_output>>> open13 1 TPASS : read(2) failed with EBADF open13 2 TPASS : write(2) failed with EBADF open13 3 TPASS : fchmod(2) failed with EBADF open13 4 TPASS : fchown(2) failed with EBADF open13 5 TFAIL : open13.c:155: fgetxattr(2) failed unexpectedly, expected EBADF: TEST_ERRNO=EOPNOTSUPP(95): Operation not supported <<<execution_status>>> initiation_status="ok" duration=0 termination_type=exited termination_id=1 corefile=no cutime=0 cstime=1 <<<test_end>>> ... LTP Version: 20190930-35-gb8aebc835 ############################################################### Done executing testcases. LTP Version: 20190930-35-gb8aebc835 ############################################################### To reproduce: # build kernel cd linux cp config-5.4.0-00007-gab91fe640fca6 .config make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 olddefconfig prepare modules_prepare bzImage modules make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 INSTALL_MOD_PATH=<mod-install-dir> modules_install cd <mod-install-dir> find lib/ | cpio -o -H newc --quiet | gzip > modules.cgz git clone https://github.com/intel/lkp-tests.git cd lkp-tests bin/lkp qemu -k <bzImage> -m modules.cgz job-script # job-script is attached in this email Thanks, Rong Chen

2 years, 5 months

2
1
0 / 0

[sched/fair] eadb093379: WARNING:at_kernel/sched/sched.h:#start_dl_timer

by kernel test robot

FYI, we noticed the following commit (built with gcc-7): commit: eadb09337933bbce1b82d424b4c119fc5fc41214 ("sched/fair: Add trivial fair server") https://git.kernel.org/cgit/linux/kernel/git/peterz/queue.git sched/wip-deadline in testcase: boot on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 8G caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace): +---------------------------------------------------+------------+------------+ | | 0b0cd6a414 | eadb093379 | +---------------------------------------------------+------------+------------+ | boot_successes | 827 | 723 | | boot_failures | 0 | 8 | | WARNING:at_kernel/sched/sched.h:#start_dl_timer | 0 | 6 | | RIP:start_dl_timer | 0 | 5 | | BUG:soft_lockup-CPU##stuck_for#s![in:imjournal:#] | 0 | 1 | | RIP:smp_call_function_single | 0 | 1 | | Kernel_panic-not_syncing:softlockup:hung_tasks | 0 | 1 | | BUG:kernel_hang_in_early-boot_stage | 0 | 1 | | BUG:kernel_hang_in_test_stage | 0 | 1 | +---------------------------------------------------+------------+------------+ If you fix the issue, kindly add following tag Reported-by: kernel test robot <rong.a.chen(a)intel.com> [ 18.160136] WARNING: CPU: 0 PID: 630 at kernel/sched/sched.h:1122 start_dl_timer+0xd9/0xf0 [ 18.163949] Modules linked in: ip_tables [ 18.165067] CPU: 0 PID: 630 Comm: systemd-detect- Not tainted 5.4.0-rc8-00047-geadb09337933b #1 [ 18.167338] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014 [ 18.169529] RIP: 0010:start_dl_timer+0xd9/0xf0 [ 18.170738] Code: ff ff 0f 88 25 dc 9f 00 eb d3 80 3d 7b cb 72 01 00 75 97 48 c7 c7 78 ab 2e 82 48 89 04 24 c6 05 67 cb 72 01 01 e8 a7 59 fb ff <0f> 0b 48 8b 04 24 e9 75 ff ff ff 66 66 2e 0f 1f 84 00 00 00 00 00 [ 18.175291] RSP: 0018:ffffc9000063fd38 EFLAGS: 00010082 [ 18.176680] RAX: 0000000000000000 RBX: ffff88823fd2b0e8 RCX: 0000000000000000 [ 18.178443] RDX: 0000000000000006 RSI: ffffffff82f9c8e6 RDI: 0000000000000046 [ 18.180212] RBP: 000000043b3dff65 R08: ffffffff82f9c8c0 R09: 0000000000000026 [ 18.182018] R10: ffff8881fab36000 R11: 0000000000000276 R12: ffff88823fd2a780 [ 18.184468] R13: ffff88823fc2a780 R14: 000000000000000e R15: ffff8881bd6ea700 [ 18.187587] FS: 0000000000000000(0000) GS:ffff88823fc00000(0000) knlGS:0000000000000000 [ 18.191323] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 18.193869] CR2: 00007f63aa09e000 CR3: 000000000260a000 CR4: 00000000000406f0 [ 18.197026] Call Trace: [ 18.198481] update_curr_dl_se+0xbf/0x1c0 [ 18.200492] update_curr+0x106/0x190 [ 18.202302] dequeue_entity+0x21/0x440 [ 18.204222] dequeue_task_fair+0x6c/0x830 [ 18.206272] sched_move_task+0x10b/0x190 [ 18.208279] do_exit+0x330/0xc30 [ 18.210003] ? syscall_trace_enter+0x175/0x2b0 [ 18.212174] do_group_exit+0x3a/0xa0 [ 18.214009] __x64_sys_exit_group+0x14/0x20 [ 18.216033] do_syscall_64+0x5b/0x1d0 [ 18.217839] entry_SYSCALL_64_after_hwframe+0x44/0xa9 [ 18.220165] RIP: 0033:0x7f63a97451d9 [ 18.221987] Code: Bad RIP value. [ 18.223571] RSP: 002b:00007ffc43509638 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 18.226782] RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007f63a97451d9 [ 18.229352] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000 [ 18.231816] RBP: 00007f63a9a42838 R08: 000000000000003c R09: 00000000000000e7 [ 18.234291] R10: ffffffffffffff50 R11: 0000000000000246 R12: 00007f63a9a42838 [ 18.236070] R13: 00007f63a9a47e80 R14: 0000000000000000 R15: 0000000000000000 [ 18.237892] ---[ end trace c9582e75f180c8c5 ]--- To reproduce: # build kernel cd linux cp config-5.4.0-rc8-00047-geadb09337933b .config make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 olddefconfig prepare modules_prepare bzImage 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, 5 months

1
0
0 / 0

LKP port to RISCV

by Deepa B

Hello, Do you know if there are plans to port LKP to RISCV? Or if the activity has already started. We have linux (yocto) on Riscv-HiFive Unleashed-64 bit. Wanted to port/test lkp on it. Many Thanks, Deepa

2 years, 5 months

2
1
0 / 0

2022

2021

2020

2019

2018

2017

2016

2015

2014

2013

LKP December 2019