lists.openwall.net   lists  /  announce  owl-users  owl-dev  john-users  john-dev  passwdqc-users  yescrypt  popa3d-users  /  oss-security  kernel-hardening  musl  sabotage  tlsify  passwords  /  crypt-dev  xvendor  /  Bugtraq  Full-Disclosure  linux-kernel  linux-netdev  linux-ext4  linux-hardening  linux-cve-announce  PHC 
Open Source and information security mailing list archives
 
Hash Suite: Windows password security audit tool. GUI, reports in PDF.
[<prev] [next>] [day] [month] [year] [list]
Message-ID: <20160328060824.GA3882@yexl-desktop>
Date:	Mon, 28 Mar 2016 14:08:24 +0800
From:	kernel test robot <xiaolong.ye@...el.com>
To:	Peter Zijlstra <peterz@...radead.org>
Cc:	LKML <linux-kernel@...r.kernel.org>, lkp@...org
Subject: [lkp] [sched/fair] 3a47d5124a: pigz.throughput -2.8% regression

FYI, we noticed that pigz.throughput -2.8% regression with your commit, 

https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git master
commit 3a47d5124a957358274e9ca7b115b2f3a914f56d ("sched/fair: Fix fairness issue on migration")


=========================================================================================
blocksize/compiler/cpufreq_governor/kconfig/nr_threads/rootfs/tbox_group/testcase:
  128K/gcc-4.9/performance/x86_64-rhel/100%/debian-x86_64-2015-02-07.cgz/lkp-snb01/pigz

commit: 
  2f5177f0fd7e531b26d54633be62d1d4cb94621c
  3a47d5124a957358274e9ca7b115b2f3a914f56d

2f5177f0fd7e531b 3a47d5124a957358274e9ca7b1 
---------------- -------------------------- 
       fail:runs  %reproduction    fail:runs
         %stddev     %change         %stddev
             \          |                \  
 3.565e+08 ±  0%      -2.8%  3.466e+08 ±  0%  pigz.throughput
   3148624 ±  1%     -61.5%    1213138 ±  1%  pigz.time.involuntary_context_switches
      3172 ±  0%      -4.9%       3017 ±  0%  pigz.time.percent_of_cpu_this_job_got
    108.01 ±  0%     -12.7%      94.28 ±  0%  pigz.time.system_time
      9416 ±  0%      -4.8%       8963 ±  0%  pigz.time.user_time
   4435433 ±  0%     -25.4%    3308219 ±  0%  pigz.time.voluntary_context_switches
     22606 ±  6%      -8.5%      20692 ±  0%  meminfo.Shmem
      6211 ± 51%     +65.1%      10253 ± 13%  numa-meminfo.node1.AnonHugePages
     82109 ±  4%    +111.8%     173874 ±  5%  softirqs.SCHED
  17775779 ±  2%      -8.2%   16323423 ±  5%  numa-numastat.node0.local_node
  17777847 ±  2%      -8.2%   16324614 ±  5%  numa-numastat.node0.numa_hit
  10837718 ± 18%     -23.4%    8299086 ±  3%  numa-vmstat.node0.numa_hit
  10771465 ± 18%     -23.6%    8232199 ±  3%  numa-vmstat.node0.numa_local
      6147 ±  4%      +9.7%       6742 ±  4%  slabinfo.anon_vma.active_objs
      6147 ±  4%      +9.7%       6742 ±  4%  slabinfo.anon_vma.num_objs
      5652 ±  6%      -8.5%       5171 ±  0%  proc-vmstat.nr_shmem
     33999 ±  6%     +40.2%      47675 ± 14%  proc-vmstat.numa_pages_migrated
     33999 ±  6%     +40.2%      47675 ± 14%  proc-vmstat.pgmigrate_success
   3148624 ±  1%     -61.5%    1213138 ±  1%  time.involuntary_context_switches
    108.01 ±  0%     -12.7%      94.28 ±  0%  time.system_time
   4435433 ±  0%     -25.4%    3308219 ±  0%  time.voluntary_context_switches
      0.64 ±  5%    +707.0%       5.17 ±  1%  turbostat.CPU%c1
      0.01 ±  0%    +675.0%       0.08 ±  5%  turbostat.CPU%c3
     16.03 ±101%     -99.6%       0.06 ±100%  turbostat.Pkg%pc2
  14964297 ± 21%    +256.4%   53332691 ±  3%  cpuidle.C1-SNB.time
     58475 ±  5%    +229.5%     192657 ±  1%  cpuidle.C1-SNB.usage
  18841654 ± 15%    +709.6%  1.525e+08 ±  2%  cpuidle.C1E-SNB.time
     66706 ± 10%    +629.5%     486650 ±  1%  cpuidle.C1E-SNB.usage
   4314242 ± 12%    +575.3%   29134227 ±  1%  cpuidle.C3-SNB.time
     14262 ± 10%    +543.3%      91745 ±  2%  cpuidle.C3-SNB.usage
    133764 ± 27%    +434.9%     715504 ±  1%  cpuidle.C7-SNB.usage
 3.929e+08 ±  1%    -100.0%       0.00 ± -1%  latency_stats.avg.nfs_wait_on_request.nfs_updatepage.nfs_write_end.generic_perform_write.__generic_file_write_iter.generic_file_write_iter.nfs_file_write.__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath
   9059435 ±103%    -100.0%     160.00 ± 44%  latency_stats.avg.rpc_wait_bit_killable.__rpc_execute.rpc_execute.rpc_run_task.nfs4_call_sync_sequence.[nfsv4]._nfs4_proc_access.[nfsv4].nfs4_proc_access.[nfsv4].nfs_do_access.nfs_permission.__inode_permission.inode_permission.link_path_walk
 4.578e+08 ±  5%    -100.0%       0.00 ± -1%  latency_stats.max.nfs_wait_on_request.nfs_updatepage.nfs_write_end.generic_perform_write.__generic_file_write_iter.generic_file_write_iter.nfs_file_write.__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath
  51327106 ±102%    -100.0%     257.25 ± 51%  latency_stats.max.rpc_wait_bit_killable.__rpc_execute.rpc_execute.rpc_run_task.nfs4_call_sync_sequence.[nfsv4]._nfs4_proc_access.[nfsv4].nfs4_proc_access.[nfsv4].nfs_do_access.nfs_permission.__inode_permission.inode_permission.link_path_walk
 7.858e+08 ±  1%    -100.0%       0.00 ± -1%  latency_stats.sum.nfs_wait_on_request.nfs_updatepage.nfs_write_end.generic_perform_write.__generic_file_write_iter.generic_file_write_iter.nfs_file_write.__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath
     20816 ± 25%    +361.7%      96106 ± 13%  latency_stats.sum.pipe_read.__vfs_read.vfs_read.SyS_read.entry_SYSCALL_64_fastpath
 1.014e+08 ±107%    -100.0%       1445 ± 44%  latency_stats.sum.rpc_wait_bit_killable.__rpc_execute.rpc_execute.rpc_run_task.nfs4_call_sync_sequence.[nfsv4]._nfs4_proc_access.[nfsv4].nfs4_proc_access.[nfsv4].nfs_do_access.nfs_permission.__inode_permission.inode_permission.link_path_walk
    172662 ± 14%     -18.4%     140966 ±  0%  sched_debug.cfs_rq:/.exec_clock.avg
    172933 ± 14%     -18.0%     141779 ±  0%  sched_debug.cfs_rq:/.exec_clock.max
    171995 ± 14%     -18.6%     139924 ±  0%  sched_debug.cfs_rq:/.exec_clock.min
    179.82 ± 33%    +180.3%     504.10 ± 34%  sched_debug.cfs_rq:/.exec_clock.stddev
     18.12 ± 27%     -67.6%       5.88 ± 35%  sched_debug.cfs_rq:/.load.min
   5948149 ± 14%     -23.8%    4531737 ±  0%  sched_debug.cfs_rq:/.min_vruntime.avg
   5968318 ± 14%     -23.6%    4561399 ±  0%  sched_debug.cfs_rq:/.min_vruntime.max
   5921026 ± 14%     -24.1%    4496045 ±  0%  sched_debug.cfs_rq:/.min_vruntime.min
      0.67 ± 26%     -69.0%       0.21 ± 34%  sched_debug.cfs_rq:/.nr_running.min
      0.10 ± 40%    +185.3%       0.28 ±  9%  sched_debug.cfs_rq:/.nr_running.stddev
      0.38 ± 15%     -39.2%       0.23 ± 17%  sched_debug.cfs_rq:/.nr_spread_over.avg
     11.22 ± 12%     -46.1%       6.04 ± 28%  sched_debug.cfs_rq:/.nr_spread_over.max
      1.97 ± 12%     -45.5%       1.07 ± 26%  sched_debug.cfs_rq:/.nr_spread_over.stddev
     18.97 ± 27%     -69.7%       5.75 ± 36%  sched_debug.cfs_rq:/.runnable_load_avg.min
      4.44 ± 90%    +157.1%      11.41 ± 37%  sched_debug.cfs_rq:/.runnable_load_avg.stddev
     13030 ±176%    -199.0%     -12895 ±-101%  sched_debug.cfs_rq:/.spread0.avg
    -13998 ±-100%    +247.4%     -48630 ±-36%  sched_debug.cfs_rq:/.spread0.min
      9880 ± 23%     +59.9%      15795 ± 23%  sched_debug.cfs_rq:/.spread0.stddev
     34.89 ±  8%    +120.4%      76.89 ± 13%  sched_debug.cfs_rq:/.util_avg.stddev
    196657 ± 16%     +36.9%     269230 ±  6%  sched_debug.cpu.avg_idle.stddev
     17.42 ± 64%     -79.4%       3.58 ± 11%  sched_debug.cpu.clock.stddev
     17.42 ± 64%     -79.4%       3.58 ± 11%  sched_debug.cpu.clock_task.stddev
     18.10 ± 30%     -51.7%       8.75 ± 27%  sched_debug.cpu.cpu_load[0].min
     18.74 ± 27%     -44.9%      10.33 ± 18%  sched_debug.cpu.cpu_load[1].min
     19.22 ± 24%     -36.7%      12.17 ±  6%  sched_debug.cpu.cpu_load[2].min
      1912 ± 23%     -63.2%     704.04 ± 48%  sched_debug.cpu.curr->pid.min
     18.12 ± 27%     -67.6%       5.88 ± 35%  sched_debug.cpu.load.min
    176320 ± 14%     -15.5%     148922 ±  0%  sched_debug.cpu.nr_load_updates.avg
    178699 ± 14%     -15.3%     151407 ±  0%  sched_debug.cpu.nr_load_updates.max
    174865 ± 14%     -15.8%     147278 ±  0%  sched_debug.cpu.nr_load_updates.min
      0.67 ± 26%     -69.0%       0.21 ± 34%  sched_debug.cpu.nr_running.min
      0.27 ±  8%     +41.3%       0.39 ±  5%  sched_debug.cpu.nr_running.stddev
    152747 ± 14%     -34.7%      99735 ±  0%  sched_debug.cpu.nr_switches.avg
    183979 ± 12%     -31.3%     126457 ±  5%  sched_debug.cpu.nr_switches.max
    135220 ± 15%     -41.5%      79108 ±  3%  sched_debug.cpu.nr_switches.min
    154367 ± 14%     -34.5%     101165 ±  0%  sched_debug.cpu.sched_count.avg
    225427 ± 12%     -20.1%     180086 ±  4%  sched_debug.cpu.sched_count.max
    134418 ± 14%     -41.5%      78674 ±  3%  sched_debug.cpu.sched_count.min
      6588 ± 22%    +264.1%      23990 ±  2%  sched_debug.cpu.sched_goidle.avg
     20233 ± 23%     +73.8%      35174 ±  7%  sched_debug.cpu.sched_goidle.max
      3150 ± 22%    +492.7%      18675 ±  2%  sched_debug.cpu.sched_goidle.min
     86325 ± 15%     -36.0%      55212 ±  0%  sched_debug.cpu.ttwu_count.avg
    103976 ± 13%     -33.4%      69252 ±  4%  sched_debug.cpu.ttwu_count.max
     74872 ± 14%     -39.3%      45412 ±  3%  sched_debug.cpu.ttwu_count.min
     61351 ± 14%     -60.3%      24369 ±  1%  sched_debug.cpu.ttwu_local.avg
     68724 ± 13%     -56.3%      30060 ±  3%  sched_debug.cpu.ttwu_local.max
     54792 ± 14%     -65.3%      19018 ±  6%  sched_debug.cpu.ttwu_local.min
      9.86 ±  9%     -37.9%       6.13 ±  5%  perf-profile.cycles.__account_scheduler_latency.enqueue_entity.enqueue_task_fair.activate_task.ttwu_do_activate
      5.67 ±  3%     -18.2%       4.64 ±  6%  perf-profile.cycles.__alloc_pages_nodemask.alloc_pages_current.pipe_write.__vfs_write.vfs_write
      0.60 ± 98%    +306.6%       2.46 ± 19%  perf-profile.cycles.__do_softirq.irq_exit.smp_apic_timer_interrupt.apic_timer_interrupt
      2.69 ± 52%    +282.2%      10.26 ±  5%  perf-profile.cycles.__hrtimer_run_queues.hrtimer_interrupt.local_apic_timer_interrupt.smp_apic_timer_interrupt.apic_timer_interrupt
      4.49 ±  9%     -36.3%       2.86 ±  9%  perf-profile.cycles.__kernel_text_address.print_context_stack.dump_trace.save_stack_trace_tsk.__account_scheduler_latency
      0.74 ± 23%     -40.4%       0.44 ± 20%  perf-profile.cycles.__list_del_entry.list_del.__rmqueue.get_page_from_freelist.__alloc_pages_nodemask
      0.88 ± 31%    +395.7%       4.34 ±  6%  perf-profile.cycles.__mutex_lock_slowpath.mutex_lock.pipe_read.__vfs_read.vfs_read
      0.41 ± 38%    +283.7%       1.59 ± 17%  perf-profile.cycles.__mutex_lock_slowpath.mutex_lock.pipe_wait.pipe_write.__vfs_write
      3.42 ± 11%     -32.5%       2.31 ± 16%  perf-profile.cycles.__put_page.anon_pipe_buf_release.pipe_read.__vfs_read.vfs_read
      1.01 ±  8%     -28.6%       0.72 ± 10%  perf-profile.cycles.__rmqueue.get_page_from_freelist.__alloc_pages_nodemask.alloc_pages_current.pipe_write
      1.05 ± 20%     -64.2%       0.38 ± 26%  perf-profile.cycles.__schedule.schedule.exit_to_usermode_loop.syscall_return_slowpath.entry_SYSCALL_64_fastpath
      2.86 ± 10%     -15.9%       2.40 ±  2%  perf-profile.cycles.__schedule.schedule.futex_wait_queue_me.futex_wait.do_futex
      0.92 ±  7%     -22.4%       0.72 ± 12%  perf-profile.cycles.__schedule.schedule.pipe_wait.pipe_write.__vfs_write
     26.84 ±  3%     -13.9%      23.11 ±  3%  perf-profile.cycles.__vfs_write.vfs_write.sys_write.entry_SYSCALL_64_fastpath
      3.23 ± 11%     -33.6%       2.15 ±  7%  perf-profile.cycles.__wake_up_common.__wake_up_sync_key.pipe_read.__vfs_read.vfs_read
      3.27 ± 10%     -33.1%       2.19 ±  5%  perf-profile.cycles.__wake_up_sync_key.pipe_read.__vfs_read.vfs_read.sys_read
      0.18 ± 59%    +745.1%       1.50 ±  9%  perf-profile.cycles.activate_task.ttwu_do_activate.sched_ttwu_pending.cpu_startup_entry.start_secondary
      1.65 ± 10%     -65.3%       0.57 ± 13%  perf-profile.cycles.activate_task.ttwu_do_activate.sched_ttwu_pending.scheduler_ipi.smp_reschedule_interrupt
      2.33 ± 12%     -33.2%       1.56 ± 10%  perf-profile.cycles.activate_task.ttwu_do_activate.try_to_wake_up.default_wake_function.autoremove_wake_function
      5.23 ± 15%     -60.1%       2.09 ± 11%  perf-profile.cycles.activate_task.ttwu_do_activate.try_to_wake_up.wake_up_q.futex_requeue
      3.28 ± 10%     -35.9%       2.10 ± 13%  perf-profile.cycles.activate_task.ttwu_do_activate.try_to_wake_up.wake_up_q.futex_wake
      6.42 ±  3%     -15.8%       5.41 ±  4%  perf-profile.cycles.alloc_pages_current.pipe_write.__vfs_write.vfs_write.sys_write
      2.05 ± 13%     -37.9%       1.27 ±  4%  perf-profile.cycles.anon_pipe_buf_release.__vfs_read.vfs_read.sys_read.entry_SYSCALL_64_fastpath
      3.51 ± 11%     -33.3%       2.35 ± 16%  perf-profile.cycles.anon_pipe_buf_release.pipe_read.__vfs_read.vfs_read.sys_read
      5.04 ± 49%    +239.8%      17.13 ±  9%  perf-profile.cycles.apic_timer_interrupt
      3.18 ± 12%     -34.0%       2.10 ±  7%  perf-profile.cycles.autoremove_wake_function.__wake_up_common.__wake_up_sync_key.pipe_read.__vfs_read
      1.06 ± 77%    +158.9%       2.76 ±  5%  perf-profile.cycles.call_cpuidle.cpu_startup_entry.start_secondary
      0.27 ±133%    +230.6%       0.89 ± 24%  perf-profile.cycles.clockevents_program_event.tick_program_event.hrtimer_interrupt.local_apic_timer_interrupt.smp_apic_timer_interrupt
     17.49 ±  3%     -21.5%      13.73 ±  1%  perf-profile.cycles.copy_page_from_iter.pipe_write.__vfs_write.vfs_write.sys_write
      1.45 ±  9%     -21.4%       1.14 ± 13%  perf-profile.cycles.copy_page_from_iter_iovec.copy_page_from_iter.pipe_write.__vfs_write.vfs_write
     24.42 ±  3%     -15.2%      20.70 ±  2%  perf-profile.cycles.copy_page_to_iter.pipe_read.__vfs_read.vfs_read.sys_read
     15.67 ±  3%     -22.7%      12.12 ±  1%  perf-profile.cycles.copy_user_generic_string.copy_page_from_iter.pipe_write.__vfs_write.vfs_write
     23.42 ±  3%     -15.8%      19.72 ±  2%  perf-profile.cycles.copy_user_generic_string.copy_page_to_iter.pipe_read.__vfs_read.vfs_read
      1.80 ± 54%    +329.2%       7.71 ±  5%  perf-profile.cycles.cpu_startup_entry.start_secondary
      1.06 ± 77%    +157.0%       2.74 ±  5%  perf-profile.cycles.cpuidle_enter.call_cpuidle.cpu_startup_entry.start_secondary
      1.46 ± 12%     -31.4%       1.00 ± 14%  perf-profile.cycles.deactivate_task.__schedule.schedule.futex_wait_queue_me.futex_wait
      3.17 ± 12%     -34.1%       2.09 ±  7%  perf-profile.cycles.default_wake_function.autoremove_wake_function.__wake_up_common.__wake_up_sync_key.pipe_read
     17.95 ±  7%     -42.3%      10.36 ±  2%  perf-profile.cycles.do_futex.sys_futex.entry_SYSCALL_64_fastpath
      8.78 ±  9%     -36.6%       5.57 ±  4%  perf-profile.cycles.dump_trace.save_stack_trace_tsk.__account_scheduler_latency.enqueue_entity.enqueue_task_fair
      9.98 ± 10%     -45.7%       5.42 ±  4%  perf-profile.cycles.enqueue_entity.enqueue_task_fair.activate_task.ttwu_do_activate.try_to_wake_up
      0.16 ± 60%    +843.1%       1.53 ± 10%  perf-profile.cycles.enqueue_task_fair.activate_task.ttwu_do_activate.sched_ttwu_pending.cpu_startup_entry
      1.60 ± 13%     -65.3%       0.55 ± 15%  perf-profile.cycles.enqueue_task_fair.activate_task.ttwu_do_activate.sched_ttwu_pending.scheduler_ipi
      2.29 ± 11%     -34.1%       1.51 ±  9%  perf-profile.cycles.enqueue_task_fair.activate_task.ttwu_do_activate.try_to_wake_up.default_wake_function
      8.37 ± 14%     -50.9%       4.11 ±  5%  perf-profile.cycles.enqueue_task_fair.activate_task.ttwu_do_activate.try_to_wake_up.wake_up_q
     83.20 ±  3%     -19.0%      67.36 ±  2%  perf-profile.cycles.entry_SYSCALL_64_fastpath
      1.17 ± 19%     -60.8%       0.46 ± 34%  perf-profile.cycles.exit_to_usermode_loop.syscall_return_slowpath.entry_SYSCALL_64_fastpath
      3.13 ± 12%     -33.5%       2.08 ± 17%  perf-profile.cycles.free_hot_cold_page.__put_page.anon_pipe_buf_release.pipe_read.__vfs_read
      1.68 ± 13%     -51.9%       0.81 ± 13%  perf-profile.cycles.free_pcppages_bulk.free_hot_cold_page.__put_page.anon_pipe_buf_release.pipe_read
      8.80 ±  7%     -58.6%       3.64 ±  6%  perf-profile.cycles.futex_requeue.do_futex.sys_futex.entry_SYSCALL_64_fastpath
      4.01 ± 12%     -21.3%       3.16 ±  3%  perf-profile.cycles.futex_wait.do_futex.sys_futex.entry_SYSCALL_64_fastpath
      3.38 ±  8%     -17.7%       2.78 ±  3%  perf-profile.cycles.futex_wait_queue_me.futex_wait.do_futex.sys_futex.entry_SYSCALL_64_fastpath
      4.59 ± 10%     -26.7%       3.37 ± 11%  perf-profile.cycles.futex_wake.do_futex.sys_futex.entry_SYSCALL_64_fastpath
      4.08 ±  3%     -21.1%       3.22 ±  5%  perf-profile.cycles.get_page_from_freelist.__alloc_pages_nodemask.alloc_pages_current.pipe_write.__vfs_write
      3.29 ± 59%    +274.5%      12.30 ±  8%  perf-profile.cycles.hrtimer_interrupt.local_apic_timer_interrupt.smp_apic_timer_interrupt.apic_timer_interrupt
      0.33 ± 32%    +514.2%       2.06 ±  3%  perf-profile.cycles.intel_idle.cpuidle_enter_state.cpuidle_enter.call_cpuidle.cpu_startup_entry
      0.67 ± 99%    +290.4%       2.63 ± 17%  perf-profile.cycles.irq_exit.smp_apic_timer_interrupt.apic_timer_interrupt
      1.07 ±  3%     -49.4%       0.54 ± 27%  perf-profile.cycles.is_ftrace_trampoline.__kernel_text_address.print_context_stack.dump_trace.save_stack_trace_tsk
      1.74 ± 12%     -32.8%       1.17 ± 10%  perf-profile.cycles.is_module_text_address.__kernel_text_address.print_context_stack.dump_trace.save_stack_trace_tsk
      0.76 ± 22%     -39.6%       0.46 ± 17%  perf-profile.cycles.list_del.__rmqueue.get_page_from_freelist.__alloc_pages_nodemask.alloc_pages_current
      3.41 ± 59%    +268.5%      12.55 ±  8%  perf-profile.cycles.local_apic_timer_interrupt.smp_apic_timer_interrupt.apic_timer_interrupt
      0.93 ± 29%    +369.4%       4.37 ±  6%  perf-profile.cycles.mutex_lock.pipe_read.__vfs_read.vfs_read.sys_read
      0.44 ± 39%    +273.3%       1.64 ± 16%  perf-profile.cycles.mutex_lock.pipe_wait.pipe_write.__vfs_write.vfs_write
      0.88 ± 31%    +394.0%       4.32 ±  5%  perf-profile.cycles.mutex_optimistic_spin.__mutex_lock_slowpath.mutex_lock.pipe_read.__vfs_read
      0.41 ± 38%    +283.7%       1.59 ± 17%  perf-profile.cycles.mutex_optimistic_spin.__mutex_lock_slowpath.mutex_lock.pipe_wait.pipe_write
      0.85 ± 33%    +396.2%       4.24 ±  5%  perf-profile.cycles.mutex_spin_on_owner.isra.4.mutex_optimistic_spin.__mutex_lock_slowpath.mutex_lock.pipe_read
      0.41 ± 38%    +276.5%       1.56 ± 18%  perf-profile.cycles.mutex_spin_on_owner.isra.4.mutex_optimistic_spin.__mutex_lock_slowpath.mutex_lock.pipe_wait
      0.23 ± 98%    +298.9%       0.92 ± 12%  perf-profile.cycles.perf_event_task_tick.scheduler_tick.update_process_times.tick_sched_handle.tick_sched_timer
      1.55 ± 11%     +60.2%       2.49 ± 12%  perf-profile.cycles.pipe_wait.pipe_write.__vfs_write.vfs_write.sys_write
     26.79 ±  3%     -14.0%      23.05 ±  3%  perf-profile.cycles.pipe_write.__vfs_write.vfs_write.sys_write.entry_SYSCALL_64_fastpath
      0.67 ± 97%     -90.7%       0.06 ± 54%  perf-profile.cycles.poll_idle.cpuidle_enter_state.cpuidle_enter.call_cpuidle.cpu_startup_entry
      7.72 ± 10%     -36.3%       4.92 ±  5%  perf-profile.cycles.print_context_stack.dump_trace.save_stack_trace_tsk.__account_scheduler_latency.enqueue_entity
      0.22 ± 27%    +448.9%       1.23 ± 18%  perf-profile.cycles.rcu_check_callbacks.update_process_times.tick_sched_handle.tick_sched_timer.__hrtimer_run_queues
      1.72 ± 10%     -82.1%       0.31 ± 16%  perf-profile.cycles.reschedule_interrupt
      0.36 ± 82%    +222.2%       1.16 ± 22%  perf-profile.cycles.run_timer_softirq.__do_softirq.irq_exit.smp_apic_timer_interrupt.apic_timer_interrupt
      8.82 ± 10%     -36.9%       5.57 ±  3%  perf-profile.cycles.save_stack_trace_tsk.__account_scheduler_latency.enqueue_entity.enqueue_task_fair.activate_task
      0.23 ± 40%    +625.8%       1.69 ±  4%  perf-profile.cycles.sched_ttwu_pending.cpu_startup_entry.start_secondary
      1.37 ± 11%     -81.2%       0.26 ± 18%  perf-profile.cycles.sched_ttwu_pending.scheduler_ipi.smp_reschedule_interrupt.reschedule_interrupt
      1.09 ± 21%     -63.3%       0.40 ± 29%  perf-profile.cycles.schedule.exit_to_usermode_loop.syscall_return_slowpath.entry_SYSCALL_64_fastpath
      3.00 ±  8%     -14.5%       2.57 ±  4%  perf-profile.cycles.schedule.futex_wait_queue_me.futex_wait.do_futex.sys_futex
      0.96 ± 10%     -24.1%       0.72 ± 13%  perf-profile.cycles.schedule.pipe_wait.pipe_write.__vfs_write.vfs_write
      0.11 ± 44%    +675.6%       0.87 ± 19%  perf-profile.cycles.schedule.schedule_preempt_disabled.cpu_startup_entry.start_secondary
      0.11 ± 44%    +675.6%       0.87 ± 19%  perf-profile.cycles.schedule_preempt_disabled.cpu_startup_entry.start_secondary
      1.47 ± 14%     -82.0%       0.27 ± 21%  perf-profile.cycles.scheduler_ipi.smp_reschedule_interrupt.reschedule_interrupt
      1.65 ± 61%    +266.8%       6.04 ±  9%  perf-profile.cycles.scheduler_tick.update_process_times.tick_sched_handle.tick_sched_timer.__hrtimer_run_queues
      1.38 ± 17%     -66.1%       0.47 ± 13%  perf-profile.cycles.select_task_rq_fair.try_to_wake_up.wake_up_q.futex_requeue.do_futex
      4.41 ± 62%    +258.0%      15.80 ±  9%  perf-profile.cycles.smp_apic_timer_interrupt.apic_timer_interrupt
      1.50 ± 14%     -80.8%       0.29 ± 17%  perf-profile.cycles.smp_reschedule_interrupt.reschedule_interrupt
      1.81 ± 54%    +336.0%       7.89 ±  4%  perf-profile.cycles.start_secondary
     18.17 ±  7%     -42.4%      10.46 ±  2%  perf-profile.cycles.sys_futex.entry_SYSCALL_64_fastpath
     35.99 ±  2%      -9.8%      32.45 ±  3%  perf-profile.cycles.sys_read.entry_SYSCALL_64_fastpath
     27.21 ±  3%     -14.1%      23.37 ±  3%  perf-profile.cycles.sys_write.entry_SYSCALL_64_fastpath
      1.21 ± 17%     -57.6%       0.52 ± 32%  perf-profile.cycles.syscall_return_slowpath.entry_SYSCALL_64_fastpath
      0.85 ± 52%    +261.2%       3.07 ±  7%  perf-profile.cycles.task_tick_fair.scheduler_tick.update_process_times.tick_sched_handle.tick_sched_timer
      0.32 ±134%    +244.2%       1.11 ± 21%  perf-profile.cycles.tick_program_event.hrtimer_interrupt.local_apic_timer_interrupt.smp_apic_timer_interrupt.apic_timer_interrupt
      2.31 ± 50%    +274.6%       8.66 ±  6%  perf-profile.cycles.tick_sched_handle.isra.19.tick_sched_timer.__hrtimer_run_queues.hrtimer_interrupt.local_apic_timer_interrupt
      2.44 ± 53%    +279.1%       9.23 ±  6%  perf-profile.cycles.tick_sched_timer.__hrtimer_run_queues.hrtimer_interrupt.local_apic_timer_interrupt.smp_apic_timer_interrupt
      3.17 ± 12%     -33.4%       2.11 ±  7%  perf-profile.cycles.try_to_wake_up.default_wake_function.autoremove_wake_function.__wake_up_common.__wake_up_sync_key
      7.72 ±  8%     -61.4%       2.98 ±  6%  perf-profile.cycles.try_to_wake_up.wake_up_q.futex_requeue.do_futex.sys_futex
      4.04 ±  8%     -27.5%       2.93 ± 11%  perf-profile.cycles.try_to_wake_up.wake_up_q.futex_wake.do_futex.sys_futex
      0.19 ± 51%    +728.6%       1.59 ±  7%  perf-profile.cycles.ttwu_do_activate.constprop.89.sched_ttwu_pending.cpu_startup_entry.start_secondary
      1.72 ± 11%     -61.5%       0.66 ± 15%  perf-profile.cycles.ttwu_do_activate.constprop.89.sched_ttwu_pending.scheduler_ipi.smp_reschedule_interrupt.reschedule_interrupt
      2.43 ± 13%     -31.6%       1.66 ±  7%  perf-profile.cycles.ttwu_do_activate.constprop.89.try_to_wake_up.default_wake_function.autoremove_wake_function.__wake_up_common
      5.57 ± 16%     -60.2%       2.21 ± 11%  perf-profile.cycles.ttwu_do_activate.constprop.89.try_to_wake_up.wake_up_q.futex_requeue.do_futex
      3.43 ±  8%     -36.8%       2.17 ± 12%  perf-profile.cycles.ttwu_do_activate.constprop.89.try_to_wake_up.wake_up_q.futex_wake.do_futex
      0.31 ± 83%    +243.9%       1.06 ±  6%  perf-profile.cycles.update_cfs_shares.task_tick_fair.scheduler_tick.update_process_times.tick_sched_handle
      2.27 ± 52%    +274.1%       8.49 ±  5%  perf-profile.cycles.update_process_times.tick_sched_handle.tick_sched_timer.__hrtimer_run_queues.hrtimer_interrupt
     35.85 ±  2%      -9.7%      32.36 ±  3%  perf-profile.cycles.vfs_read.sys_read.entry_SYSCALL_64_fastpath
     27.07 ±  2%     -14.1%      23.25 ±  3%  perf-profile.cycles.vfs_write.sys_write.entry_SYSCALL_64_fastpath
      7.87 ±  7%     -60.7%       3.09 ±  5%  perf-profile.cycles.wake_up_q.futex_requeue.do_futex.sys_futex.entry_SYSCALL_64_fastpath
      4.09 ±  8%     -27.0%       2.98 ± 10%  perf-profile.cycles.wake_up_q.futex_wake.do_futex.sys_futex.entry_SYSCALL_64_fastpath



lkp-snb01: Sandy Bridge-EP
Memory: 32G


                                    pigz.throughput

  3.58e+08 ++---------------------------------------------------------------+
           *.         .*.*..*.         .*.*..      .*.              .*.     |
  3.56e+08 ++*..*.*..*        *..*.*.*.      *.*..*   *..*.*..*.*.*.   *..*.*
           |                                                                |
           |                                                                |
  3.54e+08 ++                                                               |
           |                                                                |
  3.52e+08 ++                                                               |
           |                                                                |
   3.5e+08 ++                                                               |
           |                                                                |
           |                                                                |
  3.48e+08 ++          O    O O  O   O                                      |
           O O  O    O   O                O  O    O                         |
  3.46e+08 ++-----O----------------O----O------O----------------------------+


                                pigz.time.user_time

  9450 ++-------------------------------*-----------------------------------+
  9400 *+.*.*..*.*..*.*..*.*..*..*.  .*    *.*..*.*..*..*.*..*.*..*.*..*.*..*
       |                           *.                                       |
  9350 ++                                                                   |
  9300 ++                                                                   |
       |                                                                    |
  9250 ++                                                                   |
  9200 ++                                                                   |
  9150 ++                                                                   |
       |                                                                    |
  9100 ++                                                                   |
  9050 ++                                                                   |
       |                      O    O                                        |
  9000 O+ O O       O O  O O            O                                   |
  8950 ++------O-O---------------O----O----O-O--O---------------------------+


                               pigz.time.system_time

  110 ++--------------------------------------------------------------------+
      *..         .*..      .*..         .*..    .*..         .*.. .*..    .*
  108 ++ *.*..*.*.    *.*..*    *..*.*..*    *.*.    *.*..*.*.    *    *.*. |
  106 ++                                                                    |
      |                                                                     |
  104 ++                                                                    |
  102 ++                                                                    |
      |                                                                     |
  100 ++                                                                    |
   98 ++                                                                    |
      |                                                                     |
   96 ++                                                                    |
   94 O+   O  O O     O         O    O    O  O O                            |
      |  O         O    O  O O     O    O                                   |
   92 ++--------------------------------------------------------------------+


                       pigz.time.percent_of_cpu_this_job_got

  3180 *+-----------*-*--*--------------*----*----*------------*--*-*----*--+
       |  *.*..*.*.        *..*..*.*..*    *    *    *..*.*..*         *    *
  3160 ++                                                                   |
  3140 ++                                                                   |
       |                                                                    |
  3120 ++                                                                   |
  3100 ++                                                                   |
       |                                                                    |
  3080 ++                                                                   |
  3060 ++                                                                   |
       |                                                                    |
  3040 ++                     O    O                                        |
  3020 ++ O         O O  O O            O                                   |
       O    O  O O               O    O    O O  O                           |
  3000 ++-------------------------------------------------------------------+


                         pigz.time.voluntary_context_switches

  4.6e+06 ++----------------------------------------------------------------+
          *.              .*..           .*.  .*.  .*.         .*.       .*.|
  4.4e+06 ++*..*.*..*.*..*    *.*.*..*.*.   *.   *.   *.*..*.*.   *..*.*.   *
          |                                                                 |
  4.2e+06 ++                                                                |
          |                                                                 |
    4e+06 ++                                                                |
          |                                                                 |
  3.8e+06 ++                                                                |
          |                                                                 |
  3.6e+06 ++                                                                |
          |                                                                 |
  3.4e+06 ++                                                                |
          O O  O O  O O  O O  O O O  O O  O O  O O                          |
  3.2e+06 ++----------------------------------------------------------------+


                        pigz.time.involuntary_context_switches

  3.5e+06 ++----------------------------------------------------------------+
          |                              .*.                                |
          *.*..*.*..*.*..*.*..*.*.*..*.*.   *..*.*..*.*.*..*.*..*.*..*.*..*.*
    3e+06 ++                                                                |
          |                                                                 |
          |                                                                 |
  2.5e+06 ++                                                                |
          |                                                                 |
    2e+06 ++                                                                |
          |                                                                 |
          |                                                                 |
  1.5e+06 ++                                                                |
          |                O    O    O                                      |
          O O  O O  O O  O    O   O    O  O O  O O                          |
    1e+06 ++----------------------------------------------------------------+


	[*] bisect-good sample
	[O] bisect-bad  sample

To reproduce:

        git clone git://git.kernel.org/pub/scm/linux/kernel/git/wfg/lkp-tests.git
        cd lkp-tests
        bin/lkp install job.yaml  # job file is attached in this email
        bin/lkp run     job.yaml


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,
Xiaolong Ye

View attachment "job.yaml" of type "text/plain" (3321 bytes)

View attachment "reproduce" of type "text/plain" (2999 bytes)

Powered by blists - more mailing lists

Powered by Openwall GNU/*/Linux Powered by OpenVZ