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: <202311161149.4d39cc37-oliver.sang@intel.com>
Date:   Thu, 16 Nov 2023 12:51:41 +0800
From:   kernel test robot <oliver.sang@...el.com>
To:     John Johansen <john.johansen@...onical.com>
CC:     <oe-lkp@...ts.linux.dev>, <lkp@...el.com>,
        <linux-kernel@...r.kernel.org>,
        Georgia Garcia <georgia.garcia@...onical.com>,
        <apparmor@...ts.ubuntu.com>, <ying.huang@...el.com>,
        <feng.tang@...el.com>, <fengwei.yin@...el.com>,
        <oliver.sang@...el.com>
Subject: [linus:master] [apparmor]  90c436a64a:  stress-ng.kill.ops_per_sec
 -8.0% regression


hi, John Johansen,

we reported
"[linux-next:master] [apparmor]  90c436a64a: stress-ng.kcmp.ops_per_sec 383.0% improvement"
in
https://lore.kernel.org/all/202310311037.173ebf2b-oliver.sang@intel.com/
when this commit is in linux-next/master.

now we noticed this commit is in mainline, however, we catpured a small
regression by another stress-ng test. FYI.


Hello,

kernel test robot noticed a -8.0% regression of stress-ng.kill.ops_per_sec on:


commit: 90c436a64a6e20482a9a613c47eb4af2e8a5328e ("apparmor: pass cred through to audit info.")
https://git.kernel.org/cgit/linux/kernel/git/torvalds/linux.git master

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

	nr_threads: 100%
	disk: 1HDD
	testtime: 60s
	class: interrupt
	test: kill
	cpufreq_governor: performance



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


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


The kernel config and materials to reproduce are available at:
https://download.01.org/0day-ci/archive/20231116/202311161149.4d39cc37-oliver.sang@intel.com

=========================================================================================
class/compiler/cpufreq_governor/disk/kconfig/nr_threads/rootfs/tbox_group/test/testcase/testtime:
  interrupt/gcc-12/performance/1HDD/x86_64-rhel-8.3/100%/debian-11.1-x86_64-20220510.cgz/lkp-icl-2sp8/kill/stress-ng/60s

commit: 
  d20f5a1a6e ("apparmor: rename audit_data->label to audit_data->subj_label")
  90c436a64a ("apparmor: pass cred through to audit info.")

d20f5a1a6e792d22 90c436a64a6e20482a9a613c47e 
---------------- --------------------------- 
         %stddev     %change         %stddev
             \          |                \  
    112.01        +21438.4%      24124 ±200%  meminfo.Active(file)
     62.80            +4.5%      65.65        turbostat.RAMWatt
     15.04 ±100%   +4879.0%     748.88 ±114%  vmstat.io.bo
     82957 ±  2%      -7.4%      76795 ±  3%  vmstat.system.cs
     28.00        +21403.0%       6021 ±200%  proc-vmstat.nr_active_file
     28.00        +21403.0%       6021 ±200%  proc-vmstat.nr_zone_active_file
    129482            -4.4%     123826        proc-vmstat.pgactivate
      1032 ±100%   +4641.1%      48928 ±113%  proc-vmstat.pgpgout
     16.90 ± 14%     +25.5%      21.21 ± 20%  sched_debug.cfs_rq:/.removed.runnable_avg.avg
     16.89 ± 14%     +25.5%      21.21 ± 20%  sched_debug.cfs_rq:/.removed.util_avg.avg
    247.17 ±  6%     +14.0%     281.82 ±  8%  sched_debug.cfs_rq:/.util_est_enqueued.avg
     34402 ±  2%     -21.9%      26867 ±  6%  sched_debug.cpu.nr_switches.min
    181.33 ±  7%    +129.4%     416.00 ±  2%  perf-c2c.DRAM.local
      1766 ±  4%    +140.5%       4249 ±  4%  perf-c2c.DRAM.remote
      3189 ±  5%    +119.0%       6984        perf-c2c.HITM.local
    538.00 ± 10%    +273.7%       2010 ±  7%  perf-c2c.HITM.remote
      3727 ±  6%    +141.3%       8995 ±  2%  perf-c2c.HITM.total
    738.58            -8.1%     678.88        stress-ng.kill.kill_calls_per_sec
    912564            -8.0%     839201        stress-ng.kill.ops
     15208            -8.0%      13985        stress-ng.kill.ops_per_sec
   2741058            -7.8%    2527111        stress-ng.time.involuntary_context_switches
   2721296            -7.9%    2505879        stress-ng.time.voluntary_context_switches
      0.58 ±  7%     +35.9%       0.79 ±  6%  perf-sched.sch_delay.avg.ms.smpboot_thread_fn.kthread.ret_from_fork
     21.33 ± 18%     -50.0%      10.67 ± 32%  perf-sched.wait_and_delay.count.__cond_resched.generic_perform_write.generic_file_write_iter.vfs_write.ksys_write
      0.01 ± 26%     +54.5%       0.01 ± 25%  perf-sched.wait_time.avg.ms.__cond_resched.__kmem_cache_alloc_node.__kmalloc_node.proc_sys_call_handler.vfs_read
      1.68 ±214%     -99.5%       0.01 ± 64%  perf-sched.wait_time.avg.ms.exit_to_user_mode_loop.exit_to_user_mode_prepare.irqentry_exit_to_user_mode.asm_sysvec_apic_timer_interrupt
      0.01 ± 36%     +66.0%       0.01 ± 28%  perf-sched.wait_time.max.ms.__cond_resched.__kmem_cache_alloc_node.__kmalloc_node.proc_sys_call_handler.vfs_read
      8.10 ± 43%     -29.7%       5.69 ± 12%  perf-sched.wait_time.max.ms.__cond_resched.dput.__fput.task_work_run.exit_to_user_mode_loop
      1.64 ±  2%    +152.0%       4.13 ±  3%  perf-stat.i.MPKI
     11.98            +6.8       18.75 ±  2%  perf-stat.i.cache-miss-rate%
  13072329 ±  2%    +145.9%   32141491 ±  4%  perf-stat.i.cache-misses
 1.082e+08 ±  3%     +53.1%  1.657e+08 ±  4%  perf-stat.i.cache-references
     86654 ±  2%      -8.4%      79368 ±  4%  perf-stat.i.context-switches
     15915           -59.2%       6487        perf-stat.i.cycles-between-cache-misses
      0.01 ± 15%      -0.0        0.01 ±  5%  perf-stat.i.dTLB-load-miss-rate%
    338311 ± 15%     -21.7%     264729 ±  8%  perf-stat.i.dTLB-load-misses
    149.20 ±  5%    +115.1%     320.97 ±  2%  perf-stat.i.metric.K/sec
     86.96            +3.9       90.83        perf-stat.i.node-load-miss-rate%
   2659602 ±  3%    +205.8%    8133328 ±  4%  perf-stat.i.node-load-misses
    398586 ±  5%     +69.2%     674468 ±  5%  perf-stat.i.node-loads
   4272013 ±  2%    +102.4%    8646615 ±  5%  perf-stat.i.node-store-misses
    444659 ±  3%    +117.1%     965253 ±  5%  perf-stat.i.node-stores
      1.63 ±  2%    +149.4%       4.06 ±  2%  perf-stat.overall.MPKI
     12.08            +7.3       19.41        perf-stat.overall.cache-miss-rate%
     16313           -59.8%       6554        perf-stat.overall.cycles-between-cache-misses
      0.02 ± 15%      -0.0        0.01 ±  7%  perf-stat.overall.dTLB-load-miss-rate%
     86.84            +5.5       92.33        perf-stat.overall.node-load-miss-rate%
  12940552 ±  2%    +146.4%   31886577 ±  3%  perf-stat.ps.cache-misses
 1.072e+08 ±  2%     +53.3%  1.643e+08 ±  3%  perf-stat.ps.cache-references
     85805 ±  2%      -8.3%      78664 ±  3%  perf-stat.ps.context-switches
    341154 ± 16%     -21.3%     268598 ±  9%  perf-stat.ps.dTLB-load-misses
   2632824 ±  2%    +206.4%    8067907 ±  4%  perf-stat.ps.node-load-misses
    398939 ±  5%     +68.2%     670849 ±  5%  perf-stat.ps.node-loads
   4233610 ±  2%    +102.6%    8578379 ±  4%  perf-stat.ps.node-store-misses
    434918 ±  3%    +119.3%     953796 ±  4%  perf-stat.ps.node-stores
     38.66           -38.7        0.00        perf-profile.calltrace.cycles-pp.aa_get_task_label.apparmor_task_kill.security_task_kill.kill_something_info.__x64_sys_kill
      9.04 ±  6%      -2.4        6.67        perf-profile.calltrace.cycles-pp.aa_may_signal.apparmor_task_kill.security_task_kill.kill_something_info.__x64_sys_kill
      0.74            -0.0        0.68        perf-profile.calltrace.cycles-pp.profile_signal_perm.aa_may_signal.apparmor_task_kill.security_task_kill.kill_something_info
      0.76            -0.0        0.72        perf-profile.calltrace.cycles-pp.kill_pid_info.kill_something_info.__x64_sys_kill.do_syscall_64.entry_SYSCALL_64_after_hwframe
     98.37            +0.1       98.46        perf-profile.calltrace.cycles-pp.do_syscall_64.entry_SYSCALL_64_after_hwframe.kill
     98.38            +0.1       98.47        perf-profile.calltrace.cycles-pp.entry_SYSCALL_64_after_hwframe.kill
     98.40            +0.1       98.49        perf-profile.calltrace.cycles-pp.kill
     98.24            +0.1       98.36        perf-profile.calltrace.cycles-pp.__x64_sys_kill.do_syscall_64.entry_SYSCALL_64_after_hwframe.kill
     98.20            +0.1       98.32        perf-profile.calltrace.cycles-pp.kill_something_info.__x64_sys_kill.do_syscall_64.entry_SYSCALL_64_after_hwframe.kill
      0.00            +0.7        0.68        perf-profile.calltrace.cycles-pp.check_kill_permission.kill_something_info.__x64_sys_kill.do_syscall_64.entry_SYSCALL_64_after_hwframe
      0.00            +0.7        0.74        perf-profile.calltrace.cycles-pp.get_task_cred.apparmor_task_kill.security_task_kill.kill_something_info.__x64_sys_kill
     38.96           -39.0        0.00        perf-profile.children.cycles-pp.aa_get_task_label
      9.14 ±  6%      -2.4        6.74        perf-profile.children.cycles-pp.aa_may_signal
      0.47 ±  4%      -0.1        0.41 ±  2%  perf-profile.children.cycles-pp.__x64_sys_openat
      0.47 ±  3%      -0.1        0.41 ±  2%  perf-profile.children.cycles-pp.do_sys_openat2
      0.46 ±  3%      -0.1        0.41        perf-profile.children.cycles-pp.open64
      0.76            -0.1        0.71        perf-profile.children.cycles-pp.profile_signal_perm
      0.45 ±  4%      -0.1        0.40        perf-profile.children.cycles-pp.do_filp_open
      0.45 ±  4%      -0.1        0.40 ±  2%  perf-profile.children.cycles-pp.path_openat
      0.20 ±  2%      -0.0        0.15 ±  3%  perf-profile.children.cycles-pp.__schedule
      0.20 ±  3%      -0.0        0.16 ±  3%  perf-profile.children.cycles-pp.schedule
      0.32 ±  6%      -0.0        0.28 ±  4%  perf-profile.children.cycles-pp._raw_spin_lock
      0.76            -0.0        0.72        perf-profile.children.cycles-pp.kill_pid_info
      0.07 ±  7%      -0.0        0.03 ± 70%  perf-profile.children.cycles-pp.complete_signal
      0.10 ±  5%      -0.0        0.07        perf-profile.children.cycles-pp.do_send_sig_info
      0.09            -0.0        0.06 ±  7%  perf-profile.children.cycles-pp.__send_signal_locked
      0.19 ±  3%      -0.0        0.17 ±  3%  perf-profile.children.cycles-pp.apparmor_capable
      0.07 ±  5%      -0.0        0.05 ±  7%  perf-profile.children.cycles-pp.switch_mm_irqs_off
      0.07 ±  5%      -0.0        0.05 ±  7%  perf-profile.children.cycles-pp.try_to_wake_up
      0.19 ±  3%      -0.0        0.17 ±  2%  perf-profile.children.cycles-pp.ns_capable
      0.19 ±  3%      -0.0        0.17 ±  2%  perf-profile.children.cycles-pp.security_capable
      0.09            -0.0        0.07 ±  5%  perf-profile.children.cycles-pp.__close
      0.07 ±  7%      -0.0        0.05        perf-profile.children.cycles-pp.__x64_sys_pause
      0.07            -0.0        0.06 ±  8%  perf-profile.children.cycles-pp.task_work_run
      0.09 ±  5%      -0.0        0.07 ±  6%  perf-profile.children.cycles-pp._raw_spin_lock_irq
      0.09 ±  8%      -0.0        0.07 ±  6%  perf-profile.children.cycles-pp.lookup_fast
      0.07 ±  5%      -0.0        0.06        perf-profile.children.cycles-pp.proc_sys_call_handler
     98.24            +0.1       98.36        perf-profile.children.cycles-pp.__x64_sys_kill
     98.23            +0.1       98.35        perf-profile.children.cycles-pp.kill_something_info
      0.05            +0.1        0.17 ±  2%  perf-profile.children.cycles-pp.audit_signal_info
      0.07 ±  7%      +0.2        0.27 ±  2%  perf-profile.children.cycles-pp.audit_signal_info_syscall
      0.42            +0.3        0.70        perf-profile.children.cycles-pp.check_kill_permission
      0.00            +0.8        0.76        perf-profile.children.cycles-pp.get_task_cred
     38.83           -38.8        0.00        perf-profile.self.cycles-pp.aa_get_task_label
      8.36 ±  6%      -2.3        6.03        perf-profile.self.cycles-pp.aa_may_signal
      0.74            -0.1        0.68        perf-profile.self.cycles-pp.profile_signal_perm
      0.10 ±  4%      -0.0        0.08 ±  4%  perf-profile.self.cycles-pp.security_task_kill
      0.09 ±  4%      -0.0        0.07 ±  5%  perf-profile.self.cycles-pp._raw_spin_lock
      0.07 ±  5%      -0.0        0.05        perf-profile.self.cycles-pp.switch_mm_irqs_off
      0.12 ±  4%      -0.0        0.11 ±  5%  perf-profile.self.cycles-pp.__kill_pgrp_info
      0.09 ±  5%      -0.0        0.08        perf-profile.self.cycles-pp.kill_something_info
      0.11 ±  4%      -0.0        0.10        perf-profile.self.cycles-pp.check_kill_permission
      0.00            +0.2        0.16 ±  2%  perf-profile.self.cycles-pp.audit_signal_info
      0.06 ±  8%      +0.2        0.26 ±  2%  perf-profile.self.cycles-pp.audit_signal_info_syscall
      0.00            +0.8        0.75        perf-profile.self.cycles-pp.get_task_cred
     46.37           +40.2       86.58        perf-profile.self.cycles-pp.apparmor_task_kill



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


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

Powered by blists - more mailing lists

Powered by Openwall GNU/*/Linux Powered by OpenVZ